4.1.0: test suite is failing with `Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module` messages
To be honest I'm a bit puzzled because as long as I'm running in interactive session rpm -package build test suite is OK. Build of the gcr rpm package in which build is done in non-tty session fails. In both cases p11-kit list-modules
command shows exactly the same output. In both cases are used binaries installed out of exactly the same binary packages.
Here is p11-kit list-modules
command output + test suite failing output:
+ cd gcr-4.1.0
+ p11-kit list-modules
p11-kit-trust: p11-kit-trust.so
library-description: PKCS#11 Kit Trust Module
library-manufacturer: PKCS#11 Kit
library-version: 0.25
token: System Trust
manufacturer: PKCS#11 Kit
model: p11-kit-trust
serial-number: 1
hardware-version: 0.25
flags:
write-protected
token-initialized
token: Default Trust
manufacturer: PKCS#11 Kit
model: p11-kit-trust
serial-number: 1
hardware-version: 0.25
flags:
write-protected
token-initialized
+ xvfb-run -a /usr/bin/meson test -C x86_64-redhat-linux-gnu --num-processes 48 --print-errorlogs
ninja: Entering directory `/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu'
ninja: no work to do.
1/44 gcr:egg / asn1 OK 0.36s
2/44 gcr:egg / asn1x OK 0.36s
3/44 gcr:egg / dn OK 0.36s
4/44 gcr:egg / decimal OK 0.35s
5/44 gcr:egg / hex OK 0.35s
6/44 gcr:egg / hkdf OK 0.34s
7/44 gcr:egg / oid OK 0.34s
8/44 gcr:egg / padding OK 0.33s
9/44 gcr:egg / symkey OK 0.33s
10/44 gcr:egg / armor OK 0.32s
11/44 gcr:egg / openssl OK 0.32s
12/44 gcr:egg / dh OK 0.31s
13/44 gcr:gck / attributes OK 0.31s
14/44 gcr:gck / uri OK 0.28s
15/44 gcr:gcr-ssh-agent / process OK 0.25s
16/44 gcr:gcr-ssh-agent / util OK 0.23s
17/44 gcr:gcr / util OK 0.22s
18/44 gcr:gcr / secret-exchange OK 0.21s
19/44 gcr:gcr / simple-certificate OK 0.21s
20/44 gcr:gcr / certificate OK 0.20s
21/44 gcr:gcr / certificate-chain OK 0.20s
22/44 gcr:gcr / subject-public-key OK 0.19s
23/44 gcr:gcr / fingerprint OK 0.16s
24/44 gcr:gcr / pkcs11-certificate OK 0.15s
25/44 gcr:gcr / openpgp OK 0.15s
26/44 gcr:gcr / openssh OK 0.14s
27/44 gcr:gcr / trust OK 0.13s
28/44 gcr:gcr / record OK 0.12s
29/44 gcr:gcr / gnupg-key OK 0.11s
30/44 gcr:gck / module FAIL 0.32s killed by signal 6 SIGABRT
>>> MALLOC_PERTURB_=87 LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-module
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02S0c8a7815e6f66f8fbe29db8c5b446751
1..5
# Start of gck tests
# Start of module tests
not ok /gck/module/initialize_async - Gck:ERROR:../gck/test-gck-module.c:82:test_initialize_async: assertion failed (error == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-module.c:82:test_initialize_async: assertion failed (error == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
31/44 gcr:gck / slot FAIL 0.31s killed by signal 6 SIGABRT
>>> LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck MALLOC_PERTURB_=246 /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-slot
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02S4627843c42cf8254d1d5c7623c65bda4
1..7
# Start of gck tests
# Start of slot tests
not ok /gck/slot/slot_info - Gck:ERROR:../gck/test-gck-slot.c:46:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-slot.c:46:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
32/44 gcr:gck / session FAIL 0.31s killed by signal 6 SIGABRT
>>> LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck MALLOC_PERTURB_=231 /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-session
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02Sdb9a1144be372f71082084a14de28c2d
1..10
# Start of gck tests
# Start of session tests
not ok /gck/session/session_props - Gck:ERROR:../gck/test-gck-session.c:52:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-session.c:52:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
33/44 gcr:gck / object FAIL 0.30s killed by signal 6 SIGABRT
>>> LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck MALLOC_PERTURB_=43 /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-object
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02S6e99608d2bb54cd683ab92f56442e53a
1..8
# Start of gck tests
# Start of object tests
not ok /gck/object/object_props - Gck:ERROR:../gck/test-gck-object.c:53:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-object.c:53:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
34/44 gcr:gck / crypto FAIL 0.30s killed by signal 6 SIGABRT
>>> LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck MALLOC_PERTURB_=228 /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-crypto
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02S1c1a5b935e98404e8234870f361e1e45
1..9
# Start of gck tests
# Start of crypto tests
not ok /gck/crypto/encrypt - Gck:ERROR:../gck/test-gck-crypto.c:62:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-crypto.c:62:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
35/44 gcr:gck / enumerator FAIL 0.29s killed by signal 6 SIGABRT
>>> LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck MALLOC_PERTURB_=212 /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-enumerator
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02Sfd9c967bb4e07ec976ec3c496ba2ff1a
1..14
# Start of gck tests
# Start of enumerator tests
not ok /gck/enumerator/create - Gck:ERROR:../gck/test-gck-enumerator.c:52:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-enumerator.c:52:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
36/44 gcr:gcr / secure-memory OK 0.14s
37/44 gcr:gck / modules FAIL 0.29s killed by signal 6 SIGABRT
>>> LD_LIBRARY_PATH=/home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck MALLOC_PERTURB_=11 /home/tkloczko/rpmbuild/BUILD/gcr-4.1.0/x86_64-redhat-linux-gnu/gck/test-gck-modules
――――――――――――――――――――――――――――――――――――― ✀ ―――――――――――――――――――――――――――――――――――――
stdout:
TAP version 13
# random seed: R02S26cef49cba4cb482e1982c4cf65b3804
1..9
# Start of gck tests
# Start of modules tests
not ok /gck/modules/enumerate_objects - Gck:ERROR:../gck/test-gck-modules.c:51:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
Bail out!
stderr:
**
Gck:ERROR:../gck/test-gck-modules.c:51:setup: assertion failed (err == NULL): Error loading PKCS#11 module: refusing to load the p11-kit-proxy.so module as a registered module (GckError, -951891199)
――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――――
38/44 gcr:gcr / gnupg-process OK 0.12s
39/44 gcr:gcr-ssh-agent / service OK 0.27s
40/44 gcr:gcr / ssh-askpass OK 0.11s
41/44 gcr:gcr / parser OK 0.18s
42/44 gcr:egg / secmem OK 0.97s
43/44 gcr:gcr-ssh-agent / preload OK 3.02s
44/44 gcr:gcr / system-prompt OK 4.65s
Summary of Failures:
30/44 gcr:gck / module FAIL 0.32s killed by signal 6 SIGABRT
31/44 gcr:gck / slot FAIL 0.31s killed by signal 6 SIGABRT
32/44 gcr:gck / session FAIL 0.31s killed by signal 6 SIGABRT
33/44 gcr:gck / object FAIL 0.30s killed by signal 6 SIGABRT
34/44 gcr:gck / crypto FAIL 0.30s killed by signal 6 SIGABRT
35/44 gcr:gck / enumerator FAIL 0.29s killed by signal 6 SIGABRT
37/44 gcr:gck / modules FAIL 0.29s killed by signal 6 SIGABRT
Ok: 37
Expected Fail: 0
Fail: 7
Unexpected Pass: 0
Skipped: 0
Timeout: 0
May I ask for some help with diagnose what could be wrong or maybe it is some gcr
issue?
Edited by Tomasz Kłoczko