Just found https://issues.apache.org/jira/browse/KARAF-7607, so this is a known
bug.
Paul Spencer
On 2022/11/14 15:53:03 Paul Spencer wrote:
> Karaf version 4.4.2
> Java Virtual Machine Java HotSpot(TM) 64-Bit Server VM version
> 25.172-b11
> Version 1.8.0_172
> Vendor Oracle Corporation
>
> bin/karaf works, but bin/client fails with the following error:
> DefaultOpenFuture[ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]]: Failed
> (IllegalArgumentException) to execute: Invalid UINT32 value: -1
>
> I have verified the ssh port is not in use prior to starting karaf using
> bin/start
> sparrow:apache-karaf-4.4.2 paul$ netstat -an | grep 8101
> sparrow:apache-karaf-4.4.2 paul$
>
> What am I missing?
>
> ***
> * Below is bin/client with debug logging enabled.
> ***
> sparrow:apache-karaf-4.4.2 paul$ bin/client -v -l 3
> 165 [main] DEBUG org.apache.sshd.common.util.security.SecurityUtils -
> register([BC, true, false]) not registered - enabled=null, supported={}
> 165 [main] DEBUG org.apache.sshd.common.util.security.SecurityUtils -
> register([EdDSA, true, false]) not registered - enabled=null, supported={}
> Logging in as karaf
> 260 [main] INFO org.apache.sshd.common.io.DefaultIoServiceFactoryFactory - No
> detected/configured IoServiceFactoryFactory using Nio2ServiceFactoryFactory
> 266 [main] DEBUG
> org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver -
> reloadHostConfigEntries([karaf, localhost, 8101, null,
> /Users/paul/.ssh/config]@null:{}/{}) check permissions of {}
> 272 [main] INFO
> org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver -
> resolveEffectiveResolver(karaf@localhost:8101) loaded 7 entries from
> /Users/paul/.ssh/config
> 273 [main] DEBUG
> org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver -
> resolveEffectiveHost([karaf, localhost, 8101, null, null]@null:{}/{}) => {}
> 273 [main] DEBUG org.apache.sshd.client.SshClient - connect([karaf,
> localhost, 8101]@null:{}) no overrides
> 277 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Connector - Connecting to
> localhost/127.0.0.1:8101
> 282 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Connector -
> setOption([SO_REUSEADDR, true, Property[socket-reuseaddr](Boolean]])[null]
> from property={}
> 286 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session - Creating IoSession on
> [/127.0.0.1:64796, localhost/127.0.0.1:8101, null] from null via {}
> 298 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl - Client session created:
> Nio2Session[local=/127.0.0.1:64796, remote=localhost/127.0.0.1:8101]
> 299 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> ClientUserAuthService(ClientSessionImpl[null@localhost/127.0.0.1:8101])
> client methods: [publickey, keyboard-interactive, password]
> 303 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.common.session.helpers.SessionTimeoutListener -
> sessionCreated(ClientSessionImpl[null@localhost/127.0.0.1:8101]) tracking
> 304 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> initializeProxyConnector(ClientSessionImpl[null@localhost/127.0.0.1:8101]) no
> proxy to initialize
> 305 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> sendIdentification(ClientSessionImpl[null@localhost/127.0.0.1:8101]):
> SSH-2.0-APACHE-SSHD-2.9.1
> 305 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 27 bytes
> 307 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> sendKexInit(ClientSessionImpl[null@localhost/127.0.0.1:8101]) Send
> SSH_MSG_KEXINIT
> 313 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[null@localhost/127.0.0.1:8101], 0, 20,
> SSH_MSG_KEXINIT, 1171]) packet #null sending command={}[{}] len={}
> 313 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 1184 bytes
> 314 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.SshClient -
> setupDefaultSessionIdentities(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> key identity provider override in session listener
> 315 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> doReadIdentification(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> line='SSH-2.0-APACHE-SSHD-2.9.1'
> 316 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> readIdentification(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) Server
> version string: SSH-2.0-APACHE-SSHD-2.9.1
> 316 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> handleKexInit(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> SSH_MSG_KEXINIT
> 321 [main] DEBUG org.apache.sshd.client.session.ClientSessionImpl -
> addPasswordIdentity(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> SHA256:MFiExFNQu83IUEdulJM4nQvyxg1KalAz2uFviHDn6o0
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], kex
> algorithms, ecdh-sha2-nistp521]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> server host key algorithms, rsa-sha2-512]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> encryption algorithms (client to server), aes128-ctr]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> encryption algorithms (server to client), aes128-ctr]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], mac
> algorithms (client to server), hmac-sha2-256]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], mac
> algorithms (server to client), hmac-sha2-256]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> compression algorithms (client to server), none]) Kex: null = {}
> 323 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setNegotiationResult([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> compression algorithms (server to client), none]) Kex: null = {}
> 368 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.kex.DHGClient -
> init(DHGClient[ecdh-sha2-nistp521])[ClientSessionImpl[karaf@localhost/127.0.0.1:8101]]
> Send SSH_MSG_KEXDH_INIT
> 368 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 1, 30, 30, 138])
> packet #null sending command={}[{}] len={}
> 369 [sshd-SshClient[10bdf5e5]-nio2-thread-1] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 152 bytes
> 378 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.kex.DHGClient - next([DHGClient[ecdh-sha2-nistp521],
> ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> SSH_MSG_KEXDH_REPLY])[null] process command={}
> 388 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setServerKey([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], ssh-rsa,
> SHA256:0XXt/w0gjLKT+0fMxcNYjDRbXpNpcGmXncs8rrN/hgw]) keyType=null, digest={}
> 388 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> handleKexMessage([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ecdh-sha2-nistp521, 31])[null] KEX processing complete after cmd={}
> 388 [sshd-SshClient[10bdf5e5]-nio2-thread-2] WARN
> org.apache.sshd.client.keyverifier.AcceptAllServerKeyVerifier - Server at
> localhost/127.0.0.1:8101 presented unverified RSA key:
> SHA256:0XXt/w0gjLKT+0fMxcNYjDRbXpNpcGmXncs8rrN/hgw
> 389 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> checkKeys([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], ssh-rsa,
> SHA256:0XXt/w0gjLKT+0fMxcNYjDRbXpNpcGmXncs8rrN/hgw, true]) key=null-{},
> verified={}
> 389 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> sendNewKeys(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) Send
> SSH_MSG_NEWKEYS
> 389 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> prepareNewKeys(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) session
> ID=50:78:c0:35:ea:d9:67:92:d4:05:b7:7c:ef:00:36:57:24:f2:52:27:95:42:64:2c:a4:7a:98:2f:e1:e6:92:19:94:24:7b:77:4d:26:7f:a9:b3:99:6d:fd:51:83:fa:4c:5f:9f:43:19:94:a2:3b:4a:4b:2b:3b:52:26:d9:7a:38
> 411 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 2, 21,
> SSH_MSG_NEWKEYS, 1]) packet #null sending command={}[{}] len={}
> 411 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 16 bytes
> 425 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setOutputEncoding([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> BaseCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16],
> BaseMac[HmacSHA256] - block=32/32 bytes, encrypt-then-mac=false,
> none[Deflater/-1], 134217728]): cipher null; mac {}; compression {}; blocks
> limit {}
> 426 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> handleNewKeys(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) No pending
> packets to flush at end of KEX
> 426 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> handleNewKeys(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> SSH_MSG_NEWKEYS command=SSH_MSG_NEWKEYS
> 427 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> setInputEncoding([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> BaseCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16],
> BaseMac[HmacSHA256] - block=32/32 bytes, encrypt-then-mac=false,
> none[Inflater/-1], 4294967296]): cipher null; mac {}; compression {}; blocks
> limit {}
> 427 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> sendInitialServiceRequest(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> Send SSH_MSG_SERVICE_REQUEST for ssh-userauth
> 427 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 3, 5,
> SSH_MSG_SERVICE_REQUEST, 17]) packet #null sending command={}[{}] len={}
> 427 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 80 bytes
> 428 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> auth(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])[ssh-connection] send
> SSH_MSG_USERAUTH_REQUEST for 'none'
> 428 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 4, 50,
> SSH_MSG_USERAUTH_REQUEST, 36]) packet #null sending command={}[{}] len={}
> 428 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 96 bytes
> 429 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.kex.extension.DefaultClientKexExtensionHandler -
> handleServerSignatureAlgorithms(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]):
> [ssh-rsa, rsa-sha2-256, rsa-sha2-512, [email protected],
> ecdsa-sha2-nistp256, ecdsa-sha2-nistp384, ecdsa-sha2-nistp521]
> 430 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.kex.extension.DefaultClientKexExtensionHandler -
> handleServerSignatureAlgorithms(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]):
> PubkeyAcceptedAlgorithms before: [[email protected],
> [email protected],
> [email protected], [email protected],
> [email protected], ecdsa-sha2-nistp256, ecdsa-sha2-nistp384,
> ecdsa-sha2-nistp521, [email protected], rsa-sha2-512,
> rsa-sha2-256, ssh-rsa]
> 430 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.kex.extension.DefaultClientKexExtensionHandler -
> handleServerSignatureAlgorithms(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]):
> PubkeyAcceptedAlgorithms after: [ecdsa-sha2-nistp256, ecdsa-sha2-nistp384,
> ecdsa-sha2-nistp521, [email protected], rsa-sha2-512,
> rsa-sha2-256, ssh-rsa, [email protected],
> [email protected],
> [email protected], [email protected],
> [email protected]]
> 430 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> handleServiceAccept(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> SSH_MSG_SERVICE_ACCEPT service=ssh-userauth
> 430 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> processUserAuth([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], false,
> keyboard-interactive,password,publickey]) Received SSH_MSG_USERAUTH_FAILURE -
> partial=null, methods={}
> 430 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> tryNext([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], [publickey,
> keyboard-interactive, password], [keyboard-interactive, password,
> publickey]]) starting authentication mechanisms: client=null, server={}
> 430 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> tryNext(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) attempting
> method=publickey
> 444 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.auth.pubkey.UserAuthPublicKey -
> sendAuthDataRequest([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ssh-connection, publickey, rsa-sha2-512,
> SHA256:fcF5snOtV4tmHqf3leQCyiKl5lpMeQp6wRZKA1T0ckg])[null] send
> SSH_MSG_USERAUTH_REQUEST request {} type={} - fingerprint={}
> 444 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 5, 50,
> SSH_MSG_USERAUTH_REQUEST, 339]) packet #null sending command={}[{}] len={}
> 444 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 400 bytes
> 445 [sshd-SshClient[10bdf5e5]-nio2-thread-2] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> tryNext(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) successfully
> processed initial buffer by method=publickey
> 447 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> processUserAuth([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], false,
> keyboard-interactive,password,publickey]) Received SSH_MSG_USERAUTH_FAILURE -
> partial=null, methods={}
> 447 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.auth.pubkey.UserAuthPublicKey -
> sendAuthDataRequest([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ssh-connection, rsa-sha2-512])[null] server rejected publickey authentication
> with known signature algorithm {}
> 447 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.config.keys.DefaultClientIdentitiesWatcher -
> loadKeys(/Users/paul/.ssh/id_dsa) no key loaded
> 447 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.config.keys.DefaultClientIdentitiesWatcher -
> loadKeys(/Users/paul/.ssh/id_ecdsa) no key loaded
> 447 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.config.keys.DefaultClientIdentitiesWatcher -
> loadKeys(/Users/paul/.ssh/id_ed25519) no key loaded
> 448 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.auth.pubkey.UserAuthPublicKey -
> resolveAttemptedPublicKeyIdentity(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])[ssh-connection]
> no more keys to send
> 448 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> tryNext(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) no initial request
> sent by method=publickey
> 448 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.auth.pubkey.UserAuthPublicKey -
> destroy(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])[ssh-connection]
> 448 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> tryNext(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) attempting
> method=keyboard-interactive
> 449 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.auth.keyboard.UserAuthKeyboardInteractive -
> verifyTrialsCount([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ssh-connection, SSH_MSG_USERAUTH_REQUEST, 0, 3])[null] cmd={} - {} out of {}
> 449 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.auth.keyboard.UserAuthKeyboardInteractive -
> sendAuthDataRequest([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ssh-connection, keyboard-interactive, , ])[null] send
> SSH_MSG_USERAUTH_REQUEST for {}: lang={}, methods={}
> 449 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 6, 50,
> SSH_MSG_USERAUTH_REQUEST, 60]) packet #null sending command={}[{}] len={}
> 449 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 128 bytes
> 449 [sshd-SshClient[10bdf5e5]-nio2-thread-3] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> tryNext(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) successfully
> processed initial buffer by method=keyboard-interactive
> 450 [sshd-SshClient[10bdf5e5]-nio2-thread-4] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> processUserAuth([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 60,
> keyboard-interactive]) delegate processing of null to {}
> 450 [sshd-SshClient[10bdf5e5]-nio2-thread-4] DEBUG
> org.apache.sshd.client.auth.keyboard.UserAuthKeyboardInteractive -
> processAuthDataRequest([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ssh-connection, Password authentication, , en-US, 1])[null]
> SSH_MSG_USERAUTH_INFO_REQUEST name={}, instruction={}, language={},
> num-prompts={}
> 450 [sshd-SshClient[10bdf5e5]-nio2-thread-4] DEBUG
> org.apache.sshd.client.auth.keyboard.UserAuthKeyboardInteractive -
> verifyTrialsCount([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> ssh-connection, SSH_MSG_USERAUTH_INFO_REQUEST, 1, 3])[null] cmd={} - {} out
> of {}
> 450 [sshd-SshClient[10bdf5e5]-nio2-thread-4] DEBUG
> org.apache.sshd.client.auth.keyboard.UserAuthKeyboardInteractive -
> getUserResponses(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) use
> password candidate for interaction=Password authentication
> 450 [sshd-SshClient[10bdf5e5]-nio2-thread-4] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 7, 61,
> SSH_MSG_USERAUTH_INFO_RESPONSE, 14]) packet #null sending command={}[{}]
> len={}
> 451 [sshd-SshClient[10bdf5e5]-nio2-thread-4] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 80 bytes
> 452 [sshd-SshClient[10bdf5e5]-nio2-thread-5] DEBUG
> org.apache.sshd.client.session.ClientUserAuthService -
> processUserAuth(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> SSH_MSG_USERAUTH_SUCCESS Succeeded with keyboard-interactive
> 452 [sshd-SshClient[10bdf5e5]-nio2-thread-5] DEBUG
> org.apache.sshd.client.auth.keyboard.UserAuthKeyboardInteractive -
> destroy(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])[ssh-connection]
> 453 [sshd-SshClient[10bdf5e5]-nio2-thread-5] DEBUG
> org.apache.sshd.client.session.ClientConnectionService -
> stopHeartBeat(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) no heartbeat
> to stop
> 453 [sshd-SshClient[10bdf5e5]-nio2-thread-5] DEBUG
> org.apache.sshd.client.session.ClientConnectionService -
> startHeartbeat([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], PT1M,
> [email protected]]) - started at interval=null with request={}
> 578 [main] DEBUG org.apache.sshd.client.channel.ChannelShell - init()
> service=[ClientConnectionService[ClientSessionImpl[karaf@localhost/127.0.0.1:8101]],
> ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 0] session=null id={}
> 578 [main] DEBUG org.apache.sshd.common.channel.Window -
> init([Window[client/local](ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]), 2097152,
> 2097152, 32768]) size=null, max={}, packet={}
> 578 [main] DEBUG org.apache.sshd.client.session.ClientConnectionService -
> registerChannel([ClientConnectionService[ClientSessionImpl[karaf@localhost/127.0.0.1:8101]],
> 0, true, ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]])[id=null,
> registered={}] {}
> 579 [main] DEBUG org.apache.sshd.client.session.ClientSessionImpl -
> createShellChannel([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 0,
> null]) created id=null - PTY={}
> 580 [main] DEBUG org.apache.sshd.client.channel.ChannelShell -
> open(ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) Send
> SSH_MSG_CHANNEL_OPEN - type=session
> 580 [main] DEBUG org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 8, 90,
> SSH_MSG_CHANNEL_OPEN, 24]) packet #null sending command={}[{}] len={}
> 581 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 80 bytes
> 582 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.client.session.ClientConnectionService -
> channelOpenConfirmation([ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 0, 2097152,
> 32768]) SSH_MSG_CHANNEL_OPEN_CONFIRMATION sender=null, window-size={},
> packet-size={}
> 582 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.client.channel.ChannelShell - setRecipient(ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) recipient=0
> 582 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.channel.Window -
> init([Window[client/remote](ChannelShell[id=0,
> recipient=0]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]), 2097152,
> 2097152, 32768]) size=null, max={}, packet={}
> 583 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.client.channel.ChannelShell - doOpenPty(ChannelShell[id=0,
> recipient=0]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) Send agent
> forwarding request
> 583 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.client.session.ClientSessionImpl -
> encode([ClientSessionImpl[karaf@localhost/127.0.0.1:8101], 9, 98,
> SSH_MSG_CHANNEL_REQUEST, 36]) packet #null sending command={}[{}] len={}
> 583 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> writeBuffer(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) writing 96 bytes
> 584 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.client.channel.ChannelShell - doOpenPty(ChannelShell[id=0,
> recipient=0]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) Send
> SSH_MSG_CHANNEL_REQUEST pty-req: PtyChannelConfiguration[type=xterm-256color,
> lines=47, columns=259, height=480, width=640, modes={IGNCR=0, ECHOE=1,
> VSTATUS=20, ECHOK=0, IXON=1, OPOST=1, ONOCR=0, VERASE=127, IXANY=1, VINTR=3,
> VSTART=17, INLCR=0, VEOL=-1, PARMRK=0, OCRNL=0, ICRNL=1, INPCK=0,
> VREPRINT=18, IGNPAR=0, ONLCR=1, ISTRIP=0, ISIG=1, ONLRET=0, VKILL=21,
> VEOL2=-1, VSUSP=26, VLNEXT=22, VDISCARD=15, ECHONL=0, ICANON=1, TOSTOP=0,
> IEXTEN=1, VSTOP=19, VEOF=4, VWERASE=23, NOFLSH=0, VDSUSP=25, IXOFF=0, ECHO=1,
> VQUIT=28}]
> 586 [main] DEBUG org.apache.sshd.client.SshClient -
> close(SshClient[10bdf5e5]) Closing immediately
> 587 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Connector -
> close(org.apache.sshd.common.io.nio2.Nio2Connector@5af97850) Closing
> immediately
> 589 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.channel.AbstractChannel$GracefulChannelCloseable -
> close(ChannelShell[id=0,
> recipient=0]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101])[immediately=true]
> processing
> 589 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.client.session.ClientConnectionService -
> unregisterChannel(ChannelShell[id=0,
> recipient=0]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101])
> result=ChannelShell[id=0,
> recipient=0]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]
> 589 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Session -
> close(Nio2Session[local=/127.0.0.1:64796, remote=localhost/127.0.0.1:8101])
> Closing immediately
> 590 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Session -
> doCloseImmediately(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) closing
> socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected
> local=/127.0.0.1:64796 remote=localhost/127.0.0.1:8101]
> 590 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.util.closeable.SequentialCloseable -
> doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@69716b88)
> signal close complete immediately=true
> 590 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Session -
> doCloseImmediately(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101])
> socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed
> 590 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.util.closeable.SequentialCloseable -
> doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@57f7fe58)
> signal close complete immediately=true
> 590 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Connector -
> unmapSession(id=101): Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]
> 590 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.util.closeable.SequentialCloseable -
> doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@65ec6247)
> signal close complete immediately=true
> 590 [main] DEBUG org.apache.sshd.client.session.ClientSessionImpl -
> close(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) Closing immediately
> 590 [main] DEBUG org.apache.sshd.client.session.ClientSessionImpl -
> signalAuthFailure([ClientSessionImpl[karaf@localhost/127.0.0.1:8101],
> SshException, false, false, Session is being closed]) type=null,
> signalled={}, first={}: {}
> 591 [sshd-SshClient[10bdf5e5]-nio2-thread-7] DEBUG
> org.apache.sshd.common.io.nio2.Nio2Session -
> handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101]) IoSession has been closed, stop reading
> 593 [main] DEBUG org.apache.sshd.client.session.ClientConnectionService -
> close(ClientConnectionService[ClientSessionImpl[karaf@localhost/127.0.0.1:8101]])
> Closing immediately
> 593 [main] DEBUG org.apache.sshd.client.session.ClientConnectionService -
> stopHeartBeat(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) stopping
> 593 [main] DEBUG org.apache.sshd.client.session.ClientConnectionService -
> stopHeartBeat(ClientSessionImpl[karaf@localhost/127.0.0.1:8101]) stopped
> 593 [main] DEBUG org.apache.sshd.client.session.ClientConnectionService -
> close(ClientConnectionService[ClientSessionImpl[karaf@localhost/127.0.0.1:8101]])[Immediately]
> closed
> 593 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Session -
> close(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101])[Immediately] state already Closed
> 593 [main] DEBUG org.apache.sshd.common.util.closeable.SequentialCloseable -
> doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@14ec4505)
> signal close complete immediately=true
> 593 [main] DEBUG org.apache.sshd.client.session.ClientSessionImpl -
> close(ClientSessionImpl[karaf@localhost/127.0.0.1:8101])[Immediately] closed
> 593 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Session -
> close(Nio2Session[local=/127.0.0.1:64796,
> remote=localhost/127.0.0.1:8101])[Immediately] closed
> 593 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2Connector -
> close(org.apache.sshd.common.io.nio2.Nio2Connector@5af97850)[Immediately]
> closed
> 593 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2ServiceFactory -
> close(org.apache.sshd.common.io.nio2.Nio2ServiceFactory@53ca01a2) Closing
> immediately
> 594 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2ServiceFactory - Shutdown
> group
> 594 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2ServiceFactory - Group
> successfully shut down
> 594 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2ServiceFactory - Shutdown
> executor
> 595 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2ServiceFactory - Shutdown
> complete
> 595 [main] DEBUG org.apache.sshd.common.io.nio2.Nio2ServiceFactory -
> close(org.apache.sshd.common.io.nio2.Nio2ServiceFactory@53ca01a2)[Immediately]
> closed
> 595 [main] DEBUG org.apache.sshd.common.util.closeable.SequentialCloseable -
> doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@358c99f5)
> signal close complete immediately=true
> 595 [main] DEBUG org.apache.sshd.client.SshClient -
> close(SshClient[10bdf5e5])[Immediately] closed
> org.apache.sshd.common.SshException: DefaultOpenFuture[ChannelShell[id=0,
> recipient=-1]-ClientSessionImpl[karaf@localhost/127.0.0.1:8101]]: Failed
> (IllegalArgumentException) to execute: Invalid UINT32 value: -1
> at
> org.apache.sshd.common.future.AbstractSshFuture.lambda$verifyResult$1(AbstractSshFuture.java:132)
> at
> org.apache.sshd.common.future.AbstractSshFuture.formatExceptionMessage(AbstractSshFuture.java:190)
> at
> org.apache.sshd.common.future.AbstractSshFuture.verifyResult(AbstractSshFuture.java:131)
> at
> org.apache.sshd.client.future.DefaultOpenFuture.verify(DefaultOpenFuture.java:39)
> at
> org.apache.sshd.client.future.DefaultOpenFuture.verify(DefaultOpenFuture.java:32)
> at
> org.apache.sshd.common.future.VerifiableFuture.verify(VerifiableFuture.java:43)
> at org.apache.karaf.client.Main.main(Main.java:281)
> Caused by: java.lang.IllegalArgumentException: Invalid UINT32 value: -1
> at
> org.apache.sshd.common.util.ValidateUtils.createFormattedException(ValidateUtils.java:213)
> at
> org.apache.sshd.common.util.ValidateUtils.throwIllegalArgumentException(ValidateUtils.java:179)
> at
> org.apache.sshd.common.util.ValidateUtils.checkTrue(ValidateUtils.java:162)
> at
> org.apache.sshd.common.util.buffer.BufferUtils.validateUint32Value(BufferUtils.java:703)
> at org.apache.sshd.common.util.buffer.Buffer.putUInt(Buffer.java:726)
> at
> org.apache.sshd.client.channel.PtyCapableChannelSession.lambda$doOpenPty$0(PtyCapableChannelSession.java:265)
> at java.util.HashMap.forEach(HashMap.java:1289)
> at
> org.apache.sshd.client.channel.PtyCapableChannelSession.doOpenPty(PtyCapableChannelSession.java:263)
> at
> org.apache.sshd.client.channel.ChannelShell.doOpen(ChannelShell.java:45)
> at
> org.apache.sshd.client.channel.AbstractClientChannel.handleOpenSuccess(AbstractClientChannel.java:366)
> at
> org.apache.sshd.common.session.helpers.AbstractConnectionService.channelOpenConfirmation(AbstractConnectionService.java:545)
> at
> org.apache.sshd.common.session.helpers.AbstractConnectionService.process(AbstractConnectionService.java:456)
> at
> org.apache.sshd.common.session.helpers.CurrentService.process(CurrentService.java:109)
> at
> org.apache.sshd.common.session.helpers.AbstractSession.doHandleMessage(AbstractSession.java:594)
> at
> org.apache.sshd.common.session.helpers.AbstractSession.lambda$handleMessage$0(AbstractSession.java:525)
> at
> org.apache.sshd.common.util.threads.ThreadUtils.runAsInternal(ThreadUtils.java:66)
> at
> org.apache.sshd.common.session.helpers.AbstractSession.handleMessage(AbstractSession.java:524)
> at
> org.apache.sshd.common.session.helpers.AbstractSession.decode(AbstractSession.java:1644)
> at
> org.apache.sshd.common.session.helpers.AbstractSession.messageReceived(AbstractSession.java:485)
> at
> org.apache.sshd.common.session.helpers.AbstractSessionIoHandler.messageReceived(AbstractSessionIoHandler.java:64)
> at
> org.apache.sshd.common.io.nio2.Nio2Session.handleReadCycleCompletion(Nio2Session.java:371)
> at
> org.apache.sshd.common.io.nio2.Nio2Session$1.onCompleted(Nio2Session.java:344)
> at
> org.apache.sshd.common.io.nio2.Nio2Session$1.onCompleted(Nio2Session.java:339)
> at
> org.apache.sshd.common.io.nio2.Nio2CompletionHandler.lambda$completed$0(Nio2CompletionHandler.java:38)
> at java.security.AccessController.doPrivileged(Native Method)
> at
> org.apache.sshd.common.io.nio2.Nio2CompletionHandler.completed(Nio2CompletionHandler.java:37)
> at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126)
> at sun.nio.ch.Invoker$2.run(Invoker.java:218)
> at
> sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> at java.lang.Thread.run(Thread.java:748)
> sparrow:apache-karaf-4.4.2 paul$
>
>
> Paul Spencer
