[QA] excessive syslog messages from hsmdaemon

[jnweiger]

Seen while testing encryption 1.5.1-rc1 with hsmdaemon-0.0.8 and SoftHSM master (2.6.1+) on server 10.7.0

journalctl -a | grep hsmdaemon

Jun 19 03:09:59 jw-oc1070-enc-20210619-l91 hsmdaemon[42371]: File.cpp(94): Could not open the file (No such file or directory): /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d/generation
Jun 19 03:09:59 jw-oc1070-enc-20210619-l91 hsmdaemon[42371]: File.cpp(94): Could not open the file (No such file or directory): /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d/generation
Jun 19 03:09:59 jw-oc1070-enc-20210619-l91 hsmdaemon[42371]: File.cpp(94): Could not open the file (No such file or directory): /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d/generation
Jun 19 03:09:59 jw-oc1070-enc-20210619-l91 hsmdaemon[42371]: File.cpp(94): Could not open the file (No such file or directory): /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d/generation
Jun 19 03:09:59 jw-oc1070-enc-20210619-l91 hsmdaemon[42371]: File.cpp(94): Could not open the file (No such file or directory): /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d/generation
Jun 19 03:09:59 jw-oc1070-enc-20210619-l91 hsmdaemon[42371]: File.cpp(94): Could not open the file (No such file or directory): /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d/generation
...

The token exists:

ls -la /var/lib/softhsm/tokens//2e78508d-cd88-66d9-9aa1-3f982d2b840d
total 36
drwx------ 2 root root 4096 Jun 19 03:10 .
drwxrwxrwt 3 root root 4096 Jun 19 03:09 ..
-rw------- 1 root root    0 Jun 19 03:10 07aec470-33f6-8511-7b8f-692d488c4713.lock
-rw------- 1 root root 2241 Jun 19 03:10 07aec470-33f6-8511-7b8f-692d488c4713.object
-rw------- 1 root root    0 Jun 19 03:10 0ec8a75c-1edb-2d2f-260f-092fe23d73ca.lock
-rw------- 1 root root 2273 Jun 19 03:10 0ec8a75c-1edb-2d2f-260f-092fe23d73ca.object
-rw------- 1 root root    0 Jun 19 03:10 15722a91-ed60-3778-53f9-fe983663807c.lock
-rw------- 1 root root  827 Jun 19 03:10 15722a91-ed60-3778-53f9-fe983663807c.object
-rw------- 1 root root    0 Jun 19 03:10 40bea97d-0e0e-545d-1cb5-a6bb5b4fe44c.lock
-rw------- 1 root root  862 Jun 19 03:10 40bea97d-0e0e-545d-1cb5-a6bb5b4fe44c.object
-rw------- 1 root root    0 Jun 19 03:10 52c7836f-2a62-4f1a-a144-b91a23aebe11.lock
-rw------- 1 root root 2273 Jun 19 03:10 52c7836f-2a62-4f1a-a144-b91a23aebe11.object
-rw------- 1 root root    0 Jun 19 03:10 7c9b75aa-5c7f-9ff4-c922-3b3fb5100e7a.lock
-rw------- 1 root root  862 Jun 19 03:10 7c9b75aa-5c7f-9ff4-c922-3b3fb5100e7a.object
-rw------- 1 root root    0 Jun 20 22:22 token.lock
-rw------- 1 root root  320 Jun 20 22:22 token.object

• The log message seems harmless. Normal operation.
• The log message is repetitive, number of new repetitiions per action:
  • 15 for a file upload, file download.
  • 60 when moving a file to an encrypted external storage
  • 30 when moving back from encrpted external storage.
  • 15 when moving a file to an unencrypted external storage.

Expected behaviour: no such messages, not wasting resources.

[jnweiger]

These messages do not show up in the logfile configured in /etc/hsmdaemon/hsmdaemon.toml -- only in the syslog accessible via journalctl.

[TushkiK]

Is this issue of excessive syslog messages resolved in SoftHSM?