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

SSH proxy failed backup until disconnect/timeout #3114

Open
romainsi opened this issue Apr 3, 2024 · 4 comments
Open

SSH proxy failed backup until disconnect/timeout #3114

romainsi opened this issue Apr 3, 2024 · 4 comments

Comments

@romainsi
Copy link

romainsi commented Apr 3, 2024 •
edited

Hello,

First thanks for you job.
I've a problem with backing up a Mikrotik router via ssh proxy, ok with direct ssh access.

I use docker with last version of oxidized (0.29.1-207-gea40b51), I generated an rsa key to the oxidized user, and added the mapping for the ssh proxy which works fine (I see ssh session on first equipement for proxy and on the final router) but the connection (forward then ssh on the router) never ends (so no backup even when I change the router configuration).
But when I force the closure of this session (the proxy part or the ssh client part), or when I reboot the router, the backup is carried out correctly (with data).

On the web interface of the routerOS, when I close client ssh, I see the backup performed correctly, but average run time is necessarily very long, since it depends on the time I took to close the connection (1195.83s for example in my last test).

From what I understand, the session doesn't close properly at the end of the information retrieval command, so the backup never takes place.

Thanks for your help.
@robertcheramy
Copy link
Collaborator

As I neither use Mikrotik nor ssh proxy, it's quite difficult form me to help.

From your description, if the ssh session never ends, the quit is not enought to close the session:

oxidized/lib/oxidized/model/routeros.rb

Line 61 in c79826a

pre_logout 'quit'

How do you logout when using a proxy? Do you have to enter something else as just quit ?

@romainsi
Copy link
Author

romainsi commented Apr 15, 2024 •
edited

Hi,

The pre_logout command seems to be good for exit classic and proxy connections.

When I log on to the docker and I testing this :
docker exec -it <oxidized-container-name> bash
su oxidized
ssh -J <IP-routerOS-forProxy> oxidized@<IP-Router-forBackup>
[oxidized@<IP-Router-forBackup>] > quit

The simple 'quit' disconnects client and proxy and sends me back to the oxidized bash terminal.

In parallel, I've tried adding a 2sd quit to the model, but this doesn't solve the problem :
cfg :telnet, :ssh do
pre_logout 'quit'
pre_logout 'quit'

@romainsi
Copy link
Author

I've added the logs if that helps.

Ssh_proxy is '192.168.99.35' and routerOS is '10.20.0.10' (APSiegeLABviaProxy).

*** Running /etc/my_init.d/00_regen_ssh_host_keys.sh...
*** Running /etc/my_init.d/10_syslog-ng.init...
Apr 15 14:38:08 a829ef4147c2 syslog-ng[14]: syslog-ng starting up; version='3.35.1'
*** Booting runit daemon...
*** Runit started as PID 24
Apr 15 14:38:09 a829ef4147c2 cron[33]: (CRON) INFO (pidfile fd = 3)
Apr 15 14:38:09 a829ef4147c2 cron[33]: (CRON) INFO (Running @reboot jobs)
I, [2024-04-15T14:38:09.860858 #32]  INFO -- : Oxidized starting, running as pid 32
I, [2024-04-15T14:38:09.861759 #32]  INFO -- : lib/oxidized/nodes.rb: Loading nodes
I, [2024-04-15T14:38:09.929506 #32]  INFO -- : lib/oxidized/nodes.rb: Loaded 1 nodes
D, [2024-04-15T14:38:10.057331 #32] DEBUG -- net.ssh.transport.session[5c8]: establishing connection to 10.20.0.10:22 through proxy
Puma starting in single mode...
* Version 3.11.4 (ruby 3.0.2-p107), codename: Love Song
* Min threads: 0, max threads: 16
* Environment: development
* Listening on tcp://0.0.0.0:8888
Use Ctrl-C to stop
D, [2024-04-15T14:38:10.255107 #32] DEBUG -- net.ssh.transport.session[5c8]: connection established
I, [2024-04-15T14:38:10.255334 #32]  INFO -- net.ssh.transport.server_version[5dc]: negotiating protocol version
D, [2024-04-15T14:38:10.255376 #32] DEBUG -- net.ssh.transport.server_version[5dc]: local is `SSH-2.0-Ruby/Net::SSH_7.2.3 x86_64-linux-gnu'
D, [2024-04-15T14:38:10.255485 #32] DEBUG -- net.ssh.transport.server_version[5dc]: remote is `SSH-2.0-ROSSSH'
I, [2024-04-15T14:38:10.256882 #32]  INFO -- net.ssh.transport.algorithms[5f0]: sending KEXINIT
D, [2024-04-15T14:38:10.257314 #32] DEBUG -- io[604]: queueing packet nr 0 type 20 len 1436
D, [2024-04-15T14:38:10.257391 #32] DEBUG -- io[604]: sent 1440 bytes
D, [2024-04-15T14:38:10.258802 #32] DEBUG -- io[604]: read 304 bytes
D, [2024-04-15T14:38:10.258996 #32] DEBUG -- io[604]: received packet nr 0 type 20 len 300
I, [2024-04-15T14:38:10.259068 #32]  INFO -- net.ssh.transport.algorithms[5f0]: got KEXINIT from server
I, [2024-04-15T14:38:10.259150 #32]  INFO -- net.ssh.transport.algorithms[5f0]: negotiating algorithms
D, [2024-04-15T14:38:10.259288 #32] DEBUG -- net.ssh.transport.algorithms[5f0]: negotiated:
* kex: diffie-hellman-group-exchange-sha256
* host_key: rsa-sha2-256
* encryption_server: aes256-ctr
* encryption_client: aes256-ctr
* hmac_client: hmac-sha2-512
* hmac_server: hmac-sha2-512
* compression_client: none
* compression_server: none
* language_client: 
* language_server: 
D, [2024-04-15T14:38:10.259306 #32] DEBUG -- net.ssh.transport.algorithms[5f0]: exchanging keys
D, [2024-04-15T14:38:10.259509 #32] DEBUG -- io[604]: queueing packet nr 1 type 34 len 20
D, [2024-04-15T14:38:10.259545 #32] DEBUG -- io[604]: sent 24 bytes
D, [2024-04-15T14:38:10.261860 #32] DEBUG -- io[604]: read 280 bytes
D, [2024-04-15T14:38:10.261996 #32] DEBUG -- io[604]: received packet nr 1 type 31 len 276
D, [2024-04-15T14:38:10.266532 #32] DEBUG -- io[604]: queueing packet nr 2 type 32 len 268
D, [2024-04-15T14:38:10.266581 #32] DEBUG -- io[604]: sent 272 bytes
D, [2024-04-15T14:38:10.537377 #32] DEBUG -- io[604]: read 856 bytes
D, [2024-04-15T14:38:10.537572 #32] DEBUG -- io[604]: received packet nr 2 type 33 len 836
D, [2024-04-15T14:38:10.544098 #32] DEBUG -- io[604]: queueing packet nr 3 type 21 len 20
D, [2024-04-15T14:38:10.544147 #32] DEBUG -- io[604]: sent 24 bytes
D, [2024-04-15T14:38:10.544193 #32] DEBUG -- io[604]: received packet nr 3 type 21 len 12
D, [2024-04-15T14:38:10.544543 #32] DEBUG -- net.ssh.authentication.session[618]: beginning authentication of `oxidized'
D, [2024-04-15T14:38:10.544691 #32] DEBUG -- io[604]: queueing packet nr 4 type 5 len 28
D, [2024-04-15T14:38:10.544713 #32] DEBUG -- io[604]: sent 96 bytes
D, [2024-04-15T14:38:10.547486 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:10.547686 #32] DEBUG -- io[604]: received packet nr 4 type 6 len 28
D, [2024-04-15T14:38:10.547805 #32] DEBUG -- net.ssh.authentication.session[618]: trying none
D, [2024-04-15T14:38:10.547956 #32] DEBUG -- io[604]: queueing packet nr 5 type 50 len 44
D, [2024-04-15T14:38:10.547980 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:10.554556 #32] DEBUG -- io[604]: read 112 bytes
D, [2024-04-15T14:38:10.554822 #32] DEBUG -- io[604]: received packet nr 5 type 51 len 44
D, [2024-04-15T14:38:10.554920 #32] DEBUG -- net.ssh.authentication.session[618]: allowed methods: publickey,password
D, [2024-04-15T14:38:10.554996 #32] DEBUG -- net.ssh.authentication.methods.none[62c]: none failed
D, [2024-04-15T14:38:10.555054 #32] DEBUG -- net.ssh.authentication.session[618]: trying publickey
D, [2024-04-15T14:38:10.555828 #32] DEBUG -- net.ssh.authentication.agent[640]: connecting to ssh-agent
E, [2024-04-15T14:38:10.555906 #32] ERROR -- net.ssh.authentication.agent[640]: could not connect to ssh-agent: Agent not configured
D, [2024-04-15T14:38:10.556066 #32] DEBUG -- net.ssh.authentication.methods.publickey[668]: trying publickey (51:b8:75:ba:0f:d8:4a:d5:0c:77:b1:27:2e:76:cb:a4) alg rsa-sha2-256
D, [2024-04-15T14:38:10.556262 #32] DEBUG -- io[604]: queueing packet nr 6 type 50 len 364
D, [2024-04-15T14:38:10.556290 #32] DEBUG -- io[604]: sent 432 bytes
D, [2024-04-15T14:38:10.558580 #32] DEBUG -- io[604]: read 112 bytes
D, [2024-04-15T14:38:10.558713 #32] DEBUG -- io[604]: received packet nr 6 type 51 len 44
D, [2024-04-15T14:38:10.558758 #32] DEBUG -- net.ssh.authentication.session[618]: allowed methods: publickey,password
D, [2024-04-15T14:38:10.558778 #32] DEBUG -- net.ssh.authentication.methods.publickey[668]: trying publickey (51:b8:75:ba:0f:d8:4a:d5:0c:77:b1:27:2e:76:cb:a4) alg ssh-rsa
D, [2024-04-15T14:38:10.558885 #32] DEBUG -- io[604]: queueing packet nr 7 type 50 len 348
D, [2024-04-15T14:38:10.558916 #32] DEBUG -- io[604]: sent 416 bytes
D, [2024-04-15T14:38:10.561035 #32] DEBUG -- io[604]: read 112 bytes
D, [2024-04-15T14:38:10.561133 #32] DEBUG -- io[604]: received packet nr 7 type 51 len 44
D, [2024-04-15T14:38:10.561164 #32] DEBUG -- net.ssh.authentication.session[618]: allowed methods: publickey,password
D, [2024-04-15T14:38:10.561186 #32] DEBUG -- net.ssh.authentication.session[618]: trying password
D, [2024-04-15T14:38:10.561292 #32] DEBUG -- io[604]: queueing packet nr 8 type 50 len 60
D, [2024-04-15T14:38:10.561309 #32] DEBUG -- io[604]: sent 128 bytes
D, [2024-04-15T14:38:10.569321 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:10.569577 #32] DEBUG -- io[604]: received packet nr 8 type 52 len 12
D, [2024-04-15T14:38:10.569701 #32] DEBUG -- net.ssh.authentication.methods.password[67c]: password succeeded
D, [2024-04-15T14:38:10.569845 #32] DEBUG -- : lib/oxidized/input/cli.rb: Running post_login commands at APSiegeLABviaProxy
D, [2024-04-15T14:38:10.569874 #32] DEBUG -- : lib/oxidized/model/model.rb Collecting commands' outputs
D, [2024-04-15T14:38:10.569909 #32] DEBUG -- : lib/oxidized/model/model.rb Executing /system routerboard print
D, [2024-04-15T14:38:10.569938 #32] DEBUG -- : lib/oxidized/input/ssh.rb /system routerboard print @ APSiegeLABviaProxy with expect: /\[\w+@\S+(\s+\S+)*\]\s?>\s?$/
D, [2024-04-15T14:38:10.570186 #32] DEBUG -- io[604]: queueing packet nr 9 type 90 len 44
D, [2024-04-15T14:38:10.570365 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:10.572404 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:10.572788 #32] DEBUG -- io[604]: received packet nr 9 type 91 len 28
I, [2024-04-15T14:38:10.572937 #32]  INFO -- net.ssh.connection.session[690]: channel_open_confirmation: 0 0 2621440 262144
I, [2024-04-15T14:38:10.573068 #32]  INFO -- net.ssh.connection.channel[6a4]: sending channel request "env"
D, [2024-04-15T14:38:10.573310 #32] DEBUG -- io[604]: queueing packet nr 10 type 98 len 44
I, [2024-04-15T14:38:10.573379 #32]  INFO -- net.ssh.connection.channel[6a4]: sending channel request "env"
D, [2024-04-15T14:38:10.573525 #32] DEBUG -- io[604]: queueing packet nr 11 type 98 len 60
I, [2024-04-15T14:38:10.573550 #32]  INFO -- net.ssh.connection.channel[6a4]: sending channel request "env"
D, [2024-04-15T14:38:10.573670 #32] DEBUG -- io[604]: queueing packet nr 12 type 98 len 44
I, [2024-04-15T14:38:10.573709 #32]  INFO -- net.ssh.connection.channel[6a4]: sending channel request "exec"
D, [2024-04-15T14:38:10.573845 #32] DEBUG -- io[604]: queueing packet nr 13 type 98 len 60
D, [2024-04-15T14:38:10.573948 #32] DEBUG -- io[604]: sent 480 bytes
D, [2024-04-15T14:38:10.576221 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:10.576373 #32] DEBUG -- io[604]: received packet nr 10 type 99 len 12
I, [2024-04-15T14:38:10.576428 #32]  INFO -- net.ssh.connection.session[690]: channel_success: 0
D, [2024-04-15T14:38:10.594849 #32] DEBUG -- io[604]: read 512 bytes
D, [2024-04-15T14:38:10.595122 #32] DEBUG -- io[604]: received packet nr 11 type 94 len 252
I, [2024-04-15T14:38:10.595200 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 0 236b
D, [2024-04-15T14:38:10.595376 #32] DEBUG -- io[604]: received packet nr 12 type 98 len 44
I, [2024-04-15T14:38:10.595445 #32]  INFO -- net.ssh.connection.session[690]: channel_request: 0 exit-status false
D, [2024-04-15T14:38:10.595591 #32] DEBUG -- io[604]: received packet nr 13 type 97 len 12
I, [2024-04-15T14:38:10.595630 #32]  INFO -- net.ssh.connection.session[690]: channel_close: 0
D, [2024-04-15T14:38:10.595842 #32] DEBUG -- io[604]: queueing packet nr 14 type 97 len 28
I, [2024-04-15T14:38:10.595880 #32]  INFO -- net.ssh.connection.session[690]: 10.20.0.10 delete channel 0 which closed locally and remotely
D, [2024-04-15T14:38:10.595970 #32] DEBUG -- io[604]: sent 96 bytes
D, [2024-04-15T14:38:10.596344 #32] DEBUG -- : lib/oxidized/model/model.rb Executing /system package update print
D, [2024-04-15T14:38:10.596395 #32] DEBUG -- : lib/oxidized/input/ssh.rb /system package update print @ APSiegeLABviaProxy with expect: /\[\w+@\S+(\s+\S+)*\]\s?>\s?$/
D, [2024-04-15T14:38:10.596618 #32] DEBUG -- io[604]: queueing packet nr 15 type 90 len 44
D, [2024-04-15T14:38:10.596730 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:10.599130 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:10.599276 #32] DEBUG -- io[604]: received packet nr 14 type 91 len 28
I, [2024-04-15T14:38:10.599324 #32]  INFO -- net.ssh.connection.session[690]: channel_open_confirmation: 1 1 2621440 262144
I, [2024-04-15T14:38:10.599412 #32]  INFO -- net.ssh.connection.channel[6b8]: sending channel request "env"
D, [2024-04-15T14:38:10.599534 #32] DEBUG -- io[604]: queueing packet nr 16 type 98 len 44
I, [2024-04-15T14:38:10.599581 #32]  INFO -- net.ssh.connection.channel[6b8]: sending channel request "env"
D, [2024-04-15T14:38:10.599692 #32] DEBUG -- io[604]: queueing packet nr 17 type 98 len 60
I, [2024-04-15T14:38:10.599710 #32]  INFO -- net.ssh.connection.channel[6b8]: sending channel request "env"
D, [2024-04-15T14:38:10.599805 #32] DEBUG -- io[604]: queueing packet nr 18 type 98 len 44
I, [2024-04-15T14:38:10.599825 #32]  INFO -- net.ssh.connection.channel[6b8]: sending channel request "exec"
D, [2024-04-15T14:38:10.599921 #32] DEBUG -- io[604]: queueing packet nr 19 type 98 len 60
D, [2024-04-15T14:38:10.599986 #32] DEBUG -- io[604]: sent 480 bytes
D, [2024-04-15T14:38:10.602467 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:10.602618 #32] DEBUG -- io[604]: received packet nr 15 type 99 len 12
I, [2024-04-15T14:38:10.602664 #32]  INFO -- net.ssh.connection.session[690]: channel_success: 1
D, [2024-04-15T14:38:10.604023 #32] DEBUG -- io[604]: read 336 bytes
D, [2024-04-15T14:38:10.604151 #32] DEBUG -- io[604]: received packet nr 16 type 94 len 76
I, [2024-04-15T14:38:10.604194 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 1 60b
D, [2024-04-15T14:38:10.604327 #32] DEBUG -- io[604]: received packet nr 17 type 98 len 44
I, [2024-04-15T14:38:10.604395 #32]  INFO -- net.ssh.connection.session[690]: channel_request: 1 exit-status false
D, [2024-04-15T14:38:10.604527 #32] DEBUG -- io[604]: received packet nr 18 type 97 len 12
I, [2024-04-15T14:38:10.604560 #32]  INFO -- net.ssh.connection.session[690]: channel_close: 1
D, [2024-04-15T14:38:10.604722 #32] DEBUG -- io[604]: queueing packet nr 20 type 97 len 28
I, [2024-04-15T14:38:10.604752 #32]  INFO -- net.ssh.connection.session[690]: 10.20.0.10 delete channel 1 which closed locally and remotely
D, [2024-04-15T14:38:10.604829 #32] DEBUG -- io[604]: sent 96 bytes
D, [2024-04-15T14:38:10.605353 #32] DEBUG -- : lib/oxidized/model/model.rb Executing /system history print without-paging
D, [2024-04-15T14:38:10.605394 #32] DEBUG -- : lib/oxidized/input/ssh.rb /system history print without-paging @ APSiegeLABviaProxy with expect: /\[\w+@\S+(\s+\S+)*\]\s?>\s?$/
D, [2024-04-15T14:38:10.605631 #32] DEBUG -- io[604]: queueing packet nr 21 type 90 len 44
D, [2024-04-15T14:38:10.605743 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:10.607794 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:10.607935 #32] DEBUG -- io[604]: received packet nr 19 type 91 len 28
I, [2024-04-15T14:38:10.607987 #32]  INFO -- net.ssh.connection.session[690]: channel_open_confirmation: 2 2 2621440 262144
I, [2024-04-15T14:38:10.608068 #32]  INFO -- net.ssh.connection.channel[6cc]: sending channel request "env"
D, [2024-04-15T14:38:10.608182 #32] DEBUG -- io[604]: queueing packet nr 22 type 98 len 44
I, [2024-04-15T14:38:10.608228 #32]  INFO -- net.ssh.connection.channel[6cc]: sending channel request "env"
D, [2024-04-15T14:38:10.608336 #32] DEBUG -- io[604]: queueing packet nr 23 type 98 len 60
I, [2024-04-15T14:38:10.608354 #32]  INFO -- net.ssh.connection.channel[6cc]: sending channel request "env"
D, [2024-04-15T14:38:10.608438 #32] DEBUG -- io[604]: queueing packet nr 24 type 98 len 44
I, [2024-04-15T14:38:10.608458 #32]  INFO -- net.ssh.connection.channel[6cc]: sending channel request "exec"
D, [2024-04-15T14:38:10.608552 #32] DEBUG -- io[604]: queueing packet nr 25 type 98 len 60
D, [2024-04-15T14:38:10.608614 #32] DEBUG -- io[604]: sent 480 bytes
D, [2024-04-15T14:38:10.610961 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:10.611127 #32] DEBUG -- io[604]: received packet nr 20 type 99 len 12
I, [2024-04-15T14:38:10.611175 #32]  INFO -- net.ssh.connection.session[690]: channel_success: 2
D, [2024-04-15T14:38:10.616932 #32] DEBUG -- io[604]: read 800 bytes
D, [2024-04-15T14:38:10.617085 #32] DEBUG -- io[604]: received packet nr 21 type 94 len 284
I, [2024-04-15T14:38:10.617147 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 2 256b
D, [2024-04-15T14:38:10.617281 #32] DEBUG -- io[604]: received packet nr 22 type 94 len 188
I, [2024-04-15T14:38:10.617314 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 2 162b
D, [2024-04-15T14:38:10.617419 #32] DEBUG -- io[604]: received packet nr 23 type 98 len 44
I, [2024-04-15T14:38:10.617461 #32]  INFO -- net.ssh.connection.session[690]: channel_request: 2 exit-status false
D, [2024-04-15T14:38:10.617550 #32] DEBUG -- io[604]: received packet nr 24 type 97 len 12
I, [2024-04-15T14:38:10.617580 #32]  INFO -- net.ssh.connection.session[690]: channel_close: 2
D, [2024-04-15T14:38:10.617717 #32] DEBUG -- io[604]: queueing packet nr 26 type 97 len 28
I, [2024-04-15T14:38:10.617742 #32]  INFO -- net.ssh.connection.session[690]: 10.20.0.10 delete channel 2 which closed locally and remotely
D, [2024-04-15T14:38:10.617814 #32] DEBUG -- io[604]: sent 96 bytes
D, [2024-04-15T14:38:10.618038 #32] DEBUG -- : lib/oxidized/model/routerosproxy.rb: running /export for routerosproxy version 7
D, [2024-04-15T14:38:10.618090 #32] DEBUG -- : lib/oxidized/model/model.rb Executing /export hide-sensitive
D, [2024-04-15T14:38:10.618111 #32] DEBUG -- : lib/oxidized/input/ssh.rb /export hide-sensitive @ APSiegeLABviaProxy with expect: /\[\w+@\S+(\s+\S+)*\]\s?>\s?$/
D, [2024-04-15T14:38:10.618293 #32] DEBUG -- io[604]: queueing packet nr 27 type 90 len 44
D, [2024-04-15T14:38:10.618388 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:10.620385 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:10.620770 #32] DEBUG -- io[604]: received packet nr 25 type 91 len 28
I, [2024-04-15T14:38:10.620828 #32]  INFO -- net.ssh.connection.session[690]: channel_open_confirmation: 3 3 2621440 262144
I, [2024-04-15T14:38:10.620915 #32]  INFO -- net.ssh.connection.channel[6e0]: sending channel request "env"
D, [2024-04-15T14:38:10.621035 #32] DEBUG -- io[604]: queueing packet nr 28 type 98 len 44
I, [2024-04-15T14:38:10.621080 #32]  INFO -- net.ssh.connection.channel[6e0]: sending channel request "env"
D, [2024-04-15T14:38:10.621197 #32] DEBUG -- io[604]: queueing packet nr 29 type 98 len 60
I, [2024-04-15T14:38:10.621219 #32]  INFO -- net.ssh.connection.channel[6e0]: sending channel request "env"
D, [2024-04-15T14:38:10.621313 #32] DEBUG -- io[604]: queueing packet nr 30 type 98 len 44
I, [2024-04-15T14:38:10.621332 #32]  INFO -- net.ssh.connection.channel[6e0]: sending channel request "exec"
D, [2024-04-15T14:38:10.621424 #32] DEBUG -- io[604]: queueing packet nr 31 type 98 len 60
D, [2024-04-15T14:38:10.621491 #32] DEBUG -- io[604]: sent 480 bytes
D, [2024-04-15T14:38:10.623779 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:10.623903 #32] DEBUG -- io[604]: received packet nr 26 type 99 len 12
I, [2024-04-15T14:38:10.623946 #32]  INFO -- net.ssh.connection.session[690]: channel_success: 3
D, [2024-04-15T14:38:10.632173 #32] DEBUG -- io[604]: read 224 bytes
D, [2024-04-15T14:38:10.632317 #32] DEBUG -- io[604]: received packet nr 27 type 94 len 156
I, [2024-04-15T14:38:10.632360 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 131b
D, [2024-04-15T14:38:10.635973 #32] DEBUG -- io[604]: read 304 bytes
D, [2024-04-15T14:38:10.636198 #32] DEBUG -- io[604]: received packet nr 28 type 94 len 44
I, [2024-04-15T14:38:10.636251 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 19b
D, [2024-04-15T14:38:10.636398 #32] DEBUG -- io[604]: received packet nr 29 type 94 len 124
I, [2024-04-15T14:38:10.636440 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 104b
D, [2024-04-15T14:38:10.641837 #32] DEBUG -- io[604]: read 112 bytes
D, [2024-04-15T14:38:10.641982 #32] DEBUG -- io[604]: received packet nr 30 type 94 len 44
I, [2024-04-15T14:38:10.642019 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 17b
D, [2024-04-15T14:38:10.642947 #32] DEBUG -- io[604]: read 416 bytes
D, [2024-04-15T14:38:10.643070 #32] DEBUG -- io[604]: received packet nr 31 type 94 len 172
I, [2024-04-15T14:38:10.643105 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 155b
D, [2024-04-15T14:38:10.643632 #32] DEBUG -- io[604]: received packet nr 32 type 94 len 108
I, [2024-04-15T14:38:10.643677 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 90b
D, [2024-04-15T14:38:10.644001 #32] DEBUG -- io[604]: read 320 bytes
D, [2024-04-15T14:38:10.644129 #32] DEBUG -- io[604]: received packet nr 33 type 94 len 252
I, [2024-04-15T14:38:10.644161 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 235b
D, [2024-04-15T14:38:10.651623 #32] DEBUG -- io[604]: read 256 bytes
D, [2024-04-15T14:38:10.651784 #32] DEBUG -- io[604]: received packet nr 34 type 94 len 44
I, [2024-04-15T14:38:10.651825 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 17b
D, [2024-04-15T14:38:10.652149 #32] DEBUG -- io[604]: received packet nr 35 type 94 len 76
I, [2024-04-15T14:38:10.652186 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 50b
D, [2024-04-15T14:38:10.658818 #32] DEBUG -- io[604]: read 224 bytes
D, [2024-04-15T14:38:10.658998 #32] DEBUG -- io[604]: received packet nr 36 type 94 len 44
I, [2024-04-15T14:38:10.659042 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 17b
D, [2024-04-15T14:38:10.659155 #32] DEBUG -- io[604]: received packet nr 37 type 94 len 44
I, [2024-04-15T14:38:10.659188 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 28b
D, [2024-04-15T14:38:10.826330 #32] DEBUG -- io[604]: read 656 bytes
D, [2024-04-15T14:38:10.826794 #32] DEBUG -- io[604]: received packet nr 38 type 94 len 44
I, [2024-04-15T14:38:10.826895 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 24b
D, [2024-04-15T14:38:10.827465 #32] DEBUG -- io[604]: received packet nr 39 type 94 len 156
I, [2024-04-15T14:38:10.827534 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 141b
D, [2024-04-15T14:38:10.827704 #32] DEBUG -- io[604]: received packet nr 40 type 94 len 92
I, [2024-04-15T14:38:10.827747 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 70b
D, [2024-04-15T14:38:10.827865 #32] DEBUG -- io[604]: received packet nr 41 type 94 len 92
I, [2024-04-15T14:38:10.827901 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 71b
D, [2024-04-15T14:38:10.830109 #32] DEBUG -- io[604]: read 240 bytes
D, [2024-04-15T14:38:10.830511 #32] DEBUG -- io[604]: received packet nr 42 type 94 len 60
I, [2024-04-15T14:38:10.830582 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 34b
D, [2024-04-15T14:38:10.830701 #32] DEBUG -- io[604]: received packet nr 43 type 94 len 44
I, [2024-04-15T14:38:10.830734 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 21b
D, [2024-04-15T14:38:10.831095 #32] DEBUG -- io[604]: read 256 bytes
D, [2024-04-15T14:38:10.831393 #32] DEBUG -- io[604]: received packet nr 44 type 94 len 60
I, [2024-04-15T14:38:10.831459 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 33b
D, [2024-04-15T14:38:10.831575 #32] DEBUG -- io[604]: received packet nr 45 type 94 len 60
I, [2024-04-15T14:38:10.831608 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 34b
D, [2024-04-15T14:38:10.834089 #32] DEBUG -- io[604]: read 272 bytes
D, [2024-04-15T14:38:10.834355 #32] DEBUG -- io[604]: received packet nr 46 type 94 len 44
I, [2024-04-15T14:38:10.834420 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 24b
D, [2024-04-15T14:38:10.834535 #32] DEBUG -- io[604]: received packet nr 47 type 94 len 92
I, [2024-04-15T14:38:10.834569 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 70b
D, [2024-04-15T14:38:10.868314 #32] DEBUG -- io[604]: read 256 bytes
D, [2024-04-15T14:38:10.868479 #32] DEBUG -- io[604]: received packet nr 48 type 94 len 44
I, [2024-04-15T14:38:10.868496 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 24b
D, [2024-04-15T14:38:10.868535 #32] DEBUG -- io[604]: received packet nr 49 type 94 len 76
I, [2024-04-15T14:38:10.868544 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 62b
D, [2024-04-15T14:38:10.878106 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:10.879051 #32] DEBUG -- io[604]: received packet nr 50 type 94 len 28
I, [2024-04-15T14:38:10.879209 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 13b
D, [2024-04-15T14:38:10.879431 #32] DEBUG -- io[604]: received packet nr 51 type 94 len 124
I, [2024-04-15T14:38:10.879499 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 104b
D, [2024-04-15T14:38:10.881139 #32] DEBUG -- io[604]: read 240 bytes
D, [2024-04-15T14:38:10.881566 #32] DEBUG -- io[604]: received packet nr 52 type 94 len 28
I, [2024-04-15T14:38:10.881665 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 9b
D, [2024-04-15T14:38:10.881869 #32] DEBUG -- io[604]: received packet nr 53 type 94 len 76
I, [2024-04-15T14:38:10.881938 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 55b
D, [2024-04-15T14:38:10.882586 #32] DEBUG -- io[604]: read 432 bytes
D, [2024-04-15T14:38:10.882938 #32] DEBUG -- io[604]: received packet nr 54 type 94 len 44
I, [2024-04-15T14:38:10.883037 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 21b
D, [2024-04-15T14:38:10.883315 #32] DEBUG -- io[604]: received packet nr 55 type 94 len 60
I, [2024-04-15T14:38:10.883398 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.883650 #32] DEBUG -- io[604]: received packet nr 56 type 94 len 124
I, [2024-04-15T14:38:10.883727 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 107b
D, [2024-04-15T14:38:10.892212 #32] DEBUG -- io[604]: read 336 bytes
D, [2024-04-15T14:38:10.892432 #32] DEBUG -- io[604]: received packet nr 57 type 94 len 108
I, [2024-04-15T14:38:10.892491 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 93b
D, [2024-04-15T14:38:10.892573 #32] DEBUG -- io[604]: received packet nr 58 type 94 len 92
I, [2024-04-15T14:38:10.892597 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 76b
D, [2024-04-15T14:38:10.892667 #32] DEBUG -- io[604]: read 2768 bytes
D, [2024-04-15T14:38:10.892758 #32] DEBUG -- io[604]: received packet nr 59 type 94 len 60
I, [2024-04-15T14:38:10.892795 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.892867 #32] DEBUG -- io[604]: received packet nr 60 type 94 len 92
I, [2024-04-15T14:38:10.899603 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 77b
D, [2024-04-15T14:38:10.899793 #32] DEBUG -- io[604]: received packet nr 61 type 94 len 108
I, [2024-04-15T14:38:10.899827 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 79b
D, [2024-04-15T14:38:10.899892 #32] DEBUG -- io[604]: received packet nr 62 type 94 len 44
I, [2024-04-15T14:38:10.899910 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 28b
D, [2024-04-15T14:38:10.899959 #32] DEBUG -- io[604]: received packet nr 63 type 94 len 92
I, [2024-04-15T14:38:10.899972 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 77b
D, [2024-04-15T14:38:10.900015 #32] DEBUG -- io[604]: received packet nr 64 type 94 len 44
I, [2024-04-15T14:38:10.900028 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 27b
D, [2024-04-15T14:38:10.906183 #32] DEBUG -- io[604]: received packet nr 65 type 94 len 92
I, [2024-04-15T14:38:10.906257 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 78b
D, [2024-04-15T14:38:10.906336 #32] DEBUG -- io[604]: received packet nr 66 type 94 len 44
I, [2024-04-15T14:38:10.906349 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 28b
D, [2024-04-15T14:38:10.906394 #32] DEBUG -- io[604]: received packet nr 67 type 94 len 92
I, [2024-04-15T14:38:10.906406 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 78b
D, [2024-04-15T14:38:10.906441 #32] DEBUG -- io[604]: received packet nr 68 type 94 len 76
I, [2024-04-15T14:38:10.906451 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 57b
D, [2024-04-15T14:38:10.906488 #32] DEBUG -- io[604]: received packet nr 69 type 94 len 60
I, [2024-04-15T14:38:10.906499 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 43b
D, [2024-04-15T14:38:10.906535 #32] DEBUG -- io[604]: received packet nr 70 type 94 len 124
I, [2024-04-15T14:38:10.906544 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 108b
D, [2024-04-15T14:38:10.906583 #32] DEBUG -- io[604]: received packet nr 71 type 94 len 92
I, [2024-04-15T14:38:10.906595 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 65b
D, [2024-04-15T14:38:10.906638 #32] DEBUG -- io[604]: received packet nr 72 type 94 len 44
I, [2024-04-15T14:38:10.906648 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 30b
D, [2024-04-15T14:38:10.906707 #32] DEBUG -- io[604]: received packet nr 73 type 94 len 60
I, [2024-04-15T14:38:10.906725 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.906781 #32] DEBUG -- io[604]: received packet nr 74 type 94 len 140
I, [2024-04-15T14:38:10.906795 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 126b
D, [2024-04-15T14:38:10.906831 #32] DEBUG -- io[604]: received packet nr 75 type 94 len 44
I, [2024-04-15T14:38:10.906841 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 18b
D, [2024-04-15T14:38:10.906878 #32] DEBUG -- io[604]: received packet nr 76 type 94 len 92
I, [2024-04-15T14:38:10.906889 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 68b
D, [2024-04-15T14:38:10.906926 #32] DEBUG -- io[604]: received packet nr 77 type 94 len 76
I, [2024-04-15T14:38:10.906937 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 50b
D, [2024-04-15T14:38:10.911507 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:10.911691 #32] DEBUG -- io[604]: received packet nr 78 type 94 len 28
I, [2024-04-15T14:38:10.911724 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 11b
D, [2024-04-15T14:38:10.912495 #32] DEBUG -- io[604]: read 224 bytes
D, [2024-04-15T14:38:10.912625 #32] DEBUG -- io[604]: received packet nr 79 type 94 len 156
I, [2024-04-15T14:38:10.912654 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 129b
D, [2024-04-15T14:38:10.917738 #32] DEBUG -- io[604]: read 432 bytes
D, [2024-04-15T14:38:10.917896 #32] DEBUG -- io[604]: received packet nr 80 type 94 len 28
I, [2024-04-15T14:38:10.917922 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 13b
D, [2024-04-15T14:38:10.917963 #32] DEBUG -- io[604]: received packet nr 81 type 94 len 44
I, [2024-04-15T14:38:10.917975 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 25b
D, [2024-04-15T14:38:10.918020 #32] DEBUG -- io[604]: received packet nr 82 type 94 len 44
I, [2024-04-15T14:38:10.918037 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 22b
D, [2024-04-15T14:38:10.918072 #32] DEBUG -- io[604]: received packet nr 83 type 94 len 44
I, [2024-04-15T14:38:10.918082 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 26b
D, [2024-04-15T14:38:10.926167 #32] DEBUG -- io[604]: read 240 bytes
D, [2024-04-15T14:38:10.926624 #32] DEBUG -- io[604]: received packet nr 84 type 94 len 28
I, [2024-04-15T14:38:10.926668 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 9b
D, [2024-04-15T14:38:10.926733 #32] DEBUG -- io[604]: received packet nr 85 type 94 len 76
I, [2024-04-15T14:38:10.926750 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 48b
D, [2024-04-15T14:38:10.948645 #32] DEBUG -- io[604]: read 1344 bytes
D, [2024-04-15T14:38:10.948905 #32] DEBUG -- io[604]: received packet nr 86 type 94 len 44
I, [2024-04-15T14:38:10.948946 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 29b
D, [2024-04-15T14:38:10.949002 #32] DEBUG -- io[604]: received packet nr 87 type 94 len 220
I, [2024-04-15T14:38:10.949015 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 197b
D, [2024-04-15T14:38:10.949058 #32] DEBUG -- io[604]: received packet nr 88 type 94 len 92
I, [2024-04-15T14:38:10.949070 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 76b
D, [2024-04-15T14:38:10.949115 #32] DEBUG -- io[604]: received packet nr 89 type 94 len 92
I, [2024-04-15T14:38:10.949126 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 64b
D, [2024-04-15T14:38:10.949166 #32] DEBUG -- io[604]: received packet nr 90 type 94 len 92
I, [2024-04-15T14:38:10.949176 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 69b
D, [2024-04-15T14:38:10.949211 #32] DEBUG -- io[604]: received packet nr 91 type 94 len 92
I, [2024-04-15T14:38:10.949221 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 74b
D, [2024-04-15T14:38:10.949253 #32] DEBUG -- io[604]: received packet nr 92 type 94 len 92
I, [2024-04-15T14:38:10.949265 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 66b
D, [2024-04-15T14:38:10.949303 #32] DEBUG -- io[604]: received packet nr 93 type 94 len 76
I, [2024-04-15T14:38:10.949314 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 62b
D, [2024-04-15T14:38:10.949989 #32] DEBUG -- io[604]: read 448 bytes
D, [2024-04-15T14:38:10.950041 #32] DEBUG -- io[604]: received packet nr 94 type 94 len 44
I, [2024-04-15T14:38:10.950054 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 23b
D, [2024-04-15T14:38:10.950091 #32] DEBUG -- io[604]: received packet nr 95 type 94 len 140
I, [2024-04-15T14:38:10.950103 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 113b
D, [2024-04-15T14:38:10.950145 #32] DEBUG -- io[604]: received packet nr 96 type 94 len 60
I, [2024-04-15T14:38:10.950157 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 35b
D, [2024-04-15T14:38:10.950901 #32] DEBUG -- io[604]: read 448 bytes
D, [2024-04-15T14:38:10.950948 #32] DEBUG -- io[604]: received packet nr 97 type 94 len 108
I, [2024-04-15T14:38:10.950962 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 80b
D, [2024-04-15T14:38:10.951005 #32] DEBUG -- io[604]: received packet nr 98 type 94 len 28
I, [2024-04-15T14:38:10.951019 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 13b
D, [2024-04-15T14:38:10.951057 #32] DEBUG -- io[604]: received packet nr 99 type 94 len 108
I, [2024-04-15T14:38:10.951070 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 87b
D, [2024-04-15T14:38:10.952263 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:10.952314 #32] DEBUG -- io[604]: received packet nr 100 type 94 len 92
I, [2024-04-15T14:38:10.952329 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 74b
D, [2024-04-15T14:38:10.952377 #32] DEBUG -- io[604]: received packet nr 101 type 94 len 60
I, [2024-04-15T14:38:10.952390 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 39b
D, [2024-04-15T14:38:10.953138 #32] DEBUG -- io[604]: read 336 bytes
D, [2024-04-15T14:38:10.953239 #32] DEBUG -- io[604]: received packet nr 102 type 94 len 60
I, [2024-04-15T14:38:10.953258 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.953439 #32] DEBUG -- io[604]: received packet nr 103 type 94 len 140
I, [2024-04-15T14:38:10.953460 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 111b
D, [2024-04-15T14:38:10.953649 #32] DEBUG -- io[604]: read 544 bytes
D, [2024-04-15T14:38:10.953712 #32] DEBUG -- io[604]: received packet nr 104 type 94 len 108
I, [2024-04-15T14:38:10.953729 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 81b
D, [2024-04-15T14:38:10.953778 #32] DEBUG -- io[604]: received packet nr 105 type 94 len 44
I, [2024-04-15T14:38:10.953792 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 18b
D, [2024-04-15T14:38:10.953840 #32] DEBUG -- io[604]: received packet nr 106 type 94 len 92
I, [2024-04-15T14:38:10.953855 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 77b
D, [2024-04-15T14:38:10.953899 #32] DEBUG -- io[604]: received packet nr 107 type 94 len 28
I, [2024-04-15T14:38:10.953912 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 14b
D, [2024-04-15T14:38:10.954913 #32] DEBUG -- io[604]: read 272 bytes
D, [2024-04-15T14:38:10.954976 #32] DEBUG -- io[604]: received packet nr 108 type 94 len 92
I, [2024-04-15T14:38:10.954992 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 78b
D, [2024-04-15T14:38:10.955045 #32] DEBUG -- io[604]: received packet nr 109 type 94 len 44
I, [2024-04-15T14:38:10.955060 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 15b
D, [2024-04-15T14:38:10.955568 #32] DEBUG -- io[604]: read 416 bytes
D, [2024-04-15T14:38:10.955636 #32] DEBUG -- io[604]: received packet nr 110 type 94 len 60
I, [2024-04-15T14:38:10.955653 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.955709 #32] DEBUG -- io[604]: received packet nr 111 type 94 len 92
I, [2024-04-15T14:38:10.955725 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 75b
D, [2024-04-15T14:38:10.955777 #32] DEBUG -- io[604]: received packet nr 112 type 94 len 60
I, [2024-04-15T14:38:10.955791 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 39b
D, [2024-04-15T14:38:10.956681 #32] DEBUG -- io[604]: read 496 bytes
D, [2024-04-15T14:38:10.956838 #32] DEBUG -- io[604]: received packet nr 113 type 94 len 108
I, [2024-04-15T14:38:10.956885 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 87b
D, [2024-04-15T14:38:10.956981 #32] DEBUG -- io[604]: received packet nr 114 type 94 len 60
I, [2024-04-15T14:38:10.957008 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 43b
D, [2024-04-15T14:38:10.957112 #32] DEBUG -- io[604]: received packet nr 115 type 94 len 124
I, [2024-04-15T14:38:10.957143 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 107b
D, [2024-04-15T14:38:10.957585 #32] DEBUG -- io[604]: read 272 bytes
D, [2024-04-15T14:38:10.957783 #32] DEBUG -- io[604]: received packet nr 116 type 94 len 92
I, [2024-04-15T14:38:10.957835 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 65b
D, [2024-04-15T14:38:10.957943 #32] DEBUG -- io[604]: received packet nr 117 type 94 len 44
I, [2024-04-15T14:38:10.957970 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 30b
D, [2024-04-15T14:38:10.958267 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:10.958333 #32] DEBUG -- io[604]: received packet nr 118 type 94 len 60
I, [2024-04-15T14:38:10.958352 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.958409 #32] DEBUG -- io[604]: received packet nr 119 type 94 len 92
I, [2024-04-15T14:38:10.958426 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 73b
D, [2024-04-15T14:38:10.959019 #32] DEBUG -- io[604]: read 576 bytes
D, [2024-04-15T14:38:10.959082 #32] DEBUG -- io[604]: received packet nr 120 type 94 len 60
I, [2024-04-15T14:38:10.959100 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.959171 #32] DEBUG -- io[604]: received packet nr 121 type 94 len 92
I, [2024-04-15T14:38:10.959187 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 73b
D, [2024-04-15T14:38:10.959384 #32] DEBUG -- io[604]: received packet nr 122 type 94 len 92
I, [2024-04-15T14:38:10.959407 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 77b
D, [2024-04-15T14:38:10.959464 #32] DEBUG -- io[604]: received packet nr 123 type 94 len 60
I, [2024-04-15T14:38:10.959481 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 39b
D, [2024-04-15T14:38:10.960141 #32] DEBUG -- io[604]: read 416 bytes
D, [2024-04-15T14:38:10.960205 #32] DEBUG -- io[604]: received packet nr 124 type 94 len 92
I, [2024-04-15T14:38:10.960223 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 77b
D, [2024-04-15T14:38:10.960272 #32] DEBUG -- io[604]: received packet nr 125 type 94 len 28
I, [2024-04-15T14:38:10.960288 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 12b
D, [2024-04-15T14:38:10.960337 #32] DEBUG -- io[604]: received packet nr 126 type 94 len 92
I, [2024-04-15T14:38:10.960352 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 76b
D, [2024-04-15T14:38:10.961350 #32] DEBUG -- io[604]: read 272 bytes
D, [2024-04-15T14:38:10.961418 #32] DEBUG -- io[604]: received packet nr 127 type 94 len 92
I, [2024-04-15T14:38:10.961437 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 74b
D, [2024-04-15T14:38:10.961489 #32] DEBUG -- io[604]: received packet nr 128 type 94 len 44
I, [2024-04-15T14:38:10.961504 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 27b
D, [2024-04-15T14:38:10.962073 #32] DEBUG -- io[604]: read 272 bytes
D, [2024-04-15T14:38:10.962177 #32] DEBUG -- io[604]: received packet nr 129 type 94 len 108
I, [2024-04-15T14:38:10.962205 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 79b
D, [2024-04-15T14:38:10.962259 #32] DEBUG -- io[604]: received packet nr 130 type 94 len 28
I, [2024-04-15T14:38:10.962277 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 14b
D, [2024-04-15T14:38:10.962674 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:10.962725 #32] DEBUG -- io[604]: received packet nr 131 type 94 len 108
I, [2024-04-15T14:38:10.962741 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 80b
D, [2024-04-15T14:38:10.962803 #32] DEBUG -- io[604]: received packet nr 132 type 94 len 44
I, [2024-04-15T14:38:10.962817 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 15b
D, [2024-04-15T14:38:10.963442 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:10.963552 #32] DEBUG -- io[604]: received packet nr 133 type 94 len 60
I, [2024-04-15T14:38:10.963583 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 43b
D, [2024-04-15T14:38:10.963665 #32] DEBUG -- io[604]: received packet nr 134 type 94 len 92
I, [2024-04-15T14:38:10.963689 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 75b
D, [2024-04-15T14:38:10.963877 #32] DEBUG -- io[604]: read 304 bytes
D, [2024-04-15T14:38:10.963972 #32] DEBUG -- io[604]: received packet nr 135 type 94 len 60
I, [2024-04-15T14:38:10.963992 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 41b
D, [2024-04-15T14:38:10.964059 #32] DEBUG -- io[604]: received packet nr 136 type 94 len 108
I, [2024-04-15T14:38:10.964080 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 87b
D, [2024-04-15T14:38:11.023049 #32] DEBUG -- io[604]: read 144 bytes
D, [2024-04-15T14:38:11.023647 #32] DEBUG -- io[604]: received packet nr 137 type 94 len 76
I, [2024-04-15T14:38:11.023748 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 48b
D, [2024-04-15T14:38:11.025357 #32] DEBUG -- io[604]: read 224 bytes
D, [2024-04-15T14:38:11.025547 #32] DEBUG -- io[604]: received packet nr 138 type 94 len 44
I, [2024-04-15T14:38:11.025596 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 18b
D, [2024-04-15T14:38:11.025705 #32] DEBUG -- io[604]: received packet nr 139 type 94 len 44
I, [2024-04-15T14:38:11.025989 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 18b
D, [2024-04-15T14:38:11.028265 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:11.028561 #32] DEBUG -- io[604]: received packet nr 140 type 94 len 28
I, [2024-04-15T14:38:11.028630 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 14b
D, [2024-04-15T14:38:11.028717 #32] DEBUG -- io[604]: read 336 bytes
D, [2024-04-15T14:38:11.028835 #32] DEBUG -- io[604]: received packet nr 141 type 94 len 44
I, [2024-04-15T14:38:11.028867 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 22b
D, [2024-04-15T14:38:11.028953 #32] DEBUG -- io[604]: received packet nr 142 type 94 len 44
I, [2024-04-15T14:38:11.028978 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 20b
D, [2024-04-15T14:38:11.029068 #32] DEBUG -- io[604]: received packet nr 143 type 94 len 44
I, [2024-04-15T14:38:11.029134 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 17b
D, [2024-04-15T14:38:11.029654 #32] DEBUG -- io[604]: read 224 bytes
D, [2024-04-15T14:38:11.029983 #32] DEBUG -- io[604]: received packet nr 144 type 94 len 44
I, [2024-04-15T14:38:11.030062 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 28b
D, [2024-04-15T14:38:11.030218 #32] DEBUG -- io[604]: received packet nr 145 type 94 len 44
I, [2024-04-15T14:38:11.030252 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 26b
D, [2024-04-15T14:38:11.042701 #32] DEBUG -- io[604]: read 128 bytes
D, [2024-04-15T14:38:11.043032 #32] DEBUG -- io[604]: received packet nr 146 type 94 len 60
I, [2024-04-15T14:38:11.043101 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 33b
D, [2024-04-15T14:38:11.043402 #32] DEBUG -- io[604]: read 128 bytes
D, [2024-04-15T14:38:11.043662 #32] DEBUG -- io[604]: received packet nr 147 type 94 len 60
I, [2024-04-15T14:38:11.043723 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 36b
D, [2024-04-15T14:38:11.045430 #32] DEBUG -- io[604]: read 880 bytes
D, [2024-04-15T14:38:11.045743 #32] DEBUG -- io[604]: received packet nr 148 type 94 len 44
I, [2024-04-15T14:38:11.045811 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 16b
D, [2024-04-15T14:38:11.045962 #32] DEBUG -- io[604]: received packet nr 149 type 94 len 124
I, [2024-04-15T14:38:11.046003 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 101b
D, [2024-04-15T14:38:11.046105 #32] DEBUG -- io[604]: received packet nr 150 type 94 len 172
I, [2024-04-15T14:38:11.046136 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 153b
D, [2024-04-15T14:38:11.046226 #32] DEBUG -- io[604]: received packet nr 151 type 94 len 172
I, [2024-04-15T14:38:11.046254 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 151b
D, [2024-04-15T14:38:11.046341 #32] DEBUG -- io[604]: received packet nr 152 type 94 len 28
I, [2024-04-15T14:38:11.046367 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 9b
D, [2024-04-15T14:38:11.055936 #32] DEBUG -- : lib/oxidized/worker.rb: 1 jobs running in parallel
D, [2024-04-15T14:38:11.068537 #32] DEBUG -- io[604]: read 128 bytes
D, [2024-04-15T14:38:11.069275 #32] DEBUG -- io[604]: received packet nr 153 type 94 len 60
I, [2024-04-15T14:38:11.069490 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 40b
D, [2024-04-15T14:38:11.091017 #32] DEBUG -- io[604]: read 480 bytes
D, [2024-04-15T14:38:11.091590 #32] DEBUG -- io[604]: received packet nr 154 type 94 len 44
I, [2024-04-15T14:38:11.091733 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 18b
D, [2024-04-15T14:38:11.091997 #32] DEBUG -- io[604]: received packet nr 155 type 94 len 60
I, [2024-04-15T14:38:11.092082 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 33b
D, [2024-04-15T14:38:11.092369 #32] DEBUG -- io[604]: received packet nr 156 type 94 len 44
I, [2024-04-15T14:38:11.092453 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 29b
D, [2024-04-15T14:38:11.092680 #32] DEBUG -- io[604]: received packet nr 157 type 94 len 60
I, [2024-04-15T14:38:11.092751 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 33b
D, [2024-04-15T14:38:11.096419 #32] DEBUG -- io[604]: read 128 bytes
D, [2024-04-15T14:38:11.097030 #32] DEBUG -- io[604]: received packet nr 158 type 94 len 60
I, [2024-04-15T14:38:11.097128 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 3 39b
D, [2024-04-15T14:38:11.110924 #32] DEBUG -- io[604]: read 192 bytes
D, [2024-04-15T14:38:11.111462 #32] DEBUG -- io[604]: received packet nr 159 type 98 len 44
I, [2024-04-15T14:38:11.111630 #32]  INFO -- net.ssh.connection.session[690]: channel_request: 3 exit-status false
D, [2024-04-15T14:38:11.111900 #32] DEBUG -- io[604]: received packet nr 160 type 97 len 12
I, [2024-04-15T14:38:11.111976 #32]  INFO -- net.ssh.connection.session[690]: channel_close: 3
D, [2024-04-15T14:38:11.112337 #32] DEBUG -- io[604]: queueing packet nr 32 type 97 len 28
I, [2024-04-15T14:38:11.112401 #32]  INFO -- net.ssh.connection.session[690]: 10.20.0.10 delete channel 3 which closed locally and remotely
D, [2024-04-15T14:38:11.112545 #32] DEBUG -- io[604]: sent 96 bytes
D, [2024-04-15T14:38:11.113679 #32] DEBUG -- : lib/oxidized/input/cli.rb Running pre_logout commands at APSiegeLABviaProxy
D, [2024-04-15T14:38:11.113792 #32] DEBUG -- : lib/oxidized/input/ssh.rb quit @ APSiegeLABviaProxy with expect: nil
D, [2024-04-15T14:38:11.114254 #32] DEBUG -- io[604]: queueing packet nr 33 type 90 len 44
D, [2024-04-15T14:38:11.114478 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:11.116777 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:11.117249 #32] DEBUG -- io[604]: received packet nr 161 type 91 len 28
I, [2024-04-15T14:38:11.117396 #32]  INFO -- net.ssh.connection.session[690]: channel_open_confirmation: 4 4 2621440 262144
I, [2024-04-15T14:38:11.117591 #32]  INFO -- net.ssh.connection.channel[6f4]: sending channel request "env"
D, [2024-04-15T14:38:11.117919 #32] DEBUG -- io[604]: queueing packet nr 34 type 98 len 44
I, [2024-04-15T14:38:11.118034 #32]  INFO -- net.ssh.connection.channel[6f4]: sending channel request "env"
D, [2024-04-15T14:38:11.118310 #32] DEBUG -- io[604]: queueing packet nr 35 type 98 len 60
I, [2024-04-15T14:38:11.118373 #32]  INFO -- net.ssh.connection.channel[6f4]: sending channel request "env"
D, [2024-04-15T14:38:11.118623 #32] DEBUG -- io[604]: queueing packet nr 36 type 98 len 44
I, [2024-04-15T14:38:11.118689 #32]  INFO -- net.ssh.connection.channel[6f4]: sending channel request "exec"
D, [2024-04-15T14:38:11.118942 #32] DEBUG -- io[604]: queueing packet nr 37 type 98 len 28
D, [2024-04-15T14:38:11.119111 #32] DEBUG -- io[604]: sent 448 bytes
D, [2024-04-15T14:38:11.121682 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:11.122075 #32] DEBUG -- io[604]: received packet nr 162 type 99 len 12
I, [2024-04-15T14:38:11.122190 #32]  INFO -- net.ssh.connection.session[690]: channel_success: 4
D, [2024-04-15T14:38:11.122733 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:11.123310 #32] DEBUG -- io[604]: received packet nr 163 type 94 len 28
I, [2024-04-15T14:38:11.123492 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 4 12b
D, [2024-04-15T14:38:11.123725 #32] DEBUG -- io[604]: received packet nr 164 type 98 len 44
I, [2024-04-15T14:38:11.123798 #32]  INFO -- net.ssh.connection.session[690]: channel_request: 4 exit-status false
D, [2024-04-15T14:38:11.123980 #32] DEBUG -- io[604]: received packet nr 165 type 97 len 12
I, [2024-04-15T14:38:11.124038 #32]  INFO -- net.ssh.connection.session[690]: channel_close: 4
D, [2024-04-15T14:38:11.124385 #32] DEBUG -- io[604]: queueing packet nr 38 type 97 len 28
I, [2024-04-15T14:38:11.124448 #32]  INFO -- net.ssh.connection.session[690]: 10.20.0.10 delete channel 4 which closed locally and remotely
D, [2024-04-15T14:38:11.124612 #32] DEBUG -- io[604]: sent 96 bytes
D, [2024-04-15T14:38:11.124696 #32] DEBUG -- : lib/oxidized/input/ssh.rb quit @ APSiegeLABviaProxy with expect: nil
D, [2024-04-15T14:38:11.125393 #32] DEBUG -- io[604]: queueing packet nr 39 type 90 len 44
D, [2024-04-15T14:38:11.125553 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:11.127770 #32] DEBUG -- io[604]: read 96 bytes
D, [2024-04-15T14:38:11.127944 #32] DEBUG -- io[604]: received packet nr 166 type 91 len 28
I, [2024-04-15T14:38:11.128008 #32]  INFO -- net.ssh.connection.session[690]: channel_open_confirmation: 5 5 2621440 262144
I, [2024-04-15T14:38:11.128118 #32]  INFO -- net.ssh.connection.channel[708]: sending channel request "env"
D, [2024-04-15T14:38:11.128270 #32] DEBUG -- io[604]: queueing packet nr 40 type 98 len 44
I, [2024-04-15T14:38:11.128326 #32]  INFO -- net.ssh.connection.channel[708]: sending channel request "env"
D, [2024-04-15T14:38:11.128444 #32] DEBUG -- io[604]: queueing packet nr 41 type 98 len 60
I, [2024-04-15T14:38:11.128465 #32]  INFO -- net.ssh.connection.channel[708]: sending channel request "env"
D, [2024-04-15T14:38:11.128560 #32] DEBUG -- io[604]: queueing packet nr 42 type 98 len 44
I, [2024-04-15T14:38:11.128582 #32]  INFO -- net.ssh.connection.channel[708]: sending channel request "exec"
D, [2024-04-15T14:38:11.128682 #32] DEBUG -- io[604]: queueing packet nr 43 type 98 len 28
D, [2024-04-15T14:38:11.128755 #32] DEBUG -- io[604]: sent 448 bytes
D, [2024-04-15T14:38:11.131235 #32] DEBUG -- io[604]: read 80 bytes
D, [2024-04-15T14:38:11.131415 #32] DEBUG -- io[604]: received packet nr 167 type 99 len 12
I, [2024-04-15T14:38:11.131459 #32]  INFO -- net.ssh.connection.session[690]: channel_success: 5
D, [2024-04-15T14:38:11.132107 #32] DEBUG -- io[604]: read 288 bytes
D, [2024-04-15T14:38:11.132229 #32] DEBUG -- io[604]: received packet nr 168 type 94 len 28
I, [2024-04-15T14:38:11.132266 #32]  INFO -- net.ssh.connection.session[690]: channel_data: 5 12b
D, [2024-04-15T14:38:11.132359 #32] DEBUG -- io[604]: received packet nr 169 type 98 len 44
I, [2024-04-15T14:38:11.132396 #32]  INFO -- net.ssh.connection.session[690]: channel_request: 5 exit-status false
D, [2024-04-15T14:38:11.132474 #32] DEBUG -- io[604]: received packet nr 170 type 97 len 12
I, [2024-04-15T14:38:11.132496 #32]  INFO -- net.ssh.connection.session[690]: channel_close: 5
D, [2024-04-15T14:38:11.132607 #32] DEBUG -- io[604]: queueing packet nr 44 type 97 len 28
I, [2024-04-15T14:38:11.132627 #32]  INFO -- net.ssh.connection.session[690]: 10.20.0.10 delete channel 5 which closed locally and remotely
D, [2024-04-15T14:38:11.132681 #32] DEBUG -- io[604]: sent 96 bytes
I, [2024-04-15T14:38:11.132980 #32]  INFO -- net.ssh.connection.keepalive[71c]: sending keepalive 0
I, [2024-04-15T14:38:11.133873 #32]  INFO -- net.ssh.connection.session[690]: sending global request keepalive@openssh.com
D, [2024-04-15T14:38:11.134051 #32] DEBUG -- io[604]: queueing packet nr 45 type 80 len 44
I, [2024-04-15T14:38:11.134161 #32]  INFO -- net.ssh.connection.session[690]: closing remaining channels (0 open)
D, [2024-04-15T14:38:11.134224 #32] DEBUG -- io[604]: sent 112 bytes
D, [2024-04-15T14:38:12.057173 #32] DEBUG -- : lib/oxidized/worker.rb: 1 jobs running in parallel
D, [2024-04-15T14:38:13.058520 #32] DEBUG -- : lib/oxidized/worker.rb: 1 jobs running in parallel

When I force disconnect :

Received disconnect from 192.168.99.35 port 22:11: logout requested
Disconnected from 192.168.99.35 port 22
D, [2024-04-15T14:41:15.131725 #32] DEBUG -- : lib/oxidized/node.rb: Oxidized::SSH ran for APSiegeLABviaProxy successfully
D, [2024-04-15T14:41:15.131785 #32] DEBUG -- : lib/oxidized/job.rb: Config fetched for APSiegeLABviaProxy at 2024-04-15 14:41:15 UTC
I, [2024-04-15T14:41:15.275344 #32]  INFO -- : Configuration updated for mikrotiktest/APSiegeLABviaProxy

@romainsi romainsi mentioned this issue Apr 24, 2024
Open
@romainsi
Copy link
Author

romainsi commented May 6, 2024

To make this work temporarily, I've set up a script that disconnects my SSH user 'oxidized' on the end device every hour.
I deduce that there's a problem with the routeros template ...

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@romainsi @robertcheramy