Skip to content
New issue

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

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

Termius #364

Closed
firestrife23 opened this issue Sep 23, 2022 · 6 comments
Closed

Termius #364

firestrife23 opened this issue Sep 23, 2022 · 6 comments
Labels
protocol/ssh type/bug

Comments

@firestrife23
Copy link

I use the Terminus app for iOS and iPadOS which worked well if connected directly to local machines, but not with warpgate.

If I use the native terminal client to connect with warpgate it will work without issue.

This is the error message if I’m attempting to connect with the Terminus app.

👤 Starting a new connection to "warpgate.xxxxx.com" port "2222"
⚙️ Starting address resolution of "warpgate.xxxxx.com"
⚙️ Address resolution finished
⚙️ Connecting to "XX.XX.XX.XX" port "2222"
👤 Connection to "warpgate.xxxxx.com" established
⚙️ Starting SSH session
😨 Connection to "warpgate.xxxxx.com" closed with error: end of file

I tried to copy and paste the warpgate log but it wouldn’t let me as it kept refreshing. From what I can see it showed open and closed sessions without an error messages.
@Eugeny
Copy link
Member

Eugeny commented Sep 23, 2022

Please run Warpgate in foreground with RUST_LOG=debug warpgate run - this should produce a detailed debug log when connecting.

@firestrife23
Copy link
Author

do i set this as evironment for docker?

@Eugeny
Copy link
Member

Eugeny commented Sep 23, 2022

Yes, for Docker, just add -e RUST_LOG=debug

@DunklerPhoenix
Copy link

Happens for all ssh apps under android

@DunklerPhoenix
Copy link

DunklerPhoenix commented Sep 25, 2022 •
edited

EDIT: Real Debug log added

Warpgate Debug:

25.09.2022 22:58:09 DEBUG Connection{peer=Server}: h2::codec::framed_read: received frame=Headers { stream_id: StreamId(117), flags: (0x4: END_HEADERS) }
25.09.2022 22:58:09 DEBUG Connection{peer=Server}: h2::codec::framed_write: send frame=Headers { stream_id: StreamId(117), flags: (0x5: END_HEADERS | END_STREAM) }
25.09.2022 22:58:09 DEBUG Connection{peer=Server}: h2::codec::framed_write: send frame=Reset { stream_id: StreamId(117), error_code: CANCEL }
25.09.2022 22:58:09 DEBUG Connection{peer=Server}: h2::codec::framed_read: received frame=Data { stream_id: StreamId(117), flags: (0x1: END_STREAM) }
25.09.2022 22:58:09 DEBUG Connection{peer=Server}: h2::codec::framed_read: received frame=Headers { stream_id: StreamId(119), flags: (0x5: END_HEADERS | END_STREAM) }
25.09.2022 22:58:09 DEBUG Connection{peer=Server}: h2::codec::framed_write: send frame=Headers { stream_id: StreamId(119), flags: (0x5: END_HEADERS | END_STREAM) }
25.09.2022 22:58:20  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 594.832µs

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:20  INFO sqlx::query: INSERT INTO "sessions" ("id", …; rows affected: 1, rows returned: 0, elapsed: 1.727ms

INSERT INTO
  "sessions" ("id", "remote_address", "started", "protocol")
VALUES
  (?, ?, ?, ?)
  
25.09.2022 22:58:20  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 319.543µs

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:20  INFO sqlx::query: SELECT "sessions"."id", "sessions"."target_snapshot", "sessions"."username", …; rows affected: 0, rows returned: 1, elapsed: 432.889µs

SELECT
  "sessions"."id",
  "sessions"."target_snapshot",
  "sessions"."username",
  "sessions"."remote_address",
  "sessions"."started",
  "sessions"."ended",
  "sessions"."ticket_id",
  "sessions"."protocol"
FROM
  "sessions"
WHERE
  "sessions"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:20 DEBUG russh::ssh_read: read_ssh_id: reading    
25.09.2022 22:58:20 DEBUG russh::ssh_read: read 22    
25.09.2022 22:58:20 DEBUG russh::ssh_read: Ok("SSH-2.0-libssh_0.9.3\r\n")    
25.09.2022 22:58:20 DEBUG russh::server::kex: server kex init: [20, 248, 57, 167, 148, 192, 129, 164, 204, 119, 126, 89, 135, 65, 160, 15, 55, 0, 0, 0, 113, 99, 117, 114, 118, 101, 50, 53, 53, 49, 57, 45, 115, 104, 97, 50, 53, 54, 64, 108, 105, 98, 115, 115, 104, 46, 111, 114, 103, 44, 100, 105, 102, 102, 105, 101, 45, 104, 101, 108, 108, 109, 97, 110, 45, 103, 114, 111, 117, 112, 49, 52, 45, 115, 104, 97, 50, 53, 54, 44, 100, 105, 102, 102, 105, 101, 45, 104, 101, 108, 108, 109, 97, 110, 45, 103, 114, 111, 117, 112, 49, 52, 45, 115, 104, 97, 49, 44, 100, 105, 102, 102, 105, 101, 45, 104, 101, 108, 108, 109, 97, 110, 45, 103, 114, 111, 117, 112, 49, 45, 115, 104, 97, 49, 0, 0, 0, 45, 115, 115, 104, 45, 101, 100, 50, 53, 53, 49, 57, 44, 114, 115, 97, 45, 115, 104, 97, 50, 45, 50, 53, 54, 44, 114, 115, 97, 45, 115, 104, 97, 50, 45, 53, 49, 50, 44, 115, 115, 104, 45, 114, 115, 97, 0, 0, 0, 85, 99, 104, 97, 99, 104, 97, 50, 48, 45, 112, 111, 108, 121, 49, 51, 48, 53, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 97, 101, 115, 50, 53, 54, 45, 103, 99, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 97, 101, 115, 50, 53, 54, 45, 99, 116, 114, 44, 97, 101, 115, 49, 57, 50, 45, 99, 116, 114, 44, 97, 101, 115, 49, 50, 56, 45, 99, 116, 114, 0, 0, 0, 85, 99, 104, 97, 99, 104, 97, 50, 48, 45, 112, 111, 108, 121, 49, 51, 48, 53, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 97, 101, 115, 50, 53, 54, 45, 103, 99, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 97, 101, 115, 50, 53, 54, 45, 99, 116, 114, 44, 97, 101, 115, 49, 57, 50, 45, 99, 116, 114, 44, 97, 101, 115, 49, 50, 56, 45, 99, 116, 114, 0, 0, 0, 128, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 53, 49, 50, 45, 101, 116, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 50, 53, 54, 45, 101, 116, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 53, 49, 50, 44, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 50, 53, 54, 44, 104, 109, 97, 99, 45, 115, 104, 97, 49, 45, 101, 116, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 104, 109, 97, 99, 45, 115, 104, 97, 49, 44, 110, 111, 110, 101, 0, 0, 0, 128, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 53, 49, 50, 45, 101, 116, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 50, 53, 54, 45, 101, 116, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 53, 49, 50, 44, 104, 109, 97, 99, 45, 115, 104, 97, 50, 45, 50, 53, 54, 44, 104, 109, 97, 99, 45, 115, 104, 97, 49, 45, 101, 116, 109, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 44, 104, 109, 97, 99, 45, 115, 104, 97, 49, 44, 110, 111, 110, 101, 0, 0, 0, 26, 110, 111, 110, 101, 44, 122, 108, 105, 98, 44, 122, 108, 105, 98, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 0, 0, 0, 26, 110, 111, 110, 101, 44, 122, 108, 105, 98, 44, 122, 108, 105, 98, 64, 111, 112, 101, 110, 115, 115, 104, 46, 99, 111, 109, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]    
25.09.2022 22:58:20 DEBUG russh::cipher: writing, seqn = 0    
25.09.2022 22:58:20 DEBUG russh::cipher: padding length 9    
25.09.2022 22:58:20 DEBUG russh::cipher: packet_length 708    
25.09.2022 22:58:20 DEBUG russh::ssh_read: id 22 22    
25.09.2022 22:58:20 DEBUG russh::cipher: reading, len = [0, 0, 3, 252]    
25.09.2022 22:58:20 DEBUG russh::cipher: reading, seqn = 0    
25.09.2022 22:58:20 DEBUG russh::cipher: reading, clear len = 1020    
25.09.2022 22:58:20 DEBUG russh::cipher: read_exact 1024    
25.09.2022 22:58:20 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:20 DEBUG russh::cipher: reading, padding_length 10    
25.09.2022 22:58:20 DEBUG russh::negotiation: kex 190    
25.09.2022 22:58:20 DEBUG russh::negotiation: kex 212    
25.09.2022 22:58:20 DEBUG russh::negotiation: kex 220    
25.09.2022 22:58:20 DEBUG russh::negotiation: client_compression = Zlib    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, len = [0, 0, 0, 44]    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, seqn = 1    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, clear len = 44    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact 48    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, padding_length 6    
25.09.2022 22:58:21 DEBUG russh::kex::curve25519: server_dh    
25.09.2022 22:58:21 DEBUG russh::server::kex: server kexdhdone.exchange = Exchange { client_id: CryptoVec { p: 0x7fa7fc01c110, size: 20, capacity: 32 }, server_id: CryptoVec { p: 0x7fa7fc01c140, size: 28, capacity: 32 }, client_kex_init: CryptoVec { p: 0x7fa7fc01c830, size: 1009, capacity: 1024 }, server_kex_init: CryptoVec { p: 0x7fa7fc0027e0, size: 698, capacity: 1024 }, client_ephemeral: CryptoVec { p: 0x7fa7fc002bf0, size: 32, capacity: 32 }, server_ephemeral: CryptoVec { p: 0x7fa7fc010a40, size: 32, capacity: 32 } }    
25.09.2022 22:58:21 DEBUG russh::server::kex: exchange hash: CryptoVec { p: 0x7fa7fc00aaa0, size: 32, capacity: 32 }    
25.09.2022 22:58:21 DEBUG russh::server::kex: signing with key 0    
25.09.2022 22:58:21 DEBUG russh::server::kex: hash: CryptoVec { p: 0x7fa7fc00aaa0, size: 32, capacity: 32 }    
25.09.2022 22:58:21 DEBUG russh::server::kex: key: Ed25519 REMOVED
25.09.2022 22:58:21 DEBUG russh::cipher: writing, seqn = 1    
25.09.2022 22:58:21 DEBUG russh::cipher: padding length 8    
25.09.2022 22:58:21 DEBUG russh::cipher: packet_length 188    
25.09.2022 22:58:21 DEBUG russh::cipher: writing, seqn = 2    
25.09.2022 22:58:21 DEBUG russh::cipher: padding length 10    
25.09.2022 22:58:21 DEBUG russh::cipher: packet_length 12    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, len = [0, 0, 0, 12]    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, seqn = 2    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, clear len = 12    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact 16    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, padding_length 10    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, len = [0, 0, 0, 32]    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, seqn = 3    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, clear len = 48    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact 52    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, padding_length 0    
25.09.2022 22:58:21 DEBUG russh::server::encrypted: request: Ok("ssh-userauth")    
25.09.2022 22:58:21 DEBUG russh::cipher: writing, seqn = 3    
25.09.2022 22:58:21 DEBUG russh::cipher: padding length 14    
25.09.2022 22:58:21 DEBUG russh::cipher: packet_length 32    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, len = [0, 0, 0, 64]    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, seqn = 4    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, clear len = 80    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact 84    
25.09.2022 22:58:21 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:21 DEBUG russh::cipher: reading, padding_length 0    
25.09.2022 22:58:21 DEBUG russh::server::encrypted: name: "REMOVED:REMOVED" Ok("ssh-connection") Ok("none")    
25.09.2022 22:58:21 DEBUG russh::server::encrypted: rejecting AuthRequest { methods: PASSWORD | PUBLICKEY | KEYBOARD_INTERACTIVE, partial_success: false, current: None, rejection_count: 0 }    
25.09.2022 22:58:21 DEBUG russh::server::encrypted: packet pushed    
25.09.2022 22:58:22 DEBUG russh::cipher: writing, seqn = 4    
25.09.2022 22:58:22 DEBUG russh::cipher: padding length 18    
25.09.2022 22:58:22 DEBUG russh::cipher: packet_length 64    
25.09.2022 22:58:22 DEBUG russh::cipher: reading, len = [0, 0, 0, 80]    
25.09.2022 22:58:22 DEBUG russh::cipher: reading, seqn = 5    
25.09.2022 22:58:22 DEBUG russh::cipher: reading, clear len = 96    
25.09.2022 22:58:22 DEBUG russh::cipher: read_exact 100    
25.09.2022 22:58:22 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:22 DEBUG russh::cipher: reading, padding_length 0    
25.09.2022 22:58:22 DEBUG russh::server::encrypted: name: "REMOVED:REMOVED" Ok("ssh-connection") Ok("keyboard-interactive")    
25.09.2022 22:58:22 DEBUG russh::server::encrypted: ""    
25.09.2022 22:58:22  INFO SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::server::session: Keyboard-interactive auth as <REMOVED for REMOVED>
25.09.2022 22:58:22  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 1.173ms

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:22 DEBUG russh::cipher: writing, seqn = 5    
25.09.2022 22:58:22 DEBUG russh::cipher: padding length 13    
25.09.2022 22:58:22 DEBUG russh::cipher: packet_length 80    
25.09.2022 22:58:22  INFO sqlx::query: SELECT "users"."id", "users"."username", "users"."credentials", …; rows affected: 0, rows returned: 1, elapsed: 2.959ms

SELECT
  "users"."id",
  "users"."username",
  "users"."credentials",
  "users"."credential_policy"
FROM
  "users"
WHERE
  "users"."username" = ?
LIMIT
  ?
  
25.09.2022 22:58:22  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 653.530µs

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:22  INFO sqlx::query: INSERT INTO "log" ("id", …; rows affected: 1, rows returned: 0, elapsed: 2.100ms

INSERT INTO
  "log" (
    "id",
    "text",
    "values",
    "timestamp",
    "session_id",
    "username"
  )
VALUES
  (?, ?, ?, ?, ?, ?)
  
25.09.2022 22:58:22  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 392.538µs

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:22  INFO sqlx::query: SELECT "log"."id", "log"."text", "log"."values", …; rows affected: 0, rows returned: 1, elapsed: 196.163µs

SELECT
  "log"."id",
  "log"."text",
  "log"."values",
  "log"."timestamp",
  "log"."session_id",
  "log"."username"
FROM
  "log"
WHERE
  "log"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:28 DEBUG russh::cipher: reading, len = [0, 0, 0, 32]    
25.09.2022 22:58:28 DEBUG russh::cipher: reading, seqn = 6    
25.09.2022 22:58:28 DEBUG russh::cipher: reading, clear len = 48    
25.09.2022 22:58:28 DEBUG russh::cipher: read_exact 52    
25.09.2022 22:58:28 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:28 DEBUG russh::cipher: reading, padding_length 0    
25.09.2022 22:58:28  INFO SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::server::session: Keyboard-interactive auth as <REMOVED for REMOVED>
25.09.2022 22:58:28  INFO sqlx::query: SELECT "users"."id", "users"."username", "users"."credentials", …; rows affected: 1, rows returned: 1, elapsed: 601.159µs

SELECT
  "users"."id",
  "users"."username",
  "users"."credentials",
  "users"."credential_policy"
FROM
  "users"
WHERE
  "users"."username" = ?
LIMIT
  ?
  
25.09.2022 22:58:28 DEBUG russh::server::encrypted: rejecting AuthRequest { methods: PASSWORD | PUBLICKEY | KEYBOARD_INTERACTIVE, partial_success: false, current: Some(KeyboardInteractive { submethods: "" }), rejection_count: 1 }    
25.09.2022 22:58:28 DEBUG russh::server::encrypted: packet pushed    
25.09.2022 22:58:28  INFO sqlx::query: INSERT INTO "log" ("id", …; rows affected: 1, rows returned: 0, elapsed: 7.072ms

INSERT INTO
  "log" (
    "id",
    "text",
    "values",
    "timestamp",
    "session_id",
    "username"
  )
VALUES
  (?, ?, ?, ?, ?, ?)
  
25.09.2022 22:58:28  INFO sqlx::query: SELECT "log"."id", "log"."text", "log"."values", …; rows affected: 0, rows returned: 1, elapsed: 346.679µs

SELECT
  "log"."id",
  "log"."text",
  "log"."values",
  "log"."timestamp",
  "log"."session_id",
  "log"."username"
FROM
  "log"
WHERE
  "log"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:29 DEBUG russh::cipher: writing, seqn = 6    
25.09.2022 22:58:29 DEBUG russh::cipher: padding length 18    
25.09.2022 22:58:29 DEBUG russh::cipher: packet_length 64    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, len = [0, 0, 0, 128]    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, seqn = 7    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, clear len = 144    
25.09.2022 22:58:29 DEBUG russh::cipher: read_exact 148    
25.09.2022 22:58:29 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, padding_length 0    
25.09.2022 22:58:29 DEBUG russh::server::encrypted: name: "REMOVED:REMOVED" Ok("ssh-connection") Ok("password")    
25.09.2022 22:58:29  INFO SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::server::session: Password auth as <REMOVED for REMOVED>
25.09.2022 22:58:29  INFO sqlx::query: SELECT "users"."id", "users"."username", "users"."credentials", …; rows affected: 1, rows returned: 1, elapsed: 226.435µs

SELECT
  "users"."id",
  "users"."username",
  "users"."credentials",
  "users"."credential_policy"
FROM
  "users"
WHERE
  "users"."username" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "targets"."id", "targets"."name", "targets"."kind", …; rows affected: 0, rows returned: 1, elapsed: 131.694µs

SELECT
  "targets"."id",
  "targets"."name",
  "targets"."kind",
  "targets"."options"
FROM
  "targets"
WHERE
  "targets"."name" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 375.949µs

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "users"."id", "users"."username", "users"."credentials", …; rows affected: 0, rows returned: 1, elapsed: 349.271µs

SELECT
  "users"."id",
  "users"."username",
  "users"."credentials",
  "users"."credential_policy"
FROM
  "users"
WHERE
  "users"."username" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "roles"."id", "roles"."name" FROM …; rows affected: 0, rows returned: 1, elapsed: 2.496ms

SELECT
  "roles"."id",
  "roles"."name"
FROM
  "roles"
  INNER JOIN "target_roles" ON "target_roles"."role_id" = "roles"."id"
  INNER JOIN "targets" ON "targets"."id" = "target_roles"."target_id"
WHERE
  "targets"."id" = ?
  
25.09.2022 22:58:29  INFO sqlx::query: PRAGMA page_size = 4096; …; rows affected: 0, rows returned: 2, elapsed: 330.454µs

PRAGMA page_size = 4096;
PRAGMA locking_mode = NORMAL;
PRAGMA journal_mode = WAL;
PRAGMA foreign_keys = ON;
PRAGMA synchronous = FULL;
PRAGMA auto_vacuum = NONE;
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "roles"."id", "roles"."name" FROM …; rows affected: 0, rows returned: 1, elapsed: 554.685µs

SELECT
  "roles"."id",
  "roles"."name"
FROM
  "roles"
  INNER JOIN "user_roles" ON "user_roles"."role_id" = "roles"."id"
  INNER JOIN "users" ON "users"."id" = "user_roles"."user_id"
WHERE
  "users"."id" = ?
  
25.09.2022 22:58:29  INFO SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::server::session: Authenticated username=REMOVED
25.09.2022 22:58:29  INFO sqlx::query: INSERT INTO "log" ("id", …; rows affected: 1, rows returned: 0, elapsed: 2.102ms

INSERT INTO
  "log" (
    "id",
    "text",
    "values",
    "timestamp",
    "session_id",
    "username"
  )
VALUES
  (?, ?, ?, ?, ?, ?)
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "log"."id", "log"."text", "log"."values", …; rows affected: 1, rows returned: 1, elapsed: 98.061µs

SELECT
  "log"."id",
  "log"."text",
  "log"."values",
  "log"."timestamp",
  "log"."session_id",
  "log"."username"
FROM
  "log"
WHERE
  "log"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: UPDATE "sessions" SET "username" …; rows affected: 1, rows returned: 0, elapsed: 1.649ms

UPDATE
  "sessions"
SET
  "username" = ?
WHERE
  "sessions"."id" = ?
  
25.09.2022 22:58:29  INFO sqlx::query: INSERT INTO "log" ("id", …; rows affected: 1, rows returned: 0, elapsed: 1.780ms

INSERT INTO
  "log" (
    "id",
    "text",
    "values",
    "timestamp",
    "session_id",
    "username"
  )
VALUES
  (?, ?, ?, ?, ?, ?)
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "targets"."id", "targets"."name", "targets"."kind", …; rows affected: 1, rows returned: 3, elapsed: 118.516µs

SELECT
  "targets"."id",
  "targets"."name",
  "targets"."kind",
  "targets"."options"
FROM
  "targets"
ORDER BY
  "targets"."name" ASC
  
25.09.2022 22:58:29  INFO sqlx::query: UPDATE "sessions" SET "target_snapshot" …; rows affected: 1, rows returned: 0, elapsed: 2.841ms

UPDATE
  "sessions"
SET
  "target_snapshot" = ?
WHERE
  "sessions"."id" = ?
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "log"."id", "log"."text", "log"."values", …; rows affected: 0, rows returned: 1, elapsed: 5.775ms

SELECT
  "log"."id",
  "log"."text",
  "log"."values",
  "log"."timestamp",
  "log"."session_id",
  "log"."username"
FROM
  "log"
WHERE
  "log"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:29 DEBUG russh::cipher: writing, seqn = 7    
25.09.2022 22:58:29 DEBUG russh::cipher: padding length 14    
25.09.2022 22:58:29 DEBUG russh::cipher: packet_length 16    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, len = [0, 0, 0, 32]    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, seqn = 8    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, clear len = 48    
25.09.2022 22:58:29 DEBUG russh::cipher: read_exact 52    
25.09.2022 22:58:29 DEBUG russh::cipher: read_exact done    
25.09.2022 22:58:29 DEBUG russh::cipher: reading, padding_length 0    
25.09.2022 22:58:29 DEBUG russh::server::session: err = Err(Decompress(DecompressError(General { msg: Some("invalid stored block lengths") })))    
25.09.2022 22:58:29 DEBUG russh::server::session: disconnected    
25.09.2022 22:58:29 DEBUG warpgate_protocol_ssh::server::russh_handler: Dropped
25.09.2022 22:58:29  INFO warpgate_protocol_ssh::server::session: Closed session
25.09.2022 22:58:29 DEBUG warpgate_protocol_ssh::server::session: Dropped
25.09.2022 22:58:29 DEBUG SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::client: Abort requested
25.09.2022 22:58:29  INFO SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::client: Client session closed
25.09.2022 22:58:29  INFO SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::client: Closed connection
25.09.2022 22:58:29 DEBUG SSH{session=c392f61e-2ef2-47ae-bba6-095251f25aa5}: warpgate_protocol_ssh::client: Dropped
25.09.2022 22:58:29  INFO sqlx::query: SELECT "sessions"."id", "sessions"."target_snapshot", "sessions"."username", …; rows affected: 1, rows returned: 1, elapsed: 308.741µs

SELECT
  "sessions"."id",
  "sessions"."target_snapshot",
  "sessions"."username",
  "sessions"."remote_address",
  "sessions"."started",
  "sessions"."ended",
  "sessions"."ticket_id",
  "sessions"."protocol"
FROM
  "sessions"
WHERE
  "sessions"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: UPDATE "sessions" SET "ended" …; rows affected: 1, rows returned: 0, elapsed: 1.889ms

UPDATE
  "sessions"
SET
  "ended" = ?
WHERE
  "sessions"."id" = ?
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "sessions"."id", "sessions"."target_snapshot", "sessions"."username", …; rows affected: 1, rows returned: 1, elapsed: 216.566µs

SELECT
  "sessions"."id",
  "sessions"."target_snapshot",
  "sessions"."username",
  "sessions"."remote_address",
  "sessions"."started",
  "sessions"."ended",
  "sessions"."ticket_id",
  "sessions"."protocol"
FROM
  "sessions"
WHERE
  "sessions"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: INSERT INTO "log" ("id", …; rows affected: 1, rows returned: 0, elapsed: 3.371ms

INSERT INTO
  "log" (
    "id",
    "text",
    "values",
    "timestamp",
    "session_id",
    "username"
  )
VALUES
  (?, ?, ?, ?, ?, ?)
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "log"."id", "log"."text", "log"."values", …; rows affected: 0, rows returned: 1, elapsed: 120.105µs

SELECT
  "log"."id",
  "log"."text",
  "log"."values",
  "log"."timestamp",
  "log"."session_id",
  "log"."username"
FROM
  "log"
WHERE
  "log"."id" = ?
LIMIT
  ?
  
25.09.2022 22:58:29  INFO sqlx::query: INSERT INTO "log" ("id", …; rows affected: 1, rows returned: 0, elapsed: 7.574ms

INSERT INTO
  "log" (
    "id",
    "text",
    "values",
    "timestamp",
    "session_id",
    "username"
  )
VALUES
  (?, ?, ?, ?, ?, ?)
  
25.09.2022 22:58:29  INFO sqlx::query: SELECT "log"."id", "log"."text", "log"."values", …; rows affected: 1, rows returned: 1, elapsed: 127.346µs

SELECT
  "log"."id",
  "log"."text",
  "log"."values",
  "log"."timestamp",
  "log"."session_id",
  "log"."username"
FROM
  "log"
WHERE
  "log"."id" = ?
LIMIT
  ?

Warpgate Web:


Time | MessageLast update: 26.9.2022, 01:13:46
-- | --
26.9.2022, 00:58:29 | Closed connection
26.9.2022, 00:58:29 | Client session closed
26.9.2022, 00:58:29 | Authenticatedusername:REMOVED
26.9.2022, 00:58:29 | Password auth as <REMOVED for REMOVED>
26.9.2022, 00:58:28 | Keyboard-interactive auth as <REMOVED for REMOVED>
26.9.2022, 00:58:22 | Keyboard-interactive auth as <REMOVED for REMOVED>
  | End of the log

Android SSH Client (JuiceSSH):

ssh_socket_exception_callback: Socket exception callback: 1 (0)
ssh_socket_exception_callback: Sockert error: disconnected

@firestrife23
Copy link
Author

well you beat me to it, thank you.

@Eugeny Eugeny changed the title Terminus Termius Sep 26, 2022
@Eugeny Eugeny closed this as completed in 773bf19 Sep 26, 2022
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
protocol/ssh type/bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@Eugeny @DunklerPhoenix @firestrife23