Net::SSH woes (Solaris, JRuby, Net::SSH 2.0.15)

Ruby JRuby
1

We’re on Solaris 10, using JRuby 1.3.1 (but we’ve seen the problem under
regular Ruby 1.8.7 as well), trying to communicate with another Solaris
10
machine. The first problem we ran into was an UnimplementedCipher error,
so
we restricted the ciphers we’re using to ones that are implemented in
the
Ruby Net::SSH module. Now we get a different problem.

Here’s the code we use to test our ability to connect:

require ‘rubygems’
require ‘net/ssh’
Net::SSH.start(’[HOST]’, ‘[USER]’, :verbose => :debug) do |ssh|
puts ssh.exec!(“hostname”)
end

And here’s the result:

jruby -S ssh_troubleshooting.rb
D, [2009-10-05T11:05:47.046000 #17498] DEBUG –
net.ssh.transport.session[98]: establishing connection to [HOST:PORT]
D, [2009-10-05T11:05:47.096000 #17498] DEBUG –
net.ssh.transport.session[98]: connection established
I, [2009-10-05T11:05:47.102000 #17498] INFO –
net.ssh.transport.server_version[9a]: negotiating protocol version
D, [2009-10-05T11:05:47.115000 #17498] DEBUG –
net.ssh.transport.server_version[9a]: remote is SSH-2.0-Sun_SSH_1.1.2' D, [2009-10-05T11:05:47.116000 #17498] DEBUG -- net.ssh.transport.server_version[9a]: local isSSH-2.0-Ruby/Net::SSH_2.0.15
java’
D, [2009-10-05T11:05:48.188000 #17498] DEBUG – tcpsocket[9c]: read 376
bytes
D, [2009-10-05T11:05:49.026000 #17498] DEBUG – tcpsocket[9c]: received
packet nr 0 type 20 len 372
I, [2009-10-05T11:05:49.032000 #17498] INFO –
net.ssh.transport.algorithms[9e]: got KEXINIT from server
I, [2009-10-05T11:05:49.037000 #17498] INFO –
net.ssh.transport.algorithms[9e]: sending KEXINIT
D, [2009-10-05T11:05:49.045000 #17498] DEBUG – tcpsocket[9c]: queueing
packet nr 0 type 20 len 556
D, [2009-10-05T11:05:49.047000 #17498] DEBUG – tcpsocket[9c]: sent 560
bytes
I, [2009-10-05T11:05:49.050000 #17498] INFO –
net.ssh.transport.algorithms[9e]: negotiating algorithms
D, [2009-10-05T11:05:49.066000 #17498] DEBUG –
net.ssh.transport.algorithms[9e]: negotiated:

  • kex: diffie-hellman-group-exchange-sha1
  • host_key: ssh-rsa
  • encryption_server: aes128-cbc
  • encryption_client: aes128-cbc
  • hmac_client: hmac-md5
  • hmac_server: hmac-md5
  • compression_client: zlib
  • compression_server: zlib
  • language_client:
  • language_server:
    D, [2009-10-05T11:05:49.067000 #17498] DEBUG –
    net.ssh.transport.algorithms[9e]: exchanging keys
    D, [2009-10-05T11:05:53.597000 #17498] DEBUG – tcpsocket[9c]: queueing
    packet nr 1 type 34 len 20
    D, [2009-10-05T11:05:53.599000 #17498] DEBUG – tcpsocket[9c]: sent 24
    bytes
    D, [2009-10-05T11:05:53.613000 #17498] DEBUG – tcpsocket[9c]: read 152
    bytes
    D, [2009-10-05T11:05:53.617000 #17498] DEBUG – tcpsocket[9c]: received
    packet nr 1 type 31 len 148
    D, [2009-10-05T11:05:54.174000 #17498] DEBUG – tcpsocket[9c]: queueing
    packet nr 2 type 32 len 140
    D, [2009-10-05T11:05:54.176000 #17498] DEBUG – tcpsocket[9c]: sent 144
    bytes
    D, [2009-10-05T11:05:54.212000 #17498] DEBUG – tcpsocket[9c]: read 464
    bytes
    D, [2009-10-05T11:05:54.215000 #17498] DEBUG – tcpsocket[9c]: received
    packet nr 2 type 33 len 444
    D, [2009-10-05T11:05:54.987000 #17498] DEBUG – tcpsocket[9c]: queueing
    packet nr 3 type 21 len 20
    D, [2009-10-05T11:05:54.989000 #17498] DEBUG – tcpsocket[9c]: sent 24
    bytes
    D, [2009-10-05T11:05:54.993000 #17498] DEBUG – tcpsocket[9c]: received
    packet nr 3 type 21 len 12
    D, [2009-10-05T11:05:55.009000 #17498] DEBUG –
    net.ssh.authentication.session[a6]: beginning authentication of [USER]' /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/state.rb:79:inupdate_cipher’: No message available (OpenSSL::Cipher::CipherError)
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:139:in
    enqueue_packet' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:108:insend_packet’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:217:in
    send_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:52:inauthenticate’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh.rb:183:in
    `start’
    from /path/to/test/code/ssh_troubleshooting.rb:3

We don’t know how to get past this point to see what is going on. Any
suggestions?

Thanks,

Paul B.

2

More details:
net-ssh (2.0.15)
jruby-openssl (0.5.2)

I tried reversing directions (i.e., starting from the server I had as
the destination server above, back to the server I originally started
with). These are both Solaris 10. Now I get a different error:

$ jruby ssh_troubleshooting.rb
D, [2009-10-06T11:26:00.343000 #13397] DEBUG –
net.ssh.transport.session[e8]: establishing connection to [HOST:PORT]
D, [2009-10-06T11:26:00.394000 #13397] DEBUG –
net.ssh.transport.session[e8]: connection established
I, [2009-10-06T11:26:00.397000 #13397] INFO –
net.ssh.transport.server_version[ea]: negotiating protocol version
D, [2009-10-06T11:26:00.415000 #13397] DEBUG –
net.ssh.transport.server_version[ea]: remote is SSH-2.0-Sun_SSH_1.1.2' D, [2009-10-06T11:26:00.417000 #13397] DEBUG -- net.ssh.transport.server_version[ea]: local isSSH-2.0-Ruby/Net::SSH_2.0.15 java’
D, [2009-10-06T11:26:00.665000 #13397] DEBUG – tcpsocket[ec]: read 376
bytes
D, [2009-10-06T11:26:00.754000 #13397] DEBUG – tcpsocket[ec]: received
packet nr 0 type 20 len 372
I, [2009-10-06T11:26:00.757000 #13397] INFO –
net.ssh.transport.algorithms[ee]: got KEXINIT from server
I, [2009-10-06T11:26:00.762000 #13397] INFO –
net.ssh.transport.algorithms[ee]: sending KEXINIT
D, [2009-10-06T11:26:00.767000 #13397] DEBUG – tcpsocket[ec]: queueing
packet nr 0 type 20 len 556
D, [2009-10-06T11:26:00.769000 #13397] DEBUG – tcpsocket[ec]: sent 560
bytes
I, [2009-10-06T11:26:00.770000 #13397] INFO –
net.ssh.transport.algorithms[ee]: negotiating algorithms
D, [2009-10-06T11:26:00.774000 #13397] DEBUG –
net.ssh.transport.algorithms[ee]: negotiated:

  • kex: diffie-hellman-group-exchange-sha1
  • host_key: ssh-rsa
  • encryption_server: aes128-cbc
  • encryption_client: aes128-cbc
  • hmac_client: hmac-md5
  • hmac_server: hmac-md5
  • compression_client: zlib
  • compression_server: zlib
  • language_client:
  • language_server:
    D, [2009-10-06T11:26:00.775000 #13397] DEBUG –
    net.ssh.transport.algorithms[ee]: exchanging keys
    D, [2009-10-06T11:26:06.590000 #13397] DEBUG – tcpsocket[ec]: queueing
    packet nr 1 type 34 len 20
    D, [2009-10-06T11:26:06.592000 #13397] DEBUG – tcpsocket[ec]: sent 24
    bytes
    D, [2009-10-06T11:26:06.616000 #13397] DEBUG – tcpsocket[ec]: read 152
    bytes
    D, [2009-10-06T11:26:06.619000 #13397] DEBUG – tcpsocket[ec]: received
    packet nr 1 type 31 len 148
    D, [2009-10-06T11:26:06.977000 #13397] DEBUG – tcpsocket[ec]: queueing
    packet nr 2 type 32 len 140
    D, [2009-10-06T11:26:06.979000 #13397] DEBUG – tcpsocket[ec]: sent 144
    bytes
    D, [2009-10-06T11:26:07.034000 #13397] DEBUG – tcpsocket[ec]: read 464
    bytes
    D, [2009-10-06T11:26:07.037000 #13397] DEBUG – tcpsocket[ec]: received
    packet nr 2 type 33 len 444
    D, [2009-10-06T11:26:07.576000 #13397] DEBUG – tcpsocket[ec]: queueing
    packet nr 3 type 21 len 20
    D, [2009-10-06T11:26:07.577000 #13397] DEBUG – tcpsocket[ec]: sent 24
    bytes
    D, [2009-10-06T11:26:07.580000 #13397] DEBUG – tcpsocket[ec]: received
    packet nr 3 type 21 len 12
    D, [2009-10-06T11:26:07.596000 #13397] DEBUG –
    net.ssh.authentication.session[f6]: beginning authentication of [USER]' D, [2009-10-06T11:26:07.659000 #13397] DEBUG -- tcpsocket[ec]: queueing packet nr 4 type len 28 D, [2009-10-06T11:26:07.661000 #13397] DEBUG -- tcpsocket[ec]: sent 48 bytes D, [2009-10-06T11:26:07.673000 #13397] DEBUG -- tcpsocket[ec]: read 0 bytes /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:96:innext_packet’: connection closed by remote host (Net::SSH::Disconnect)
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:86:in
    loop' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:86:innext_packet’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:169:in
    poll_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:164:inloop’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:164:in
    poll_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:149:innext_message’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:82:in
    next_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:81:inloop’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:81:in
    next_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:110:inexpect_message’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:53:in
    authenticate' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh.rb:183:instart’
    from ssh_troubleshooting.rb:3

Next, I changed the cipher to 3des-cbc. Now I get:

$ jruby ssh_troubleshooting.rb
D, [2009-10-06T11:29:56.237000 #13512] DEBUG –
net.ssh.transport.session[e8]: establishing connection to [HOST:PORT]
D, [2009-10-06T11:29:56.277000 #13512] DEBUG –
net.ssh.transport.session[e8]: connection established
I, [2009-10-06T11:29:56.280000 #13512] INFO –
net.ssh.transport.server_version[ea]: negotiating protocol version
D, [2009-10-06T11:29:56.295000 #13512] DEBUG –
net.ssh.transport.server_version[ea]: remote is SSH-2.0-Sun_SSH_1.1.2' D, [2009-10-06T11:29:56.297000 #13512] DEBUG -- net.ssh.transport.server_version[ea]: local isSSH-2.0-Ruby/Net::SSH_2.0.15 java’
D, [2009-10-06T11:29:56.548000 #13512] DEBUG – tcpsocket[ec]: read 376
bytes
D, [2009-10-06T11:29:56.633000 #13512] DEBUG – tcpsocket[ec]: received
packet nr 0 type 20 len 372
I, [2009-10-06T11:29:56.636000 #13512] INFO –
net.ssh.transport.algorithms[ee]: got KEXINIT from server
I, [2009-10-06T11:29:56.640000 #13512] INFO –
net.ssh.transport.algorithms[ee]: sending KEXINIT
D, [2009-10-06T11:29:56.646000 #13512] DEBUG – tcpsocket[ec]: queueing
packet nr 0 type 20 len 556
D, [2009-10-06T11:29:56.647000 #13512] DEBUG – tcpsocket[ec]: sent 560
bytes
I, [2009-10-06T11:29:56.649000 #13512] INFO –
net.ssh.transport.algorithms[ee]: negotiating algorithms
D, [2009-10-06T11:29:56.652000 #13512] DEBUG –
net.ssh.transport.algorithms[ee]: negotiated:

  • kex: diffie-hellman-group-exchange-sha1
  • host_key: ssh-rsa
  • encryption_server: 3des-cbc
  • encryption_client: 3des-cbc
  • hmac_client: hmac-md5
  • hmac_server: hmac-md5
  • compression_client: zlib
  • compression_server: zlib
  • language_client:
  • language_server:
    D, [2009-10-06T11:29:56.653000 #13512] DEBUG –
    net.ssh.transport.algorithms[ee]: exchanging keys
    D, [2009-10-06T11:30:02.628000 #13512] DEBUG – tcpsocket[ec]: queueing
    packet nr 1 type 34 len 20
    D, [2009-10-06T11:30:02.630000 #13512] DEBUG – tcpsocket[ec]: sent 24
    bytes
    D, [2009-10-06T11:30:02.654000 #13512] DEBUG – tcpsocket[ec]: read 152
    bytes
    D, [2009-10-06T11:30:02.658000 #13512] DEBUG – tcpsocket[ec]: received
    packet nr 1 type 31 len 148
    D, [2009-10-06T11:30:03.017000 #13512] DEBUG – tcpsocket[ec]: queueing
    packet nr 2 type 32 len 140
    D, [2009-10-06T11:30:03.019000 #13512] DEBUG – tcpsocket[ec]: sent 144
    bytes
    D, [2009-10-06T11:30:03.080000 #13512] DEBUG – tcpsocket[ec]: read 464
    bytes
    D, [2009-10-06T11:30:03.083000 #13512] DEBUG – tcpsocket[ec]: received
    packet nr 2 type 33 len 444
    D, [2009-10-06T11:30:03.622000 #13512] DEBUG – tcpsocket[ec]: queueing
    packet nr 3 type 21 len 20
    D, [2009-10-06T11:30:03.623000 #13512] DEBUG – tcpsocket[ec]: sent 24
    bytes
    D, [2009-10-06T11:30:03.626000 #13512] DEBUG – tcpsocket[ec]: received
    packet nr 3 type 21 len 12
    D, [2009-10-06T11:30:03.643000 #13512] DEBUG –
    net.ssh.authentication.session[f6]: beginning authentication of [USER]' D, [2009-10-06T11:30:03.709000 #13512] DEBUG -- tcpsocket[ec]: queueing packet nr 4 type len 20 D, [2009-10-06T11:30:03.710000 #13512] DEBUG -- tcpsocket[ec]: sent 40 bytes D, [2009-10-06T11:30:03.714000 #13512] DEBUG -- tcpsocket[ec]: read 64 bytes /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:197:inpoll_next_packet’: padding error, need 1794424714 block 8
    (Net::SSH::Exception)
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:87:in
    next_packet' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:86:inloop’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/packet_stream.rb:86:in
    next_packet' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:169:inpoll_message’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:164:in
    loop' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:164:inpoll_message’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:149:in
    next_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:82:innext_message’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:81:in
    loop' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:81:innext_message’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:110:in
    expect_message' from /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/authentication/session.rb:53:inauthenticate’
    from
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh.rb:183:in
    `start’
    from ssh_troubleshooting.rb:3

So now I have 3 different errors and no success.

3

I ran your troubleshooting script on solaris 10 => solaris 10 (SPARC)
with no issues. I used password for authentication. jruby-openssl
0.5.2, net-ssh 2.0.15.

Here’s my transcript:

-bash-3.00$ jruby -S ssh_test.rb
D, [2009-10-06T13:06:13.851000 #27423] DEBUG –
net.ssh.transport.session[b8]: establishing connection to
su-ecomdev01:22
D, [2009-10-06T13:06:13.867000 #27423] DEBUG –
net.ssh.transport.session[b8]: connection established
I, [2009-10-06T13:06:13.869000 #27423] INFO –
net.ssh.transport.server_version[ba]: negotiating protocol version
D, [2009-10-06T13:06:13.907000 #27423] DEBUG –
net.ssh.transport.server_version[ba]: remote is SSH-2.0-Sun_SSH_1.1' D, [2009-10-06T13:06:13.907000 #27423] DEBUG -- net.ssh.transport.server_version[ba]: local is SSH-2.0-Ruby/Net::SSH_2.0.15 java’
D, [2009-10-06T13:06:14.145000 #27423] DEBUG – tcpsocket[c4]: read 416
bytes
D, [2009-10-06T13:06:14.146000 #27423] DEBUG – tcpsocket[c4]:
received packet nr 0 type 20 len 412
I, [2009-10-06T13:06:14.147000 #27423] INFO –
net.ssh.transport.algorithms[c6]: got KEXINIT from server
I, [2009-10-06T13:06:14.148000 #27423] INFO –
net.ssh.transport.algorithms[c6]: sending KEXINIT
D, [2009-10-06T13:06:14.150000 #27423] DEBUG – tcpsocket[c4]:
queueing packet nr 0 type 20 len 556
D, [2009-10-06T13:06:14.150000 #27423] DEBUG – tcpsocket[c4]: sent 560
bytes
I, [2009-10-06T13:06:14.150000 #27423] INFO –
net.ssh.transport.algorithms[c6]: negotiating algorithms
D, [2009-10-06T13:06:14.151000 #27423] DEBUG –
net.ssh.transport.algorithms[c6]: negotiated:

  • kex: diffie-hellman-group-exchange-sha1
  • host_key: ssh-rsa
  • encryption_server: aes128-cbc
  • encryption_client: aes128-cbc
  • hmac_client: hmac-sha1
  • hmac_server: hmac-sha1
  • compression_client: none
  • compression_server: none
  • language_client:
  • language_server:
    D, [2009-10-06T13:06:14.152000 #27423] DEBUG –
    net.ssh.transport.algorithms[c6]: exchanging keys
    D, [2009-10-06T13:06:17.734000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 1 type 34 len 20
    D, [2009-10-06T13:06:17.734000 #27423] DEBUG – tcpsocket[c4]: sent 24
    bytes
    D, [2009-10-06T13:06:17.749000 #27423] DEBUG – tcpsocket[c4]: read 152
    bytes
    D, [2009-10-06T13:06:17.753000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 1 type 31 len 148
    D, [2009-10-06T13:06:17.808000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 2 type 32 len 140
    D, [2009-10-06T13:06:17.808000 #27423] DEBUG – tcpsocket[c4]: sent 144
    bytes
    D, [2009-10-06T13:06:17.862000 #27423] DEBUG – tcpsocket[c4]: read 464
    bytes
    D, [2009-10-06T13:06:17.868000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 2 type 33 len 444
    D, [2009-10-06T13:06:17.960000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 3 type 21 len 20
    D, [2009-10-06T13:06:17.961000 #27423] DEBUG – tcpsocket[c4]: sent 24
    bytes
    D, [2009-10-06T13:06:17.962000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 3 type 21 len 12
    D, [2009-10-06T13:06:17.968000 #27423] DEBUG –
    net.ssh.authentication.session[d0]: beginning authentication of
    `wasuser’
    D, [2009-10-06T13:06:17.982000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 4 type 5 len 28
    D, [2009-10-06T13:06:17.983000 #27423] DEBUG – tcpsocket[c4]: sent 52
    bytes
    D, [2009-10-06T13:06:18.062000 #27423] DEBUG – tcpsocket[c4]: read 52
    bytes
    D, [2009-10-06T13:06:18.064000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 4 type 6 len 28
    D, [2009-10-06T13:06:18.065000 #27423] DEBUG –
    net.ssh.authentication.session[d0]: trying publickey
    D, [2009-10-06T13:06:18.066000 #27423] DEBUG –
    net.ssh.authentication.agent[d2]: connecting to ssh-agent
    E, [2009-10-06T13:06:18.068000 #27423] ERROR –
    net.ssh.authentication.agent[d2]: could not connect to ssh-agent
    D, [2009-10-06T13:06:18.071000 #27423] DEBUG –
    net.ssh.authentication.methods.publickey[d4]: trying publickey
    (b8:f4:1b:37:ad:7d:78:ec:1f:1a:4c:ea:54:3e:ec:bf)
    D, [2009-10-06T13:06:18.077000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 5 type 50 len 220
    D, [2009-10-06T13:06:18.079000 #27423] DEBUG – tcpsocket[c4]: sent 244
    bytes
    D, [2009-10-06T13:06:18.082000 #27423] DEBUG – tcpsocket[c4]: read 116
    bytes
    D, [2009-10-06T13:06:18.084000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 5 type 51 len 92
    D, [2009-10-06T13:06:18.089000 #27423] DEBUG –
    net.ssh.authentication.session[d0]: allowed methods:
    gssapi-keyex,gssapi-with-mic,publickey,password,keyboard-interactive
    D, [2009-10-06T13:06:18.091000 #27423] DEBUG –
    net.ssh.authentication.session[d0]: trying password
    D, [2009-10-06T13:06:18.093000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 6 type 50 len 60
    D, [2009-10-06T13:06:18.096000 #27423] DEBUG – tcpsocket[c4]: sent 84
    bytes
    D, [2009-10-06T13:06:18.138000 #27423] DEBUG – tcpsocket[c4]: read 36
    bytes
    D, [2009-10-06T13:06:18.204000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 6 type 52 len 12
    D, [2009-10-06T13:06:18.205000 #27423] DEBUG –
    net.ssh.authentication.methods.password[d6]: password succeeded
    D, [2009-10-06T13:06:18.211000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 7 type 90 len 44
    D, [2009-10-06T13:06:18.213000 #27423] DEBUG – tcpsocket[c4]: sent 68
    bytes
    D, [2009-10-06T13:06:18.215000 #27423] DEBUG – tcpsocket[c4]: read 52
    bytes
    D, [2009-10-06T13:06:18.220000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 7 type 91 len 28
    I, [2009-10-06T13:06:18.239000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_open_confirmation: 0 0 0 32768
    I, [2009-10-06T13:06:18.275000 #27423] INFO –
    net.ssh.connection.channel[da]: sending channel request “exec”
    D, [2009-10-06T13:06:18.277000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 8 type 98 len 44
    D, [2009-10-06T13:06:18.279000 #27423] DEBUG – tcpsocket[c4]: sent 68
    bytes
    D, [2009-10-06T13:06:18.283000 #27423] DEBUG – tcpsocket[c4]: read 88
    bytes
    D, [2009-10-06T13:06:18.288000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 8 type 93 len 28
    I, [2009-10-06T13:06:18.289000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_window_adjust: 0 +131072
    D, [2009-10-06T13:06:18.291000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 9 type 99 len 12
    I, [2009-10-06T13:06:18.292000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_success: 0
    D, [2009-10-06T13:06:18.346000 #27423] DEBUG – tcpsocket[c4]: read 192
    bytes
    D, [2009-10-06T13:06:18.351000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 10 type 94 len 28
    I, [2009-10-06T13:06:18.354000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_data: 0 13b
    D, [2009-10-06T13:06:18.360000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 11 type 96 len 12
    I, [2009-10-06T13:06:18.361000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_eof: 0
    D, [2009-10-06T13:06:18.365000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 12 type 98 len 44
    I, [2009-10-06T13:06:18.371000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_request: 0 exit-status false
    D, [2009-10-06T13:06:18.375000 #27423] DEBUG – tcpsocket[c4]:
    received packet nr 13 type 97 len 12
    I, [2009-10-06T13:06:18.375000 #27423] INFO –
    net.ssh.connection.session[d8]: channel_close: 0
    D, [2009-10-06T13:06:18.378000 #27423] DEBUG – tcpsocket[c4]:
    queueing packet nr 9 type 97 len 28
    su-ecomdev01
    I, [2009-10-06T13:06:18.379000 #27423] INFO –
    net.ssh.connection.session[d8]: closing remaining channels (0 open)

–
Chris

On Tue, Oct 6, 2009 at 11:33 AM, Paul B. [email protected]
wrote:

net.ssh.transport.session[e8]: establishing connection to [HOST:PORT]
bytes
I, [2009-10-06T11:26:00.770000 #13397] INFO –

  • compression_server: zlib
    D, [2009-10-06T11:26:06.619000 #13397] DEBUG – tcpsocket[ec]: received
    packet nr 3 type 21 len 20
    D, [2009-10-06T11:26:07.673000 #13397] DEBUG – tcpsocket[ec]: read 0
    /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:169:in
    from
    `expect_message’
    Next, I changed the cipher to 3des-cbc. Now I get:
    D, [2009-10-06T11:29:56.297000 #13512] DEBUG –
    D, [2009-10-06T11:29:56.646000 #13512] DEBUG – tcpsocket[ec]: queueing
  • encryption_client: 3des-cbc
    D, [2009-10-06T11:30:02.630000 #13512] DEBUG – tcpsocket[ec]: sent 24
    bytes
    D, [2009-10-06T11:30:03.709000 #13512] DEBUG – tcpsocket[ec]: queueing
    next_packet' /path/to/jruby/lib/ruby/gems/1.8/gems/net-ssh-2.0.15/lib/net/ssh/transport/session.rb:164:in from authenticate’

To unsubscribe from this list, please visit:

http://xircles.codehaus.org/manage_email

To unsubscribe from this list, please visit:

http://xircles.codehaus.org/manage_email
4

I’ve had issues with Net::SSH with Jruby on Solaris in the past. I’ve
moved over to using trilead_ssh2.jar, a java based SSH client.
Trilead dropped support for it, but it looks like it has been revived
at http://kenai.com/projects/hudson/sources/trilead-ssh2/show.

Using that breaks compatibility with normal Ruby, though.

–Chris

On Tue, Oct 6, 2009 at 6:45 PM, Paul B. [email protected] wrote:

http://xircles.codehaus.org/manage_email

To unsubscribe from this list, please visit:

http://xircles.codehaus.org/manage_email
5

We’re trying to get Capistrano deployment working, so it looks like
we’re pretty tied to Net::SSH–at least, it looks like it would be a
fairly significant job to disentangle Net:SSH from Capistrano.

Chris E. wrote:

I’ve had issues with Net::SSH with Jruby on Solaris in the past. I’ve
moved over to using trilead_ssh2.jar, a java based SSH client.

6

Yep, we’re on SPARC too. I don’t quite know where to turn next to
troubleshoot this.

Paul B.

7

On Tue, Oct 6, 2009 at 9:33 AM, Paul B. [email protected] wrote:

More details:
net-ssh (2.0.15)
jruby-openssl (0.5.2)

I tried reversing directions (i.e., starting from the server I had as
the destination server above, back to the server I originally started
with). These are both Solaris 10. Now I get a different error:

You should go ahead and file a bug for all of this and we can try to
work through it together. It looks like there’s a few issues here.

  • Charlie

To unsubscribe from this list, please visit:

http://xircles.codehaus.org/manage_email
8

I’ve filed the following three bugs, which seemed to be the most
sensible way to break this down into separate issues. Please let me know
if you need more info or if I’ve entered them in the wrong place:

Net::SSH gives OpenSSL::Cipher::CipherError “No message available”
http://jira.codehaus.org/browse/JRUBY-4075

Net::SSH padding error using 3des-cbc on Solaris
http://jira.codehaus.org/browse/JRUBY-4076

Net::SSH “all authorization methods failed” Solaris → Solaris
http://jira.codehaus.org/browse/JRUBY-4077

Charles Nutter wrote:

You should go ahead and file a bug for all of this and we can try to
work through it together. It looks like there’s a few issues here.

  • Charlie
9

These look just fine, Paul, thanks. If you know anything about crypto
(and especially if you know any Java), please continue to investigate
:slight_smile: The jruby-openssl code is on github at
GitHub - jruby/jruby-openssl: JRuby's OpenSSL gem.

On Tue, Oct 13, 2009 at 8:50 PM, Paul B. [email protected]
wrote:

Net::SSH “all authorization methods failed” Solaris → Solaris

To unsubscribe from this list, please visit:

  http://xircles.codehaus.org/manage_email

To unsubscribe from this list, please visit:

http://xircles.codehaus.org/manage_email