Bug#899060: FTBFS: even if tests pass, dh_auto_test fails

[Antoine Beaupré]

On 2018-09-26 19:52:39, Antoine Beaupre wrote:

[...]

> For thre record, the above failed testsuite log fails on:
>
> 2018/05/18 14:25:50 socat[27609] E connect(5, AF=1 
> "/<<PKGBUILDDIR>>/tests/tmp/ms.cco/ssh-socket", 74): Connection refused
> ssh_exchange_identification: Connection closed by remote host
>
> ./tests/basic: line 81: kill: (27487) - No such process
> ##### ssh connection test FAILED. returned: 255, expected: 0
> FAILED!
> ### removing temp dir...
>
> That is strange in itself, but the "passed" one is much stranger, as
> mentioned.

I can reproduce the latter (the "failed" test) reliably. It fails at
different places, however. Here are two logfiles, for example, which
both fail, but in different tests, with the same error message:


MONKEYSPHERE_TEST_NO_EXAMINE=true ./tests/keytrans
##################################################
### generating openpgp key...
##################################################
### retrieving key timestamp...
##################################################
### exporting key to ssh file...
##################################################
### reconvert key, and compare to key in gpg keyring...
conversions look good!
Now working with key C5BE5E5ACA72DDE9 at time 1538012950
/home/anarcat/src/monkeysphere/tests/tmp/ms.enn/pubring.kbx
-----------------------------------------------------------
pub   rsa1024 2018-09-27 [SC]
      991716AD63038428521DFDC8C5BE5E5ACA72DDE9
uid          [  ultime ] testtest

##################################################
### test User ID addition...
/home/anarcat/src/monkeysphere/tests/tmp/ms.enn/pubring.kbx
-----------------------------------------------------------
pub   rsa1024 2018-09-27 [SC]
      991716AD63038428521DFDC8C5BE5E5ACA72DDE9
uid          [  ultime ] monkeymonkey
uid          [  ultime ] testtest

##################################################
### sleeping to avoid test suite breakage on fast
### processors (see http://bugs.debian.org/591118)
##################################################
### test User ID revocation ... 
/home/anarcat/src/monkeysphere/tests/tmp/ms.enn/pubring.kbx
-----------------------------------------------------------
pub   rsa1024 2018-09-27 [SC]
      991716AD63038428521DFDC8C5BE5E5ACA72DDE9
uid          [  ultime ] monkeymonkey
uid          [ révoquée] testtest

##################################################
### test working with two primary keys ... 
Generating public/private rsa key pair.
Your identification has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.enn/newkey.
Your public key has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.enn/newkey.pub.
The key fingerprint is:
SHA256:G7T9t1MGVrBMRU8FoDUqV1vQVYpRO5d0DNJwiM7YRbY anarcat@curie
The key's randomart image is:
+---[RSA 1024]----+
|           o%OOX@|
|          .*o%+=*|
|        o=+.E *oo|
|       ..=+   oo |
|        S .  . . |
|         o .    o|
|        .   . .o |
|             ... |
|              .. |
+----[SHA256]-----+
test: diff expected gpg list output
test: diff expected keytrans listfpr output
##################################################
 Monkeysphere keytrans test completed successfully!
##################################################
### removing temp dir...
MONKEYSPHERE_TEST_NO_EXAMINE=true ./tests/basic
Permissions on temporary directory 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn' are OK for permissions checks.

##################################################
### configuring testuser home...
2 blocs
gpg: le trousseau local « 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.gnupg/pubring.kbx » a 
été créé
gpg: key E00B5EEEBA79B482: 1 signature not checked due to a missing key
gpg: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.gnupg/trustdb.gpg : 
base de confiance créée
gpg: clef E00B5EEEBA79B482 : clef publique « Monkeysphere Test Suite Test User 
(DO NOT USE!!!) <testu...@example.net> » importée
gpg: clef E00B5EEEBA79B482 : clef secrète importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg:           clefs secrètes lues : 1
gpg:      clefs secrètes importées : 1
gpg: aucune clef de confiance ultime n'a été trouvée
gpg: key E00B5EEEBA79B482: 1 signature not checked due to a missing key
gpg: clef E00B5EEEBA79B482 : « Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net> » n'est pas modifiée
gpg: clef 36FF78B37005D3BE : clef publique « Monkeysphere Test Suite Fake 
Administrative User (DO NOT USE!!!) <fakead...@example.net> » importée
gpg:       Quantité totale traitée : 2
gpg:                     importées : 1
gpg:                 non modifiées : 1
gpg: aucune clef de confiance ultime n'a été trouvée
gpg: inserting ownertrust of 6
gpg: inserting ownertrust of 5

##################################################
### configuring admin home...
gpg: le trousseau local « 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/admin/.gnupg/pubring.kbx » a 
été créé
gpg: /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/admin/.gnupg/trustdb.gpg : 
base de confiance créée
gpg: clef 36FF78B37005D3BE : clef publique « Monkeysphere Test Suite Fake 
Administrative User (DO NOT USE!!!) <fakead...@example.net> » importée
gpg: clef 36FF78B37005D3BE : clef secrète importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg:           clefs secrètes lues : 1
gpg:      clefs secrètes importées : 1
gpg: clef 36FF78B37005D3BE : « Monkeysphere Test Suite Fake Administrative User 
(DO NOT USE!!!) <fakead...@example.net> » n'est pas modifiée
gpg:       Quantité totale traitée : 1
gpg:                 non modifiées : 1
gpg: inserting ownertrust of 6

##################################################
### configuring sshd...

##################################################
### import host key...
Generating public/private rsa key pair.
Your identification has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh_host_rsa_key.
Your public key has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh_host_rsa_key.pub.
The key fingerprint is:
SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU anarcat@curie
The key's randomart image is:
+---[RSA 1024]----+
|           o..E+O|
|          ..oBo**|
|            +.BoB|
|       o . o *.oB|
|      o S . o.+==|
|       * .   ..+*|
|        o   .  .=|
|           . . ..|
|            . .  |
+----[SHA256]-----+
using keyserver: example.org
ms: importing key from file 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh_host_rsa_key'...
ms: updating openpgp public key file 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/host_keys.pub.pgp'...
ms: host key imported:
pub   rsa1024 2018-09-27 [CA]
      F069340F3B65C12702643DAE7399CCDAF66E7F62
uid          [ inconnue] ssh://testhost.example
OpenPGP fingerprint: F069340F3B65C12702643DAE7399CCDAF66E7F62
ssh fingerprint: 1024 SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU (RSA)

##################################################
### getting host key fingerprint...
using keyserver: example.org
ms: listing primary fingerprints from 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/host_keys.pub.pgp
ms: obtained the following fingerprints: 
F069340F3B65C12702643DAE7399CCDAF66E7F62
ms: publishing all keys
ms: using keys: F069340F3B65C12702643DAE7399CCDAF66E7F62
ms: invoking show_key F069340F3B65C12702643DAE7399CCDAF66E7F62
F069340F3B65C12702643DAE7399CCDAF66E7F62

##################################################
### setting host key expiration...
using keyserver: example.org
ms: extending without prompting.
ms: setting key expiration to 1.
ms: executing key expire script...
ms: updating openpgp public key file 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/host_keys.pub.pgp'...
ms: Key F069340F3B65C12702643DAE7399CCDAF66E7F62 now expires at 2018-09-27 
21:49:14
ms: NOTE: Key expiration date adjusted, but not yet published.
ms: Run 'monkeysphere-host publish-key' to publish the new expiration date.

##################################################
### certifying server host key...
gpg: clef 7399CCDAF66E7F62 : clef publique « ssh://testhost.example » importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg: vérification de la base de confiance
gpg: marginals needed: 3  completes needed: 1  trust model: pgp
gpg: profondeur : 0  valables :   1  signées :   0
     confiance : 0 i., 0 n.d., 0 j., 0 m., 0 t., 1 u.

##################################################
### setup monkeysphere authentication...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 
ms: setting up Monkeysphere authentication trust core...
ms: generating monkeysphere authentication trust core key:
ms: size: 1024 bits
ms: uid: 'Monkeysphere authentication trust core UID (random string: 
FgM7f8qG7isVSNIsZSrntEAI4KJY)'
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:15 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1

##################################################
### adding admin as certifier...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:15 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: reading key from stdin...
ms: checking keys in file...
ms: loading key into core keyring...
ms: executing core ltsign script...
ms: exporting core local sigs to sphere...
ms: updating sphere trustdb...
ms: Identity certifier added.

##################################################
### list certifiers...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:16 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: finding trusted keys...
ms: determining core key fingerprint...
4275279C9512E14BDD14098A36FF78B37005D3BE:
 :Monkeysphere Test Suite Fake Administrative User (DO NOT USE!!!) 
<fakead...@example.net>:1:120:

##################################################
### generating key for testuser...
ms: creating password fifo...
ms: Prompting for passphrase
ms: Launching 
"/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/askpass"
ms: (with prompt "Please enter your passphrase for E00B5EEEBA79B482: ")
ms: Generating subkey.  This may take a long time...
ms: done.

##################################################
### export server key to testuser...
gpg: clef 7399CCDAF66E7F62 : clef publique « ssh://testhost.example » importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg: marginals needed: 3  completes needed: 1  trust model: pgp
gpg: profondeur : 0  valables :   1  signées :   1
     confiance : 0 i., 0 n.d., 0 j., 0 m., 0 t., 1 u.
gpg: profondeur : 1  valables :   1  signées :   1
     confiance : 0 i., 0 n.d., 0 j., 0 m., 1 t., 0 u.
gpg: profondeur : 2  valables :   1  signées :   0
     confiance : 1 i., 0 n.d., 0 j., 0 m., 0 t., 0 u.
gpg: la prochaine vérification de la base de confiance aura lieu le 2018-09-28

##################################################
### export testuser key to server...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:19 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1

##################################################
### update server authorized_keys file for this testuser...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:19 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQDEynV+kjx22hB0iQoE46wv2PJXfYLPEduNZSQqQVIQC9JLeothsbKWX5dNqTwQNvYSPFvrEHnN+QJP3OU/hCRyef7u+T4xW+Br1lULWIteNyZzccvvn8OGMzmKW042UDKAi2RzBMzwAlY70QtXF6YA8TTpuFoXGE+rsUwDUKquRjjH0Vvdc6vxVd+uCqLITNhPUTyapeQJdGzkMgpMB9ypr/OblVuhZJ+3A/HbzD+v14oe1awleeeTuo0aTZezjsJzkteGUCF1/UGB+KP2Y43jZOUcShzFn32K+RgxxInZoq8JVFqAuq9SuFQYK59iJJuhzTbLQpYUHFFVYZpVOjVlar4DdoSFfKF7+QOJV4X7ogmNLwtgZlI5/1TCcGwnWkRv2+fjaUwreEbgKKVon8s7UojoVCTan75AK5eleGAKZKthzTxjingyiw33aVIDhWhAXMc93R/Az8heUhOb6wUI5eQDC2LUroOnevooDkCwnCyl3JrOTtiqmXKUCc9dDS0=
 MonkeySphere2018-09-27T01:49:19 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat...

##################################################
### testing monkeysphere keys-for-userid ...
ms: processing: ssh://testhost.example
ms: key file: -
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1

##################################################
### ssh connection test for success...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts.monkeysphere.MBMVm0
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
 MonkeySphere2018-09-27T01:49:21
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:kT0yhKd95pA00E3q6UfhA5GWrGfRzNwzK1IZC+N6E5M Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Server accepts key: pkalg rsa-sha2-512 blen 407
debug1: Authentication succeeded (publickey).
Authenticated to testhost.example (via proxy).
debug1: channel 0: new [client-session]
debug1: Requesting no-more-sessi...@openssh.com
debug1: Entering interactive session.
debug1: pledge: proc
debug1: client_input_global_request: rtype hostkeys...@openssh.com want_reply 0
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Sending command: true
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
debug1: client_input_channel_req: channel 0 rtype e...@openssh.com reply 0
debug1: channel 0: free: client-session, nchannels 1
debug1: fd 2 clearing O_NONBLOCK
Transferred: sent 3092, received 2812 bytes, in 0.0 seconds
Bytes per second: sent 940214.6, received 855072.3
debug1: Exit status 0
##### ssh connection test PASSED. returned: 0

##################################################
### testing functionality in the face of unusual gpg.conf settings...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts.monkeysphere.i2hz9g
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
 MonkeySphere2018-09-27T01:49:22
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:kT0yhKd95pA00E3q6UfhA5GWrGfRzNwzK1IZC+N6E5M Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Server accepts key: pkalg rsa-sha2-512 blen 407
debug1: Authentication succeeded (publickey).
Authenticated to testhost.example (via proxy).
debug1: channel 0: new [client-session]
debug1: Requesting no-more-sessi...@openssh.com
debug1: Entering interactive session.
debug1: pledge: proc
debug1: client_input_global_request: rtype hostkeys...@openssh.com want_reply 0
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Sending command: true
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
debug1: client_input_channel_req: channel 0 rtype e...@openssh.com reply 0
debug1: channel 0: free: client-session, nchannels 1
debug1: fd 2 clearing O_NONBLOCK
Transferred: sent 3092, received 2812 bytes, in 0.0 seconds
Bytes per second: sent 773165.5, received 703150.5
debug1: Exit status 0
##### ssh connection test PASSED. returned: 0

##################################################
### removing testuser authorized_user_ids and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:23 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: empty or absent authorized_user_ids file.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts.monkeysphere.O04lPg
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
 MonkeySphere2018-09-27T01:49:24
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:kT0yhKd95pA00E3q6UfhA5GWrGfRzNwzK1IZC+N6E5M Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255

##################################################
### setting group writability on authorized_user_ids and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:25 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'
ms: improper group writability on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'
ms: not processing authorized_user_ids.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts.monkeysphere.5sJ20C
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
 MonkeySphere2018-09-27T01:49:26
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:kT0yhKd95pA00E3q6UfhA5GWrGfRzNwzK1IZC+N6E5M Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255

##################################################
### setting other writability on authorized_user_ids and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:26 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'
ms: improper other writability on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'
ms: not processing authorized_user_ids.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts.monkeysphere.2niyPV
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
 MonkeySphere2018-09-27T01:49:28
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:5pBPPN3rXQO1+VVMX1j/HrV3DuDRxh5eSOJdJ4bYdwU
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:kT0yhKd95pA00E3q6UfhA5GWrGfRzNwzK1IZC+N6E5M Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:28 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQDEynV+kjx22hB0iQoE46wv2PJXfYLPEduNZSQqQVIQC9JLeothsbKWX5dNqTwQNvYSPFvrEHnN+QJP3OU/hCRyef7u+T4xW+Br1lULWIteNyZzccvvn8OGMzmKW042UDKAi2RzBMzwAlY70QtXF6YA8TTpuFoXGE+rsUwDUKquRjjH0Vvdc6vxVd+uCqLITNhPUTyapeQJdGzkMgpMB9ypr/OblVuhZJ+3A/HbzD+v14oe1awleeeTuo0aTZezjsJzkteGUCF1/UGB+KP2Y43jZOUcShzFn32K+RgxxInZoq8JVFqAuq9SuFQYK59iJJuhzTbLQpYUHFFVYZpVOjVlar4DdoSFfKF7+QOJV4X7ogmNLwtgZlI5/1TCcGwnWkRv2+fjaUwreEbgKKVon8s7UojoVCTan75AK5eleGAKZKthzTxjingyiw33aVIDhWhAXMc93R/Az8heUhOb6wUI5eQDC2LUroOnevooDkCwnCyl3JrOTtiqmXKUCc9dDS0=
 MonkeySphere2018-09-27T01:49:28 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat...

##################################################
### setup for symlink tests...

##################################################
### make authorized_user_ids an absolute symlink and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:49:29 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2F434E85A9CB9D976A3E3D0FDCE58621EF554B47:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids'...
ms: tracing link 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere/authorized_user_ids
 to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere.linktest/authorized_user_ids
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere.linktest/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.monkeysphere.linktest'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQDEynV+kjx22hB0iQoE46wv2PJXfYLPEduNZSQqQVIQC9JLeothsbKWX5dNqTwQNvYSPFvrEHnN+QJP3OU/hCRyef7u+T4xW+Br1lULWIteNyZzccvvn8OGMzmKW042UDKAi2RzBMzwAlY70QtXF6YA8TTpuFoXGE+rsUwDUKquRjjH0Vvdc6vxVd+uCqLITNhPUTyapeQJdGzkMgpMB9ypr/OblVuhZJ+3A/HbzD+v14oe1awleeeTuo0aTZezjsJzkteGUCF1/UGB+KP2Y43jZOUcShzFn32K+RgxxInZoq8JVFqAuq9SuFQYK59iJJuhzTbLQpYUHFFVYZpVOjVlar4DdoSFfKF7+QOJV4X7ogmNLwtgZlI5/1TCcGwnWkRv2+fjaUwreEbgKKVon8s7UojoVCTan75AK5eleGAKZKthzTxjingyiw33aVIDhWhAXMc93R/Az8heUhOb6wUI5eQDC2LUroOnevooDkCwnCyl3JrOTtiqmXKUCc9dDS0=
 MonkeySphere2018-09-27T01:49:29 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/authorized_keys/anarcat...

##################################################
### ssh connection test for success...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts.monkeysphere.mRWY4p
ms:  primary key found: 7399CCDAF66E7F62
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQCT1dumwswraCaUGNxD2wfJVwvTcXqCWhhtzgoxCfp1o1VwxXdl73XRD42qAydRa5PGPf1tiVGQ0XBVNYPyFuiGTK5m7BEGdgswixbfESWclZ4NxLgOrGQrpco5tutg3dYSiaCf+ecfw8KjuINm5e2O+u00Dc4HpowAO974RU2OVQ==
 MonkeySphere2018-09-27T01:49:29
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/testuser/.ssh/known_hosts'.
2018/09/26 21:49:30 socat[8627] E connect(5, AF=1 
"/home/anarcat/src/monkeysphere/tests/tmp/ms.dsn/ssh-socket", 60): Connection 
refused
ssh_exchange_identification: Connection closed by remote host
./tests/basic: ligne 81 : kill: (8566) - Aucun processus de ce type
##### ssh connection test FAILED. returned: 255, expected: 0
FAILED!
### removing temp dir...

MONKEYSPHERE_TEST_NO_EXAMINE=true ./tests/keytrans
##################################################
### generating openpgp key...
##################################################
### retrieving key timestamp...
##################################################
### exporting key to ssh file...
##################################################
### reconvert key, and compare to key in gpg keyring...
conversions look good!
Now working with key A6C04560BF03A8D1 at time 1538013336
/home/anarcat/src/monkeysphere/tests/tmp/ms.HV0/pubring.kbx
-----------------------------------------------------------
pub   rsa1024 2018-09-27 [SC]
      653886EA49ECCA223711BFB9A6C04560BF03A8D1
uid          [  ultime ] testtest

##################################################
### test User ID addition...
/home/anarcat/src/monkeysphere/tests/tmp/ms.HV0/pubring.kbx
-----------------------------------------------------------
pub   rsa1024 2018-09-27 [SC]
      653886EA49ECCA223711BFB9A6C04560BF03A8D1
uid          [  ultime ] monkeymonkey
uid          [  ultime ] testtest

##################################################
### sleeping to avoid test suite breakage on fast
### processors (see http://bugs.debian.org/591118)
##################################################
### test User ID revocation ... 
/home/anarcat/src/monkeysphere/tests/tmp/ms.HV0/pubring.kbx
-----------------------------------------------------------
pub   rsa1024 2018-09-27 [SC]
      653886EA49ECCA223711BFB9A6C04560BF03A8D1
uid          [  ultime ] monkeymonkey
uid          [ révoquée] testtest

##################################################
### test working with two primary keys ... 
Generating public/private rsa key pair.
Your identification has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.HV0/newkey.
Your public key has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.HV0/newkey.pub.
The key fingerprint is:
SHA256:Rr0UUm8FohN6D33M4MA32aATxQ1nOPQbCh3ud4K0X90 anarcat@curie
The key's randomart image is:
+---[RSA 1024]----+
|       .==@B+..  |
|       ..&B%o.   |
|      . X.OoO    |
|       o X B o ..|
|        S B + o E|
|       .   o +   |
|            .    |
|                 |
|                 |
+----[SHA256]-----+
test: diff expected gpg list output
test: diff expected keytrans listfpr output
##################################################
 Monkeysphere keytrans test completed successfully!
##################################################
### removing temp dir...
MONKEYSPHERE_TEST_NO_EXAMINE=true ./tests/basic
Permissions on temporary directory 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2' are OK for permissions checks.

##################################################
### configuring testuser home...
2 blocs
gpg: le trousseau local « 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.gnupg/pubring.kbx » a 
été créé
gpg: key E00B5EEEBA79B482: 1 signature not checked due to a missing key
gpg: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.gnupg/trustdb.gpg : 
base de confiance créée
gpg: clef E00B5EEEBA79B482 : clef publique « Monkeysphere Test Suite Test User 
(DO NOT USE!!!) <testu...@example.net> » importée
gpg: clef E00B5EEEBA79B482 : clef secrète importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg:           clefs secrètes lues : 1
gpg:      clefs secrètes importées : 1
gpg: aucune clef de confiance ultime n'a été trouvée
gpg: key E00B5EEEBA79B482: 1 signature not checked due to a missing key
gpg: clef E00B5EEEBA79B482 : « Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net> » n'est pas modifiée
gpg: clef 36FF78B37005D3BE : clef publique « Monkeysphere Test Suite Fake 
Administrative User (DO NOT USE!!!) <fakead...@example.net> » importée
gpg:       Quantité totale traitée : 2
gpg:                     importées : 1
gpg:                 non modifiées : 1
gpg: aucune clef de confiance ultime n'a été trouvée
gpg: inserting ownertrust of 6
gpg: inserting ownertrust of 5

##################################################
### configuring admin home...
gpg: le trousseau local « 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/admin/.gnupg/pubring.kbx » a 
été créé
gpg: /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/admin/.gnupg/trustdb.gpg : 
base de confiance créée
gpg: clef 36FF78B37005D3BE : clef publique « Monkeysphere Test Suite Fake 
Administrative User (DO NOT USE!!!) <fakead...@example.net> » importée
gpg: clef 36FF78B37005D3BE : clef secrète importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg:           clefs secrètes lues : 1
gpg:      clefs secrètes importées : 1
gpg: clef 36FF78B37005D3BE : « Monkeysphere Test Suite Fake Administrative User 
(DO NOT USE!!!) <fakead...@example.net> » n'est pas modifiée
gpg:       Quantité totale traitée : 1
gpg:                 non modifiées : 1
gpg: inserting ownertrust of 6

##################################################
### configuring sshd...

##################################################
### import host key...
Generating public/private rsa key pair.
Your identification has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh_host_rsa_key.
Your public key has been saved in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh_host_rsa_key.pub.
The key fingerprint is:
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ anarcat@curie
The key's randomart image is:
+---[RSA 1024]----+
|     .           |
|    E            |
| . o . .   .     |
|. + . =o  o      |
| + . .+oS. o     |
|..o oo *.o+ = .  |
|+o +..o X..X =   |
|o o.=o + oo.= .  |
|   oooo     ..   |
+----[SHA256]-----+
using keyserver: example.org
ms: importing key from file 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh_host_rsa_key'...
ms: updating openpgp public key file 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/host_keys.pub.pgp'...
ms: host key imported:
pub   rsa1024 2018-09-27 [CA]
      B862640F02BE82D5679EF0E214B47785C6A4E411
uid          [ inconnue] ssh://testhost.example
OpenPGP fingerprint: B862640F02BE82D5679EF0E214B47785C6A4E411
ssh fingerprint: 1024 SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ (RSA)

##################################################
### getting host key fingerprint...
using keyserver: example.org
ms: listing primary fingerprints from 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/host_keys.pub.pgp
ms: obtained the following fingerprints: 
B862640F02BE82D5679EF0E214B47785C6A4E411
ms: publishing all keys
ms: using keys: B862640F02BE82D5679EF0E214B47785C6A4E411
ms: invoking show_key B862640F02BE82D5679EF0E214B47785C6A4E411
B862640F02BE82D5679EF0E214B47785C6A4E411

##################################################
### setting host key expiration...
using keyserver: example.org
ms: extending without prompting.
ms: setting key expiration to 1.
ms: executing key expire script...
ms: updating openpgp public key file 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/host_keys.pub.pgp'...
ms: Key B862640F02BE82D5679EF0E214B47785C6A4E411 now expires at 2018-09-27 
21:55:41
ms: NOTE: Key expiration date adjusted, but not yet published.
ms: Run 'monkeysphere-host publish-key' to publish the new expiration date.

##################################################
### certifying server host key...
gpg: clef 14B47785C6A4E411 : clef publique « ssh://testhost.example » importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg: vérification de la base de confiance
gpg: marginals needed: 3  completes needed: 1  trust model: pgp
gpg: profondeur : 0  valables :   1  signées :   0
     confiance : 0 i., 0 n.d., 0 j., 0 m., 0 t., 1 u.

##################################################
### setup monkeysphere authentication...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 
ms: setting up Monkeysphere authentication trust core...
ms: generating monkeysphere authentication trust core key:
ms: size: 1024 bits
ms: uid: 'Monkeysphere authentication trust core UID (random string: 
LsqtdOC+I3b64iMf/nXbOCRR3hUD)'
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:41 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1

##################################################
### adding admin as certifier...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:41 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: reading key from stdin...
ms: checking keys in file...
ms: loading key into core keyring...
ms: executing core ltsign script...
ms: exporting core local sigs to sphere...
ms: updating sphere trustdb...
ms: Identity certifier added.

##################################################
### list certifiers...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:41 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: finding trusted keys...
ms: determining core key fingerprint...
4275279C9512E14BDD14098A36FF78B37005D3BE:
 :Monkeysphere Test Suite Fake Administrative User (DO NOT USE!!!) 
<fakead...@example.net>:1:120:

##################################################
### generating key for testuser...
ms: creating password fifo...
ms: Prompting for passphrase
ms: Launching 
"/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/askpass"
ms: (with prompt "Please enter your passphrase for E00B5EEEBA79B482: ")
ms: Generating subkey.  This may take a long time...
ms: done.

##################################################
### export server key to testuser...
gpg: clef 14B47785C6A4E411 : clef publique « ssh://testhost.example » importée
gpg:       Quantité totale traitée : 1
gpg:                     importées : 1
gpg: marginals needed: 3  completes needed: 1  trust model: pgp
gpg: profondeur : 0  valables :   1  signées :   1
     confiance : 0 i., 0 n.d., 0 j., 0 m., 0 t., 1 u.
gpg: profondeur : 1  valables :   1  signées :   1
     confiance : 0 i., 0 n.d., 0 j., 0 m., 1 t., 0 u.
gpg: profondeur : 2  valables :   1  signées :   0
     confiance : 1 i., 0 n.d., 0 j., 0 m., 0 t., 0 u.
gpg: la prochaine vérification de la base de confiance aura lieu le 2018-09-28

##################################################
### export testuser key to server...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:43 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1

##################################################
### update server authorized_keys file for this testuser...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:43 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQCskXVm3wCJmoRwKwTbHdT2sYH2asXBTEwMKKLRORnCJc93JFQX5gXhpycvmRBUoAd28zsVTPbdmyCU2mU+teZKZh35lv8v2TefCnKCn0ZJldp3LAmGzvEhTwgNcA3kkLKIcVk4Ba8x6p7mSfDT6ozDUaLD3u2syRTEGaBniqbXg+sU2d03eP497AHOyor8LuvL732AMoTL8tMO4g+Xj3zm8TfRg6t55BrjvHz4egNaTcXb9EZZzD31//1ueqCbNvoPXaAfOo18ms3j3xXVg5rsoSTmNujw9ffIauAA5yJvyWnZC0hHXGxj9Ic4FK2+cM5Rm6ZXGnie/wl1qsDefkInV/WFt0fKUKGjrsQl8oMVv3oaJ+U0xcCB6X4PstWWO5DI468jp8BZtbDbUW3xvV7TqkB2bxvAN+zEV1q1dquNBmIH/uCNls5qtP3SslayQo6q+u0QZkgqgftureIRbSFYoVp/n+NkVRfx0gApfEGAmtjMtLfxz/2tPH86vUnN/O0=
 MonkeySphere2018-09-27T01:55:43 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat...

##################################################
### testing monkeysphere keys-for-userid ...
ms: processing: ssh://testhost.example
ms: key file: -
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1

##################################################
### ssh connection test for success...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.ChTjCs
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:45
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Server accepts key: pkalg rsa-sha2-512 blen 407
debug1: Authentication succeeded (publickey).
Authenticated to testhost.example (via proxy).
debug1: channel 0: new [client-session]
debug1: Requesting no-more-sessi...@openssh.com
debug1: Entering interactive session.
debug1: pledge: proc
debug1: client_input_global_request: rtype hostkeys...@openssh.com want_reply 0
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Sending command: true
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
debug1: client_input_channel_req: channel 0 rtype e...@openssh.com reply 0
debug1: channel 0: free: client-session, nchannels 1
debug1: fd 2 clearing O_NONBLOCK
Transferred: sent 3092, received 2812 bytes, in 0.0 seconds
Bytes per second: sent 906610.1, received 824510.8
debug1: Exit status 0
##### ssh connection test PASSED. returned: 0

##################################################
### testing functionality in the face of unusual gpg.conf settings...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.hvr8xJ
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:47
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Server accepts key: pkalg rsa-sha2-512 blen 407
debug1: Authentication succeeded (publickey).
Authenticated to testhost.example (via proxy).
debug1: channel 0: new [client-session]
debug1: Requesting no-more-sessi...@openssh.com
debug1: Entering interactive session.
debug1: pledge: proc
debug1: client_input_global_request: rtype hostkeys...@openssh.com want_reply 0
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Sending command: true
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
debug1: client_input_channel_req: channel 0 rtype e...@openssh.com reply 0
debug1: channel 0: free: client-session, nchannels 1
debug1: fd 2 clearing O_NONBLOCK
Transferred: sent 3092, received 2812 bytes, in 0.0 seconds
Bytes per second: sent 912308.3, received 829693.1
debug1: Exit status 0
##### ssh connection test PASSED. returned: 0

##################################################
### removing testuser authorized_user_ids and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:47 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: empty or absent authorized_user_ids file.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.EWjc2k
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:49
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255

##################################################
### setting group writability on authorized_user_ids and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:49 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: improper group writability on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: not processing authorized_user_ids.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.XJFkGX
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:50
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255

##################################################
### setting other writability on authorized_user_ids and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:51 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: improper other writability on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: not processing authorized_user_ids.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.N4AjMH
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:52
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:52 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQCskXVm3wCJmoRwKwTbHdT2sYH2asXBTEwMKKLRORnCJc93JFQX5gXhpycvmRBUoAd28zsVTPbdmyCU2mU+teZKZh35lv8v2TefCnKCn0ZJldp3LAmGzvEhTwgNcA3kkLKIcVk4Ba8x6p7mSfDT6ozDUaLD3u2syRTEGaBniqbXg+sU2d03eP497AHOyor8LuvL732AMoTL8tMO4g+Xj3zm8TfRg6t55BrjvHz4egNaTcXb9EZZzD31//1ueqCbNvoPXaAfOo18ms3j3xXVg5rsoSTmNujw9ffIauAA5yJvyWnZC0hHXGxj9Ic4FK2+cM5Rm6ZXGnie/wl1qsDefkInV/WFt0fKUKGjrsQl8oMVv3oaJ+U0xcCB6X4PstWWO5DI468jp8BZtbDbUW3xvV7TqkB2bxvAN+zEV1q1dquNBmIH/uCNls5qtP3SslayQo6q+u0QZkgqgftureIRbSFYoVp/n+NkVRfx0gApfEGAmtjMtLfxz/2tPH86vUnN/O0=
 MonkeySphere2018-09-27T01:55:52 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat...

##################################################
### setup for symlink tests...

##################################################
### make authorized_user_ids an absolute symlink and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:53 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: tracing link 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids
 to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQCskXVm3wCJmoRwKwTbHdT2sYH2asXBTEwMKKLRORnCJc93JFQX5gXhpycvmRBUoAd28zsVTPbdmyCU2mU+teZKZh35lv8v2TefCnKCn0ZJldp3LAmGzvEhTwgNcA3kkLKIcVk4Ba8x6p7mSfDT6ozDUaLD3u2syRTEGaBniqbXg+sU2d03eP497AHOyor8LuvL732AMoTL8tMO4g+Xj3zm8TfRg6t55BrjvHz4egNaTcXb9EZZzD31//1ueqCbNvoPXaAfOo18ms3j3xXVg5rsoSTmNujw9ffIauAA5yJvyWnZC0hHXGxj9Ic4FK2+cM5Rm6ZXGnie/wl1qsDefkInV/WFt0fKUKGjrsQl8oMVv3oaJ+U0xcCB6X4PstWWO5DI468jp8BZtbDbUW3xvV7TqkB2bxvAN+zEV1q1dquNBmIH/uCNls5qtP3SslayQo6q+u0QZkgqgftureIRbSFYoVp/n+NkVRfx0gApfEGAmtjMtLfxz/2tPH86vUnN/O0=
 MonkeySphere2018-09-27T01:55:53 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat...

##################################################
### ssh connection test for success...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.9rjAyz
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:55
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Server accepts key: pkalg rsa-sha2-512 blen 407
debug1: Authentication succeeded (publickey).
Authenticated to testhost.example (via proxy).
debug1: channel 0: new [client-session]
debug1: Requesting no-more-sessi...@openssh.com
debug1: Entering interactive session.
debug1: pledge: proc
debug1: client_input_global_request: rtype hostkeys...@openssh.com want_reply 0
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Sending command: true
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
debug1: client_input_channel_req: channel 0 rtype e...@openssh.com reply 0
debug1: channel 0: free: client-session, nchannels 1
debug1: fd 2 clearing O_NONBLOCK
Transferred: sent 3092, received 2812 bytes, in 0.0 seconds
Bytes per second: sent 947821.1, received 861990.0
debug1: Exit status 0
##### ssh connection test PASSED. returned: 0

##################################################
### create bad permissions on link dir and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:55 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: tracing link 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids
 to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: improper other writability on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: not processing authorized_user_ids.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.Mbbj7W
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:56
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Authentications that can continue: publickey
debug1: Trying private key: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity
no such identity: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity: 
No such file or directory
debug1: No more authentication methods to try.
anarcat@testhost.example: Permission denied (publickey).
##### ssh connection test PASSED. returned: 255

##################################################
### make authorized_user_ids a relative symlink and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:57 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: tracing link 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids
 to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQCskXVm3wCJmoRwKwTbHdT2sYH2asXBTEwMKKLRORnCJc93JFQX5gXhpycvmRBUoAd28zsVTPbdmyCU2mU+teZKZh35lv8v2TefCnKCn0ZJldp3LAmGzvEhTwgNcA3kkLKIcVk4Ba8x6p7mSfDT6ozDUaLD3u2syRTEGaBniqbXg+sU2d03eP497AHOyor8LuvL732AMoTL8tMO4g+Xj3zm8TfRg6t55BrjvHz4egNaTcXb9EZZzD31//1ueqCbNvoPXaAfOo18ms3j3xXVg5rsoSTmNujw9ffIauAA5yJvyWnZC0hHXGxj9Ic4FK2+cM5Rm6ZXGnie/wl1qsDefkInV/WFt0fKUKGjrsQl8oMVv3oaJ+U0xcCB6X4PstWWO5DI468jp8BZtbDbUW3xvV7TqkB2bxvAN+zEV1q1dquNBmIH/uCNls5qtP3SslayQo6q+u0QZkgqgftureIRbSFYoVp/n+NkVRfx0gApfEGAmtjMtLfxz/2tPH86vUnN/O0=
 MonkeySphere2018-09-27T01:55:57 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat...

##################################################
### ssh connection test for success...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.vNACVW
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:58
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
debug1: Remote protocol version 2.0, remote software version OpenSSH_7.8p1 
Debian-1
debug1: match: OpenSSH_7.8p1 Debian-1 pat OpenSSH* compat 0x04000000
debug1: Authenticating to testhost.example:22 as 'anarcat'
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug1: kex: algorithm: curve25519-sha256
debug1: kex: host key algorithm: rsa-sha2-512
debug1: kex: server->client cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: kex: client->server cipher: chacha20-poly1...@openssh.com MAC: 
<implicit> compression: none
debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
debug1: Server host key: ssh-rsa 
SHA256:/weaDZR/Cs/BIsCTqpfOXSOhR9nOOcwqQ+KIJNEe1pQ
debug1: Host 'testhost.example' is known and matches the RSA host key.
debug1: Found key in 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts:1
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug1: SSH2_MSG_NEWKEYS received
debug1: rekey after 134217728 blocks
debug1: SSH2_MSG_EXT_INFO received
debug1: kex_input_ext_info: 
server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug1: Authentications that can continue: publickey
debug1: Next authentication method: publickey
debug1: Offering public key: RSA 
SHA256:zPX/ES66nriJDWzO7317Rf6Xlm2tZScylOgvbeCFi4k Monkeysphere Test Suite Test 
User (DO NOT USE!!!) <testu...@example.net>
debug1: Server accepts key: pkalg rsa-sha2-512 blen 407
debug1: Authentication succeeded (publickey).
Authenticated to testhost.example (via proxy).
debug1: channel 0: new [client-session]
debug1: Requesting no-more-sessi...@openssh.com
debug1: Entering interactive session.
debug1: pledge: proc
debug1: client_input_global_request: rtype hostkeys...@openssh.com want_reply 0
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Remote: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat:1: key 
options: agent-forwarding port-forwarding pty user-rc x11-forwarding
debug1: Sending command: true
debug1: client_input_channel_req: channel 0 rtype exit-status reply 0
debug1: client_input_channel_req: channel 0 rtype e...@openssh.com reply 0
debug1: channel 0: free: client-session, nchannels 1
debug1: fd 2 clearing O_NONBLOCK
Transferred: sent 3092, received 2812 bytes, in 0.0 seconds
Bytes per second: sent 814376.7, received 740629.7
debug1: Exit status 0
##### ssh connection test PASSED. returned: 0

##################################################
### create bad permissions on link dir updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:59 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: tracing link 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids
 to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest/authorized_user_ids'
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: improper other writability on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: not processing authorized_user_ids.

##################################################
### ssh connection test for failure...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.sm22uI
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:55:59
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
2018/09/26 21:55:59 socat[17971] E connect(5, AF=1 
"/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket", 60): Connection 
refused
ssh_exchange_identification: Connection closed by remote host
./tests/basic: ligne 81 : kill: (17911) - Aucun processus de ce type
##### ssh connection test PASSED. returned: 255

##################################################
### make .monkeysphere directory an absolute symlink and updating...
ms: checking authentication directory structure...
ms: writing core gpg.conf...
ms: writing sphere gpg.conf...
ms: fixing sphere gnupg home ownership...
ms: determining core key fingerprint...
ms: core fingerprint: 2E87C56C09261280D18688F7554AA0A0A97A4F8D
ms: Monkeysphere authentication trust core already exists.
ms: exporting core pub key to sphere keyring...
ms: setting ultimate owner trust on core key in gpg_sphere...
ms: # Liste de valeurs de confiance définies, créée le mer 26 sep 2018 21:55:59 
EDT
ms: # (utilisez « gpg --import-ownertrust » pour les restaurer)
ms: 2E87C56C09261280D18688F7554AA0A0A97A4F8D:6:
ms: checking gpg_sphere owner trust set properly...
ms: checking trust model for authentication ...
ms: sphere trust model: 1:3:1
ms: ----- user: anarcat -----
ms: checking for authorized_user_ids...
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere/authorized_user_ids'
ms: tracing link 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.monkeysphere.linktest'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: processing authorized_user_ids...
ms: processing: Monkeysphere Test Suite Test User (DO NOT USE!!!) 
<testu...@example.net>
ms: key file: -
ms:  primary key found: E00B5EEEBA79B482
ms:   - unacceptable primary key.
ms:   * acceptable sub key.
ms: key line: ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAABgQCskXVm3wCJmoRwKwTbHdT2sYH2asXBTEwMKKLRORnCJc93JFQX5gXhpycvmRBUoAd28zsVTPbdmyCU2mU+teZKZh35lv8v2TefCnKCn0ZJldp3LAmGzvEhTwgNcA3kkLKIcVk4Ba8x6p7mSfDT6ozDUaLD3u2syRTEGaBniqbXg+sU2d03eP497AHOyor8LuvL732AMoTL8tMO4g+Xj3zm8TfRg6t55BrjvHz4egNaTcXb9EZZzD31//1ueqCbNvoPXaAfOo18ms3j3xXVg5rsoSTmNujw9ffIauAA5yJvyWnZC0hHXGxj9Ic4FK2+cM5Rm6ZXGnie/wl1qsDefkInV/WFt0fKUKGjrsQl8oMVv3oaJ+U0xcCB6X4PstWWO5DI468jp8BZtbDbUW3xvV7TqkB2bxvAN+zEV1q1dquNBmIH/uCNls5qtP3SslayQo6q+u0QZkgqgftureIRbSFYoVp/n+NkVRfx0gApfEGAmtjMtLfxz/2tPH86vUnN/O0=
 MonkeySphere2018-09-27T01:55:59 Monkeysphere Test Suite Test User (DO NOT 
USE!!!) <testu...@example.net>
ms: KEYS_PROCESSED=2
ms: KEYS_VALID=1
ms: moving new file to 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/authorized_keys/anarcat...

##################################################
### ssh connection test for success...
##### starting ssh server...
##### starting ssh client...
debug1: Executing proxy command: exec 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/proxy-command 
testhost.example 22 /home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity 
type -1
debug1: identity file 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/no-such-identity-cert
 type -1
debug1: Local version string SSH-2.0-OpenSSH_7.8p1 Debian-1
/home/anarcat/src/monkeysphere/tests/../src/monkeysphere
ms: checking path permission 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'...
ms: checking 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2'
ms: checking '/home/anarcat/src/monkeysphere/tests/tmp'
ms: checking '/home/anarcat/src/monkeysphere/tests'
ms: checking '/home/anarcat/src/monkeysphere'
ms: checking '/home/anarcat/src'
ms: checking '/home/anarcat'
ms: stopping at anarcat's home directory '/home/anarcat'
ms: lock created on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: processing: ssh://testhost.example
ms: key file: 
/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts.monkeysphere.N2EcZF
ms:  primary key found: 14B47785C6A4E411
ms:   * acceptable primary key.
ms: removing matching key lines...
ms: key line: testhost.example ssh-rsa 
AAAAB3NzaC1yc2EAAAADAQABAAAAgQDKU0IZZ39O6xhB4lit/40ImvJDVy4GaC0o0lLBdu8tCSQMoGk2W7MkDUGLLYIcqcCAhk9hfmzTqfiG8e2XJ/DdzqYvSTncuG4NBZqp8p933H+NUStMuCGoyC8bt/ibD9S45wcHpykAT2/Jpmi0shs5kgxfbpmWgDHTV/E/2+BeXQ==
 MonkeySphere2018-09-27T01:56:00
ms: adding key line to file...
ms: KEYS_PROCESSED=1
ms: KEYS_VALID=1
ms: lock touched on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
ms: known_hosts file updated.
ms: lock removed on 
'/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/testuser/.ssh/known_hosts'.
2018/09/26 21:56:00 socat[18303] E connect(5, AF=1 
"/home/anarcat/src/monkeysphere/tests/tmp/ms.dk2/ssh-socket", 60): Connection 
refused
ssh_exchange_identification: Connection closed by remote host
./tests/basic: ligne 81 : kill: (18244) - Aucun processus de ce type
##### ssh connection test FAILED. returned: 255, expected: 0
FAILED!
### removing temp dir...

Now unfortunately, I have zero idea where to go from here. Note that
there is at least one other report of Monkeysphere's test suite failing
randomly, although this report seems to differ from #861457.

[...]

> I wonder if that shouldn't be treated separately: I suspect this is
> related to the recent changes in OpenSSH's key storage format.
>
> So I'd propose we keep this bug for "kilobyte's weird heisenbug FTBFS"
> and open a separate issue for the RSA key parser.. :/

I reported that bug and fixed it, in #909700. I also pushed commits to
fix #902318 and #902367 but we're unfortunately still stuck here. :/

A.

-- 
To understand how any society functions you must understand the
relationship between the men and the women
                        - Angela Davis