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.

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

Pipe to Channel hangs on "Parser: IN_PACKETBEFORE (expecting 16)" #870

Closed
ad-m opened this issue Jan 28, 2020 · 11 comments
Closed

Pipe to Channel hangs on "Parser: IN_PACKETBEFORE (expecting 16)" #870

ad-m opened this issue Jan 28, 2020 · 11 comments

Comments

@ad-m
Copy link

ad-m commented Jan 28, 2020

Hello,

I'm trying to pipe tar archive via ssh to "tar" started by "exec".

When piping data to "exec" channel, the transmission hangs after about 4-5 MB (variable).

The problem occurs both when using ssh2-server and OpenSSH as a server. When performing similiar operation using openssh-client over ssh2-server I don't notice any issues: cat /tmp/a.tar | ssh $USER@$HOST tar xvf - -C /data/public. Therefore, it seems to me that the problem is on the client side.

Whene connection hangs, it only receives information about Outgoing: Writing REQUEST_FAILURE. Propably for keepalive purpose.

I tried to limit the transfer speed using stream-throotle. This did not bring any significant improvement.

I tried ssh2 0.8.7 and "github:mscdex/ssh2#b5aad43272ee08509e93e3ae3969c2ccfa1d9d84"

I use "tar" & exec because transfer of individual files via sftp was too slow.

I reviewed previous issues - I did not find any similar.

I attach log with timestamp & example source code.

Debug log

sty 28 04:56:13 DEBUG: Local ident: 'SSH-2.0-ssh2js0.4.8'
sty 28 04:56:13 DEBUG: Client: Trying **cut** on port 22 ...
sty 28 04:56:13 DEBUG: Client: Connected
sty 28 04:56:13 DEBUG: Parser: IN_INIT
sty 28 04:56:13 DEBUG: Parser: IN_GREETING
sty 28 04:56:13 DEBUG: Parser: IN_HEADER
sty 28 04:56:13 DEBUG: Remote ident: 'SSH-2.0-ssh2js0.4.7srv'
sty 28 04:56:13 DEBUG: Outgoing: Writing KEXINIT
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 8)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: pktLen:484,padLen:4,remainLen:480
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: KEXINIT
sty 28 04:56:13 DEBUG: Comparing KEXINITs ...
sty 28 04:56:13 DEBUG: (local) KEX algorithms: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group14-sha1
sty 28 04:56:13 DEBUG: (remote) KEX algorithms: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group14-sha1
sty 28 04:56:13 DEBUG: KEX algorithm: ecdh-sha2-nistp256
sty 28 04:56:13 DEBUG: (local) Host key formats: ssh-ed25519,ssh-rsa,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521
sty 28 04:56:13 DEBUG: (remote) Host key formats: ssh-rsa
sty 28 04:56:13 DEBUG: Host key format: ssh-rsa
sty 28 04:56:13 DEBUG: (local) Client->Server ciphers: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm,aes128-gcm@openssh.com,aes256-gcm,aes256-gcm@openssh.com
sty 28 04:56:13 DEBUG: (remote) Client->Server ciphers: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm,aes128-gcm@openssh.com,aes256-gcm,aes256-gcm@openssh.com
sty 28 04:56:13 DEBUG: Client->Server Cipher: aes128-ctr
sty 28 04:56:13 DEBUG: (local) Server->Client ciphers: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm,aes128-gcm@openssh.com,aes256-gcm,aes256-gcm@openssh.com
sty 28 04:56:13 DEBUG: (remote) Server->Client ciphers: aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm,aes128-gcm@openssh.com,aes256-gcm,aes256-gcm@openssh.com
sty 28 04:56:13 DEBUG: Server->Client Cipher: aes128-ctr
sty 28 04:56:13 DEBUG: (local) Client->Server HMAC algorithms: hmac-sha2-256,hmac-sha2-512,hmac-sha1
sty 28 04:56:13 DEBUG: (remote) Client->Server HMAC algorithms: hmac-sha2-256,hmac-sha2-512,hmac-sha1
sty 28 04:56:13 DEBUG: Client->Server HMAC algorithm: hmac-sha2-256
sty 28 04:56:13 DEBUG: (local) Server->Client HMAC algorithms: hmac-sha2-256,hmac-sha2-512,hmac-sha1
sty 28 04:56:13 DEBUG: (remote) Server->Client HMAC algorithms: hmac-sha2-256,hmac-sha2-512,hmac-sha1
sty 28 04:56:13 DEBUG: Server->Client HMAC algorithm: hmac-sha2-256
sty 28 04:56:13 DEBUG: (local) Client->Server compression algorithms: none
sty 28 04:56:13 DEBUG: (remote) Client->Server compression algorithms: none,zlib@openssh.com,zlib
sty 28 04:56:13 DEBUG: Client->Server compression algorithm: none
sty 28 04:56:13 DEBUG: (local) Server->Client compression algorithms: none
sty 28 04:56:13 DEBUG: (remote) Server->Client compression algorithms: none,zlib@openssh.com,zlib
sty 28 04:56:13 DEBUG: Server->Client compression algorithm: none
sty 28 04:56:13 DEBUG: Outgoing: Writing KEXECDH_INIT
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 8)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: pktLen:636,padLen:7,remainLen:632
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: KEXECDH_REPLY
sty 28 04:56:13 DEBUG: Checking host key format
sty 28 04:56:13 DEBUG: Checking signature format
sty 28 04:56:13 DEBUG: Verifying host fingerprint
sty 28 04:56:13 DEBUG: Host accepted by default (no verification)
sty 28 04:56:13 DEBUG: Verifying signature
sty 28 04:56:13 DEBUG: Outgoing: Writing NEWKEYS
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 8)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: pktLen:12,padLen:10,remainLen:8
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: NEWKEYS
sty 28 04:56:13 DEBUG: Outgoing: Writing SERVICE_REQUEST (ssh-userauth)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: pktLen:28,padLen:10,remainLen:16
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: HMAC size:32
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:13 DEBUG: Parser: Verifying MAC
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: SERVICE_ACCEPT
sty 28 04:56:13 DEBUG: Outgoing: Writing USERAUTH_REQUEST (none)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: pktLen:44,padLen:19,remainLen:32
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: HMAC size:32
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:13 DEBUG: Parser: Verifying MAC
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: USERAUTH_FAILURE
sty 28 04:56:13 DEBUG: Client: none auth failed
sty 28 04:56:13 DEBUG: Outgoing: Writing USERAUTH_REQUEST (password)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: pktLen:12,padLen:10,remainLen:0
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: HMAC size:32
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:13 DEBUG: Parser: Verifying MAC
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: USERAUTH_SUCCESS
sty 28 04:56:13 Client :: ready
sty 28 04:56:13 DEBUG: Outgoing: Writing CHANNEL_OPEN (0, session)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: pktLen:28,padLen:10,remainLen:16
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: HMAC size:32
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:13 DEBUG: Parser: Verifying MAC
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_OPEN_CONFIRMATION
sty 28 04:56:13 DEBUG: Outgoing: Writing CHANNEL_REQUEST (0, exec)
sty 28 04:56:13 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:13 DEBUG: Parser: IN_PACKET
sty 28 04:56:13 DEBUG: Parser: Decrypting
sty 28 04:56:13 DEBUG: Parser: pktLen:12,padLen:6,remainLen:0
sty 28 04:56:13 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:13 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_SUCCESS (0)
sty 28 04:56:14 Exec :: ready
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 Transferred bytes: 512
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 1024
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 1138
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 1536
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2048
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2285
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2560
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 3072
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 3584
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 4096
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 4608
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 33296
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 33792
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 34304
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 34905
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 35328
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 35840
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 71720
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 137256
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 202792
sty 28 04:56:14 Transferred bytes: 268328
sty 28 04:56:14 Transferred bytes: 333864
sty 28 04:56:14 Transferred bytes: 399400
sty 28 04:56:14 Transferred bytes: 464936
sty 28 04:56:14 Transferred bytes: 530472
sty 28 04:56:14 Transferred bytes: 596008
sty 28 04:56:14 Transferred bytes: 661544
sty 28 04:56:14 Transferred bytes: 727080
sty 28 04:56:14 Transferred bytes: 792616
sty 28 04:56:14 Transferred bytes: 858152
sty 28 04:56:14 Transferred bytes: 923688
sty 28 04:56:14 Transferred bytes: 989224
sty 28 04:56:14 Transferred bytes: 1054760
sty 28 04:56:14 Transferred bytes: 1120296
sty 28 04:56:14 Transferred bytes: 1185832
sty 28 04:56:14 Transferred bytes: 1251368
sty 28 04:56:14 Transferred bytes: 1316904
sty 28 04:56:14 Transferred bytes: 1382440
sty 28 04:56:14 Transferred bytes: 1447976
sty 28 04:56:14 Transferred bytes: 1513512
sty 28 04:56:14 Transferred bytes: 1579048
sty 28 04:56:14 Transferred bytes: 1644584
sty 28 04:56:14 Transferred bytes: 1710120
sty 28 04:56:14 Transferred bytes: 1775656
sty 28 04:56:14 Transferred bytes: 1841192
sty 28 04:56:14 Transferred bytes: 1906728
sty 28 04:56:14 Transferred bytes: 1972264
sty 28 04:56:14 Transferred bytes: 2037800
sty 28 04:56:14 Transferred bytes: 2103336
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2168872
sty 28 04:56:14 Transferred bytes: 2234408
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:18,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_WINDOW_ADJUST (0, 1054760)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:16,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: .
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:7,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: Dockerfile
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:7,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: action.yml
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:13,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: dist
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:44,padLen:17,remainLen:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: lib
sty 28 04:56:14 node_modules
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:44,padLen:16,remainLen:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: package-lock.json
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:5,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: package.json
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:4,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:14 STDOUT: dist/index.js
sty 28 04:56:14 
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2299944
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2365480
sty 28 04:56:14 Transferred bytes: 2431016
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 2496552
sty 28 04:56:14 Transferred bytes: 2562088
sty 28 04:56:14 Transferred bytes: 2627624
sty 28 04:56:14 Transferred bytes: 2693160
sty 28 04:56:14 Transferred bytes: 2758696
sty 28 04:56:14 Transferred bytes: 2824232
sty 28 04:56:14 Transferred bytes: 2889768
sty 28 04:56:14 Transferred bytes: 2955304
sty 28 04:56:14 Transferred bytes: 3020840
sty 28 04:56:14 Transferred bytes: 3086376
sty 28 04:56:14 Transferred bytes: 3151912
sty 28 04:56:14 Transferred bytes: 3217448
sty 28 04:56:14 Transferred bytes: 3282984
sty 28 04:56:14 Transferred bytes: 3348520
sty 28 04:56:14 Transferred bytes: 3414056
sty 28 04:56:14 Transferred bytes: 3479592
sty 28 04:56:14 Transferred bytes: 3545128
sty 28 04:56:14 Transferred bytes: 3610664
sty 28 04:56:14 Transferred bytes: 3676200
sty 28 04:56:14 Transferred bytes: 3741736
sty 28 04:56:14 Transferred bytes: 3807272
sty 28 04:56:14 Transferred bytes: 3872808
sty 28 04:56:14 Transferred bytes: 3938344
sty 28 04:56:14 Transferred bytes: 3964001
sty 28 04:56:14 Transferred bytes: 3964416
sty 28 04:56:14 Transferred bytes: 3964928
sty 28 04:56:14 Transferred bytes: 3967791
sty 28 04:56:14 Transferred bytes: 3968000
sty 28 04:56:14 Transferred bytes: 3968512
sty 28 04:56:14 Transferred bytes: 3970233
sty 28 04:56:14 Transferred bytes: 3970560
sty 28 04:56:14 Transferred bytes: 3971072
sty 28 04:56:14 Transferred bytes: 3971584
sty 28 04:56:14 Transferred bytes: 3972096
sty 28 04:56:14 Transferred bytes: 3972608
sty 28 04:56:14 Transferred bytes: 3973120
sty 28 04:56:14 Transferred bytes: 3973632
sty 28 04:56:14 Transferred bytes: 3974144
sty 28 04:56:14 Transferred bytes: 3974656
sty 28 04:56:14 Transferred bytes: 3975168
sty 28 04:56:14 Transferred bytes: 3975680
sty 28 04:56:14 Transferred bytes: 3976192
sty 28 04:56:14 Transferred bytes: 3976704
sty 28 04:56:14 Transferred bytes: 3977216
sty 28 04:56:14 Transferred bytes: 3977728
sty 28 04:56:14 Transferred bytes: 3978240
sty 28 04:56:14 Transferred bytes: 3978752
sty 28 04:56:14 Transferred bytes: 3979264
sty 28 04:56:14 Transferred bytes: 3979776
sty 28 04:56:14 Transferred bytes: 3980288
sty 28 04:56:14 Transferred bytes: 3980800
sty 28 04:56:14 Transferred bytes: 3981312
sty 28 04:56:14 Transferred bytes: 3981824
sty 28 04:56:14 Transferred bytes: 3982336
sty 28 04:56:14 Transferred bytes: 3982848
sty 28 04:56:14 Transferred bytes: 3983360
sty 28 04:56:14 Transferred bytes: 3983872
sty 28 04:56:14 Transferred bytes: 3984384
sty 28 04:56:14 Transferred bytes: 3984896
sty 28 04:56:14 Transferred bytes: 3985408
sty 28 04:56:14 Transferred bytes: 3985920
sty 28 04:56:14 Transferred bytes: 3986432
sty 28 04:56:14 Transferred bytes: 3986944
sty 28 04:56:14 Transferred bytes: 3987456
sty 28 04:56:14 Transferred bytes: 3987968
sty 28 04:56:14 Transferred bytes: 3988480
sty 28 04:56:14 Transferred bytes: 3988992
sty 28 04:56:14 Transferred bytes: 3989504
sty 28 04:56:14 Transferred bytes: 3990016
sty 28 04:56:14 Transferred bytes: 3990528
sty 28 04:56:14 Transferred bytes: 3991040
sty 28 04:56:14 Transferred bytes: 3991552
sty 28 04:56:14 Transferred bytes: 3992064
sty 28 04:56:14 Transferred bytes: 3992576
sty 28 04:56:14 Transferred bytes: 3993088
sty 28 04:56:14 Transferred bytes: 3993600
sty 28 04:56:14 Transferred bytes: 3994112
sty 28 04:56:14 Transferred bytes: 3994624
sty 28 04:56:14 Transferred bytes: 3995136
sty 28 04:56:14 Transferred bytes: 3995648
sty 28 04:56:14 Transferred bytes: 3996160
sty 28 04:56:14 Transferred bytes: 3996672
sty 28 04:56:14 Transferred bytes: 3997184
sty 28 04:56:14 Transferred bytes: 3997696
sty 28 04:56:14 Transferred bytes: 3998208
sty 28 04:56:14 Transferred bytes: 3998720
sty 28 04:56:14 Transferred bytes: 3999232
sty 28 04:56:14 Transferred bytes: 3999744
sty 28 04:56:14 Transferred bytes: 4000256
sty 28 04:56:14 Transferred bytes: 4000768
sty 28 04:56:14 Transferred bytes: 4001280
sty 28 04:56:14 Transferred bytes: 4001792
sty 28 04:56:14 Transferred bytes: 4002304
sty 28 04:56:14 Transferred bytes: 4002816
sty 28 04:56:14 Transferred bytes: 4003328
sty 28 04:56:14 Transferred bytes: 4003840
sty 28 04:56:14 Transferred bytes: 4004352
sty 28 04:56:14 Transferred bytes: 4004864
sty 28 04:56:14 Transferred bytes: 4005376
sty 28 04:56:14 Transferred bytes: 4005888
sty 28 04:56:14 Transferred bytes: 4006400
sty 28 04:56:14 Transferred bytes: 4006912
sty 28 04:56:14 Transferred bytes: 4007424
sty 28 04:56:14 Transferred bytes: 4007936
sty 28 04:56:14 Transferred bytes: 4008448
sty 28 04:56:14 Transferred bytes: 4008960
sty 28 04:56:14 Transferred bytes: 4009472
sty 28 04:56:14 Transferred bytes: 4009984
sty 28 04:56:14 Transferred bytes: 4010496
sty 28 04:56:14 Transferred bytes: 4011008
sty 28 04:56:14 Transferred bytes: 4011520
sty 28 04:56:14 Transferred bytes: 4012032
sty 28 04:56:14 Transferred bytes: 4012544
sty 28 04:56:14 Transferred bytes: 4013056
sty 28 04:56:14 Transferred bytes: 4013568
sty 28 04:56:14 Transferred bytes: 4014080
sty 28 04:56:14 Transferred bytes: 4014592
sty 28 04:56:14 Transferred bytes: 4015104
sty 28 04:56:14 Transferred bytes: 4015616
sty 28 04:56:14 Transferred bytes: 4016128
sty 28 04:56:14 Transferred bytes: 4016640
sty 28 04:56:14 Transferred bytes: 4017152
sty 28 04:56:14 Transferred bytes: 4017664
sty 28 04:56:14 Transferred bytes: 4018176
sty 28 04:56:14 Transferred bytes: 4018688
sty 28 04:56:14 Transferred bytes: 4019200
sty 28 04:56:14 Transferred bytes: 4019712
sty 28 04:56:14 Transferred bytes: 4020224
sty 28 04:56:14 Transferred bytes: 4020736
sty 28 04:56:14 Transferred bytes: 4021248
sty 28 04:56:14 Transferred bytes: 4021760
sty 28 04:56:14 Transferred bytes: 4022272
sty 28 04:56:14 Transferred bytes: 4022784
sty 28 04:56:14 Transferred bytes: 4023296
sty 28 04:56:14 Transferred bytes: 4023808
sty 28 04:56:14 Transferred bytes: 4024320
sty 28 04:56:14 Transferred bytes: 4024832
sty 28 04:56:14 Transferred bytes: 4025344
sty 28 04:56:14 Transferred bytes: 4026420
sty 28 04:56:14 Transferred bytes: 4026880
sty 28 04:56:14 Transferred bytes: 4027392
sty 28 04:56:14 Transferred bytes: 4028593
sty 28 04:56:14 Transferred bytes: 4028928
sty 28 04:56:14 Transferred bytes: 4029440
sty 28 04:56:14 Transferred bytes: 4029952
sty 28 04:56:14 Transferred bytes: 4031815
sty 28 04:56:14 Transferred bytes: 4032000
sty 28 04:56:14 Transferred bytes: 4032512
sty 28 04:56:14 Transferred bytes: 4033590
sty 28 04:56:14 Transferred bytes: 4034048
sty 28 04:56:14 Transferred bytes: 4034560
sty 28 04:56:14 Transferred bytes: 4042200
sty 28 04:56:14 Transferred bytes: 4042240
sty 28 04:56:14 Transferred bytes: 4042752
sty 28 04:56:14 Transferred bytes: 4044008
sty 28 04:56:14 Transferred bytes: 4044288
sty 28 04:56:14 Transferred bytes: 4044800
sty 28 04:56:14 Transferred bytes: 4044956
sty 28 04:56:14 Transferred bytes: 4045312
sty 28 04:56:14 Transferred bytes: 4045824
sty 28 04:56:14 Transferred bytes: 4046336
sty 28 04:56:14 Transferred bytes: 4048735
sty 28 04:56:14 Transferred bytes: 4048896
sty 28 04:56:14 Transferred bytes: 4049408
sty 28 04:56:14 Transferred bytes: 4050425
sty 28 04:56:14 Transferred bytes: 4050432
sty 28 04:56:14 Transferred bytes: 4050944
sty 28 04:56:14 Transferred bytes: 4051445
sty 28 04:56:14 Transferred bytes: 4051456
sty 28 04:56:14 Transferred bytes: 4051968
sty 28 04:56:14 Transferred bytes: 4053719
sty 28 04:56:14 Transferred bytes: 4054016
sty 28 04:56:14 Transferred bytes: 4054528
sty 28 04:56:14 Transferred bytes: 4055231
sty 28 04:56:14 Transferred bytes: 4055552
sty 28 04:56:14 Transferred bytes: 4056064
sty 28 04:56:14 Transferred bytes: 4056116
sty 28 04:56:14 Transferred bytes: 4056576
sty 28 04:56:14 Transferred bytes: 4057088
sty 28 04:56:14 Transferred bytes: 4058161
sty 28 04:56:14 Transferred bytes: 4058624
sty 28 04:56:14 Transferred bytes: 4059136
sty 28 04:56:14 Transferred bytes: 4060333
sty 28 04:56:14 Transferred bytes: 4060672
sty 28 04:56:14 Transferred bytes: 4061184
sty 28 04:56:14 Transferred bytes: 4061244
sty 28 04:56:14 Transferred bytes: 4061696
sty 28 04:56:14 Transferred bytes: 4062208
sty 28 04:56:14 Transferred bytes: 4062303
sty 28 04:56:14 Transferred bytes: 4062720
sty 28 04:56:14 Transferred bytes: 4063232
sty 28 04:56:14 Transferred bytes: 4065031
sty 28 04:56:14 Transferred bytes: 4065280
sty 28 04:56:14 Transferred bytes: 4065792
sty 28 04:56:14 Transferred bytes: 4066339
sty 28 04:56:14 Transferred bytes: 4066816
sty 28 04:56:14 Transferred bytes: 4067328
sty 28 04:56:14 Transferred bytes: 4070523
sty 28 04:56:14 Transferred bytes: 4070912
sty 28 04:56:14 Transferred bytes: 4071424
sty 28 04:56:14 Transferred bytes: 4073093
sty 28 04:56:14 Transferred bytes: 4073472
sty 28 04:56:14 Transferred bytes: 4073984
sty 28 04:56:14 Transferred bytes: 4078872
sty 28 04:56:14 Transferred bytes: 4097214
sty 28 04:56:14 Transferred bytes: 4097536
sty 28 04:56:14 Transferred bytes: 4098048
sty 28 04:56:14 Transferred bytes: 4099348
sty 28 04:56:14 Transferred bytes: 4099584
sty 28 04:56:14 Transferred bytes: 4100096
sty 28 04:56:14 Transferred bytes: 4111465
sty 28 04:56:14 Transferred bytes: 4111872
sty 28 04:56:14 Transferred bytes: 4112384
sty 28 04:56:14 Transferred bytes: 4126333
sty 28 04:56:14 Transferred bytes: 4126720
sty 28 04:56:14 Transferred bytes: 4127232
sty 28 04:56:14 Transferred bytes: 4129338
sty 28 04:56:14 Transferred bytes: 4129792
sty 28 04:56:14 Transferred bytes: 4130304
sty 28 04:56:14 Transferred bytes: 4132010
sty 28 04:56:14 Transferred bytes: 4132352
sty 28 04:56:14 Transferred bytes: 4132864
sty 28 04:56:14 Transferred bytes: 4133376
sty 28 04:56:14 Transferred bytes: 4136322
sty 28 04:56:14 Transferred bytes: 4136448
sty 28 04:56:14 Transferred bytes: 4136960
sty 28 04:56:14 Transferred bytes: 4138127
sty 28 04:56:14 Transferred bytes: 4138496
sty 28 04:56:14 Transferred bytes: 4139008
sty 28 04:56:14 Transferred bytes: 4139177
sty 28 04:56:14 Transferred bytes: 4139520
sty 28 04:56:14 Transferred bytes: 4140032
sty 28 04:56:14 Transferred bytes: 4141248
sty 28 04:56:14 Transferred bytes: 4141568
sty 28 04:56:14 Transferred bytes: 4142080
sty 28 04:56:14 Transferred bytes: 4153304
sty 28 04:56:14 Transferred bytes: 4153384
sty 28 04:56:14 Transferred bytes: 4153856
sty 28 04:56:14 Transferred bytes: 4154368
sty 28 04:56:14 Transferred bytes: 4163676
sty 28 04:56:14 Transferred bytes: 4164096
sty 28 04:56:14 Transferred bytes: 4164608
sty 28 04:56:14 Transferred bytes: 4166300
sty 28 04:56:14 Transferred bytes: 4166656
sty 28 04:56:14 Transferred bytes: 4167168
sty 28 04:56:14 Transferred bytes: 4167680
sty 28 04:56:14 Transferred bytes: 4167732
sty 28 04:56:14 Transferred bytes: 4168192
sty 28 04:56:14 Transferred bytes: 4168704
sty 28 04:56:14 Transferred bytes: 4169777
sty 28 04:56:14 Transferred bytes: 4170240
sty 28 04:56:14 Transferred bytes: 4170752
sty 28 04:56:14 Transferred bytes: 4171933
sty 28 04:56:14 Transferred bytes: 4172288
sty 28 04:56:14 Transferred bytes: 4172800
sty 28 04:56:14 Transferred bytes: 4172860
sty 28 04:56:14 Transferred bytes: 4173312
sty 28 04:56:14 Transferred bytes: 4173824
sty 28 04:56:14 Transferred bytes: 4173908
sty 28 04:56:14 Transferred bytes: 4174336
sty 28 04:56:14 Transferred bytes: 4174848
sty 28 04:56:14 Transferred bytes: 4176619
sty 28 04:56:14 Transferred bytes: 4176896
sty 28 04:56:14 Transferred bytes: 4177408
sty 28 04:56:14 Transferred bytes: 4178173
sty 28 04:56:14 Transferred bytes: 4178432
sty 28 04:56:14 Transferred bytes: 4178944
sty 28 04:56:14 Transferred bytes: 4179003
sty 28 04:56:14 Transferred bytes: 4179456
sty 28 04:56:14 Transferred bytes: 4179968
sty 28 04:56:14 Transferred bytes: 4183516
sty 28 04:56:14 Transferred bytes: 4183552
sty 28 04:56:14 Transferred bytes: 4184064
sty 28 04:56:14 Transferred bytes: 4185596
sty 28 04:56:14 Transferred bytes: 4185600
sty 28 04:56:14 Transferred bytes: 4186112
sty 28 04:56:14 Transferred bytes: 4187197
sty 28 04:56:14 Transferred bytes: 4187648
sty 28 04:56:14 Transferred bytes: 4188160
sty 28 04:56:14 Transferred bytes: 4192711
sty 28 04:56:14 Transferred bytes: 4192768
sty 28 04:56:14 Transferred bytes: 4193280
sty 28 04:56:14 Transferred bytes: 4193792
sty 28 04:56:14 Transferred bytes: 4195442
sty 28 04:56:14 Transferred bytes: 4195840
sty 28 04:56:14 Transferred bytes: 4196352
sty 28 04:56:14 Transferred bytes: 4196903
sty 28 04:56:14 Transferred bytes: 4197376
sty 28 04:56:14 Transferred bytes: 4197888
sty 28 04:56:14 Transferred bytes: 4209228
sty 28 04:56:14 Transferred bytes: 4209664
sty 28 04:56:14 Transferred bytes: 4210176
sty 28 04:56:14 Transferred bytes: 4211274
sty 28 04:56:14 Transferred bytes: 4211712
sty 28 04:56:14 Transferred bytes: 4212224
sty 28 04:56:14 Transferred bytes: 4225007
sty 28 04:56:14 Transferred bytes: 4225024
sty 28 04:56:14 Transferred bytes: 4225536
sty 28 04:56:14 Transferred bytes: 4236736
sty 28 04:56:14 Transferred bytes: 4253385
sty 28 04:56:14 Transferred bytes: 4253696
sty 28 04:56:14 Transferred bytes: 4254208
sty 28 04:56:14 Transferred bytes: 4256080
sty 28 04:56:14 Transferred bytes: 4256256
sty 28 04:56:14 Transferred bytes: 4256768
sty 28 04:56:14 Transferred bytes: 4257280
sty 28 04:56:14 Transferred bytes: 4258773
sty 28 04:56:14 Transferred bytes: 4258816
sty 28 04:56:14 Transferred bytes: 4259328
sty 28 04:56:14 Transferred bytes: 4260456
sty 28 04:56:14 Transferred bytes: 4260864
sty 28 04:56:14 Transferred bytes: 4261376
sty 28 04:56:14 Transferred bytes: 4262822
sty 28 04:56:14 Transferred bytes: 4262912
sty 28 04:56:14 Transferred bytes: 4263424
sty 28 04:56:14 Transferred bytes: 4266892
sty 28 04:56:14 Transferred bytes: 4267008
sty 28 04:56:14 Transferred bytes: 4267520
sty 28 04:56:14 Transferred bytes: 4269026
sty 28 04:56:14 Transferred bytes: 4269056
sty 28 04:56:14 Transferred bytes: 4269568
sty 28 04:56:14 Transferred bytes: 4270648
sty 28 04:56:14 Transferred bytes: 4271104
sty 28 04:56:14 Transferred bytes: 4271616
sty 28 04:56:14 Transferred bytes: 4281669
sty 28 04:56:14 Transferred bytes: 4281856
sty 28 04:56:14 Transferred bytes: 4282368
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 Transferred bytes: 4283818
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Parser: IN_PACKET
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: pktLen:28,padLen:18,remainLen:16
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:14 DEBUG: Parser: Decrypting
sty 28 04:56:14 DEBUG: Parser: HMAC size:32
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:14 DEBUG: Parser: Verifying MAC
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_WINDOW_ADJUST (0, 1048576)
sty 28 04:56:14 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:14 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Parser: IN_PACKET
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: pktLen:28,padLen:18,remainLen:16
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: HMAC size:32
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:15 DEBUG: Parser: Verifying MAC
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_WINDOW_ADJUST (0, 1048576)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Outgoing: Writing CHANNEL_DATA (0)
sty 28 04:56:15 DEBUG: Parser: IN_PACKET
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: pktLen:60,padLen:5,remainLen:48
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: HMAC size:32
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:15 DEBUG: Parser: Verifying MAC
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:15 STDOUT: lib/index.js
sty 28 04:56:15 lib/sshTar.js
sty 28 04:56:15 node_modules/.bin
sty 28 04:56:15 
sty 28 04:56:15 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:15 DEBUG: Parser: IN_PACKET
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: pktLen:108,padLen:16,remainLen:96
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: HMAC size:32
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:15 DEBUG: Parser: Verifying MAC
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:15 STDOUT: node_modules/@actions
sty 28 04:56:15 node_modules/@octokit
sty 28 04:56:15 node_modules/@types
sty 28 04:56:15 node_modules/asn1
sty 28 04:56:15 
sty 28 04:56:15 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:15 DEBUG: Parser: IN_PACKET
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: pktLen:460,padLen:7,remainLen:448
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: HMAC size:32
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:15 DEBUG: Parser: Verifying MAC
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:15 STDOUT: node_modules/asynckit
sty 28 04:56:15 node_modules/atob-lite
sty 28 04:56:15 node_modules/bcrypt-pbkdf
sty 28 04:56:15 node_modules/before-after-hook
sty 28 04:56:15 node_modules/bl
sty 28 04:56:15 node_modules/btoa-lite
sty 28 04:56:15 node_modules/chownr
sty 28 04:56:15 node_modules/combined-stream
sty 28 04:56:15 node_modules/commander
sty 28 04:56:15 node_modules/component-emitter
sty 28 04:56:15 node_modules/cookiejar
sty 28 04:56:15 node_modules/cross-spawn
sty 28 04:56:15 node_modules/debug
sty 28 04:56:15 node_modules/delayed-stream
sty 28 04:56:15 node_modules/deprecation
sty 28 04:56:15 node_modules/end-of-stream
sty 28 04:56:15 node_modules/execa
sty 28 04:56:15 node_modules/fast-safe-stringify
sty 28 04:56:15 
sty 28 04:56:15 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:15 DEBUG: Parser: IN_PACKET
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: pktLen:76,padLen:19,remainLen:64
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: HMAC size:32
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:15 DEBUG: Parser: Verifying MAC
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:15 STDOUT: node_modules/form-data
sty 28 04:56:15 node_modules/formidable
sty 28 04:56:15 
sty 28 04:56:15 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:15 DEBUG: Parser: IN_PACKET
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: pktLen:492,padLen:15,remainLen:480
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:15 DEBUG: Parser: Decrypting
sty 28 04:56:15 DEBUG: Parser: HMAC size:32
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:15 DEBUG: Parser: Verifying MAC
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:15 DEBUG: Parser: IN_PACKETDATAAFTER, packet: CHANNEL_DATA (0)
sty 28 04:56:15 STDOUT: node_modules/fs-constants
sty 28 04:56:15 node_modules/fs-minipass
sty 28 04:56:15 node_modules/get-stream
sty 28 04:56:15 node_modules/graceful-fs
sty 28 04:56:15 node_modules/hyperone-client
sty 28 04:56:15 node_modules/inherits
sty 28 04:56:15 node_modules/is-plain-object
sty 28 04:56:15 node_modules/is-stream
sty 28 04:56:15 node_modules/isexe
sty 28 04:56:15 node_modules/isobject
sty 28 04:56:15 node_modules/klaw
sty 28 04:56:15 node_modules/limiter
sty 28 04:56:15 node_modules/lodash.get
sty 28 04:56:15 node_modules/lodash.set
sty 28 04:56:15 node_modules/lodash.uniq
sty 28 04:56:15 node_modules/macos-release
sty 28 04:56:15 node_modules/methods
sty 28 04:56:15 node_modules/mime
sty 28 04:56:15 node_modules/mime-db
sty 28 04:56:15 node_modules/mime-types
sty 28 04:56:15 
sty 28 04:56:15 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:30 DEBUG: Parser: IN_PACKET
sty 28 04:56:30 DEBUG: Parser: Decrypting
sty 28 04:56:30 DEBUG: Parser: pktLen:44,padLen:16,remainLen:32
sty 28 04:56:30 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:30 DEBUG: Parser: Decrypting
sty 28 04:56:30 DEBUG: Parser: HMAC size:32
sty 28 04:56:30 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:30 DEBUG: Parser: Verifying MAC
sty 28 04:56:30 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:30 DEBUG: Parser: IN_PACKETDATAAFTER, packet: GLOBAL_REQUEST (keepalive@openssh.com)
sty 28 04:56:30 DEBUG: Outgoing: Writing REQUEST_FAILURE
sty 28 04:56:30 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:56:46 DEBUG: Parser: IN_PACKET
sty 28 04:56:46 DEBUG: Parser: Decrypting
sty 28 04:56:46 DEBUG: Parser: pktLen:44,padLen:16,remainLen:32
sty 28 04:56:46 DEBUG: Parser: IN_PACKETDATA
sty 28 04:56:46 DEBUG: Parser: Decrypting
sty 28 04:56:46 DEBUG: Parser: HMAC size:32
sty 28 04:56:46 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:56:46 DEBUG: Parser: Verifying MAC
sty 28 04:56:46 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:56:46 DEBUG: Parser: IN_PACKETDATAAFTER, packet: GLOBAL_REQUEST (keepalive@openssh.com)
sty 28 04:56:46 DEBUG: Outgoing: Writing REQUEST_FAILURE
sty 28 04:56:46 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)
sty 28 04:57:02 DEBUG: Parser: IN_PACKET
sty 28 04:57:02 DEBUG: Parser: Decrypting
sty 28 04:57:02 DEBUG: Parser: pktLen:44,padLen:16,remainLen:32
sty 28 04:57:02 DEBUG: Parser: IN_PACKETDATA
sty 28 04:57:02 DEBUG: Parser: Decrypting
sty 28 04:57:02 DEBUG: Parser: HMAC size:32
sty 28 04:57:02 DEBUG: Parser: IN_PACKETDATAVERIFY
sty 28 04:57:02 DEBUG: Parser: Verifying MAC
sty 28 04:57:02 DEBUG: Parser: IN_PACKETDATAVERIFY (Valid HMAC)
sty 28 04:57:02 DEBUG: Parser: IN_PACKETDATAAFTER, packet: GLOBAL_REQUEST (keepalive@openssh.com)
sty 28 04:57:02 DEBUG: Outgoing: Writing REQUEST_FAILURE
sty 28 04:57:02 DEBUG: Parser: IN_PACKETBEFORE (expecting 16)

Example code

const Client = require('ssh2').Client;
const tar = require('tar-fs');
const {Transform} = require('stream')
module.exports = (connInfo, srcDir, destDir) => new Promise((resolve, reject) => {
    const archiveStream = tar.pack(srcDir);
    const conn = new Client();
    conn.on('ready', function () {
        console.log('Client :: ready');
        conn.exec(`tar xvf - -C ${destDir}`, function (err, stream) {
            if (err) return reject(err);
            console.log('Exec :: ready');
            let count = 0;
            stream.on('error', (err) => {
                conn.end();
                return reject(err);
            }).on('close', function (code, signal) {
                console.log('Stream :: close :: code: ' + code + ', signal: ' + signal);
                return resolve(conn.end());
            }).on('data', function (data) {
                console.log('STDOUT: ' + data);
            }).stderr.on('data', function (data) {
                console.log('STDERR: ' + data);
            });
            archiveStream.pipe(new Transform({
                transform(chunk, encoding, callback) {
                    count += chunk.length;
                    console.log('Transferred bytes:', count)
                    return callback(null, chunk);
                }
            })).pipe(stream);
        });
    }).on('error', reject)
        .connect(connInfo);
});

if (require.main === module) {
    module.exports({
        // host: website.fqdn,
        host: process.env.HOST,
        username: process.argv[2],
        password: process.argv[3],
        debug: console.log,
        compress: false,
    }, './', '/data/public/')
        .then(console.log)
        .catch(console.error);
}

@mscdex
Copy link
Owner

mscdex commented Jan 29, 2020

Can you also provide both the ssh2 client debug output when connecting to the OpenSSH server and the ssh2 server debug output?

@mscdex
Copy link
Owner

mscdex commented Jan 29, 2020

Also, what node version are you using?

@ad-m
Copy link
Author

ad-m commented Jan 29, 2020

Can you also provide both the ssh2 client debug output when connecting to the OpenSSH server and the ssh2 server debug output?

Of course. Logs available at https://gist.github.com/ad-m/e360f5b21ac4d88780b861e59e41ab8c .

Also, what node version are you using?

I used Node v12.14.1 so far. I tested multiple version of Node. It works on 10.181, 12.1.0 and fail for 12.10.0, 12.11.0, 12.14.1 and 13.7.0.

I will provide report for working version 12.x to assess which version of Node the problem first appeared.

@ad-m
Copy link
Author

ad-m commented Jan 29, 2020

The report was generated faster than I was expected. Here is data:

PASS for 10.18.1
PASS for 12.0.0
PASS for 12.1.0
FAIL for 12.10.0
FAIL for 12.11.0
FAIL for 12.11.1
FAIL for 12.12.0
FAIL for 12.13.0
FAIL for 12.13.1
FAIL for 12.14.0
FAIL for 12.14.1
PASS for 12.2.0
PASS for 12.3.0
PASS for 12.3.1
PASS for 12.4.0
PASS for 12.5.0
PASS for 12.6.0
PASS for 12.7.0
PASS for 12.8.0
PASS for 12.8.1
FAIL for 12.9.0
FAIL for 12.9.1
FAIL for 13.0.0
FAIL for 13.0.1
FAIL for 13.1.0
FAIL for 13.2.0
FAIL for 13.3.0
FAIL for 13.4.0
FAIL for 13.5.0
FAIL for 13.6.0
FAIL for 13.7.0

First failing version is 12.9.0. Changelog mention few changes in area of streams:

Are container images for the "nightly" NodeJS releases available somewhere or do I need to rely on official, compiled files (https://nodejs.org/download/nightly/) and develop them myself? Unless the information provided is sufficient to diagnose the problem?

@mscdex
Copy link
Owner

mscdex commented Jan 29, 2020

I don't have experience with containers so I can't answer about that.

If you're feeling particularly up to it, you could narrow down which node commit caused the failures by doing a git bisect between node v12.8.1 and node v12.9.0 in the node repository. Otherwise I'd need a way to duplicate the issue on my end so I can do the bisect. Is the issue still reproducible without the tar-fs module?

@ad-m
Copy link
Author

ad-m commented Jan 29, 2020

If you're feeling particularly up to it, you could narrow down which node commit caused the failures by doing a git bisect between node v12.8.1 and node v12.9.0 in the node repository.

It takes a long time to build Node. I will try to use nightly releases first to limit the number of commits in which the bug may have been introduced.

Otherwise I'd need a way to duplicate the issue on my end so I can do the bisect.

Is the information provided enough to reproduce the problem? I provided client & server code.

Is the issue still reproducible without the tar-fs module?

Yes.

@smarusa
Copy link

smarusa commented May 19, 2020

@mscdex
I am running into this issue as well. I was able to run the git bisect 12.8.1->12.9.0 on our issue as suggested and the following commit was the culprit.

[[e2a2a3f4dd]] - stream: use lazy registration for drain for fast destinations (Robert Nagy) #29095

ssh2 - 0.8.9
node - 12.9.0+

We are also using an archive library stream piped to the sftp stream.
https://github.com/archiverjs/node-archiver
The issue seems to be a stall on draining the data. The issue is intermittent for us on every sftp server, but we can get it to fail on the slower SFTP server connections nearly all of the time.

I created an example case only using ssh2 lib. I had to play around with the highWaterMark on the input stream to cause the failure. (WriteStream.prototype.writev?). Failure on more recent nodes versions is a hang/stall. Works on node 12.8.1 and earlier.

Example Code

const fs = require('fs');
const path = require('path');
const { Client } = require('ssh2');

const fileSize = 1024 * 1024;
const zipName = 'test.zip';
const fileName = 'test.txt';
const destPath = '/';

const filePath = path.join(__dirname, fileName);

console.log(`Creating file... ${fileName}`);
fs.writeFileSync(filePath, 'a'.repeat(fileSize));

function runSftpTest() {
  const conn = new Client();
  conn.on('ready', () => {
    console.log('Sftp connected...');
    const fileStream = fs.createReadStream(filePath, { highWaterMark: 16 });
    conn.sftp(async (err, sftp) => {
      if (err) throw err;
      const sftpStream = sftp.createWriteStream(path.join(destPath, zipName));
      sftpStream.once('finish', () => {
        console.log('Sftp finished upload - SUCCESS');
        conn.end();
      });
      fileStream.pipe(sftpStream);
    });
  }).connect({
    host: 'host',
    port: 22,
    username: 'user',
    password: 'pass',
    // timing bug? so enabling debug may cause it to work
    // debug: console.log,
  });
}

const readStream = fs.createReadStream(filePath, { highWaterMark: 16 });
const writeStream = fs.createWriteStream(`${filePath}.new`);
writeStream.once('finish', () => {
  console.log(`File stream successful... created ${fileName}.new`);
  runSftpTest();
});
readStream.pipe(writeStream);

@ViljarVoidula
Copy link

ViljarVoidula commented Sep 14, 2020

+1
node: v12.18.3

@smarusa
Copy link

smarusa commented Sep 18, 2020

@mscdex Is this the same or similar issue to #908?

@mscdex
Copy link
Owner

mscdex commented Sep 18, 2020

@smarusa I have no idea and probably won't have time to investigate unfortunately. I'm currently in the middle of a rewrite which may remedy many of these issues though.

@mscdex
Copy link
Owner

mscdex commented May 29, 2021

If this is still an issue with ssh2 v1.0.0 and isn't a node.js core issue, let me know and I will reopen this.

@mscdex mscdex closed this as completed May 29, 2021
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

4 participants