Note: This is a beta release of Red Hat Bugzilla 5.0. The data contained within is a snapshot of the live data so any changes you make will not be reflected in the production Bugzilla. Also email is disabled so feel free to test any aspect of the site that you want. File any problems you find or give feedback here.
Bug 1686890 - [abrt] tpm2-abrmd: access_broker_get_tpm_properties_fixed(): tpm2-abrmd killed by SIGTRAP
Summary: [abrt] tpm2-abrmd: access_broker_get_tpm_properties_fixed(): tpm2-abrmd kille...
Keywords:
Status: NEW
Alias: None
Product: Fedora
Classification: Fedora
Component: tpm2-abrmd
Version: 30
Hardware: x86_64
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: Yunying Sun
QA Contact: Fedora Extras Quality Assurance
URL: https://retrace.fedoraproject.org/faf...
Whiteboard: abrt_hash:488b18ce8254add53d72b84146e...
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2019-03-08 15:36 UTC by Markus Linnala
Modified: 2019-04-08 12:52 UTC (History)
9 users (show)

Fixed In Version:
Doc Type: If docs needed, set a value
Doc Text:
Clone Of:
Environment:
Last Closed:


Attachments (Terms of Use)
File: backtrace (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: cgroup (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: core_backtrace (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: cpuinfo (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: dso_list (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: environ (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: limits (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: maps (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: mountinfo (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: open_fds (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: proc_pid_status (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details
File: var_log_messages (deleted)
2019-03-08 15:36 UTC, Markus Linnala
no flags Details

Description Markus Linnala 2019-03-08 15:36:22 UTC
Description of problem:
Similar setup seems to work better at F29 so I see this as a regression.

I have virtual machine. Host is F29. Virtual machine is F30 installed from netinst iso, minimal install. For virtual machine I have configured TPM v2.0, CRB.

I've installed tpm2-abrmd-2.1.0-2.fc30.x86_64 and configured it to start at boot.

It does produce coredumps and then eventually start:

Mar 08 16:49:04 localhost.localdomain tpm2-abrmd[1685]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 08 16:49:04 localhost.localdomain tpm2-abrmd[1685]: Failed to GetCapability: TPM2_CAP_TPM_PROPERTIES, TPM2_PT_FIXED: 0xa0008

Is it really required for tpm2-abrmd to abort at this kind of errors? If it is essentially to die, then maybe return code should just make it normal and systemd should restat it after a while.

Also is there something wrong in the F30. As far as I can see, this setupt should work without extra changes.

I issued "tpm2_takeownership -c" at 17:22:47 and it did not work either.

[root@localhost ~]# journalctl -b0 _SYSTEMD_UNIT=tpm2-abrmd.service --no-hostname
-- Logs begin at Fri 2019-03-08 14:03:37 EET, end at Fri 2019-03-08 17:24:49 EET. --
Mar 08 16:49:04 tpm2-abrmd[1685]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 08 16:49:04 tpm2-abrmd[1685]: Failed to GetCapability: TPM2_CAP_TPM_PROPERTIES, TPM2_PT_FIXED: 0xa0008
Mar 08 16:49:09 tpm2-abrmd[1892]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 08 16:49:09 tpm2-abrmd[1892]: Tss2_Sys_Startup returned unexpected RC: 0xa0008
Mar 08 16:49:09 tpm2-abrmd[1892]: access_broker_sent_tpm_startup failed: 0xa0008
Mar 08 16:49:09 tpm2-abrmd[1892]: Failed to GetCapability: TPM2_CAP_TPM_PROPERTIES, TPM2_PT_FIXED: 0x80007
Mar 08 16:49:15 tpm2-abrmd[1940]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 08 16:49:15 tpm2-abrmd[1940]: Tss2_Sys_Startup returned unexpected RC: 0xa0008
Mar 08 16:49:15 tpm2-abrmd[1940]: access_broker_sent_tpm_startup failed: 0xa0008
Mar 08 16:49:15 tpm2-abrmd[1940]: Failed to GetCapability: TPM2_CAP_TPM_PROPERTIES, TPM2_PT_FIXED: 0x80007
Mar 08 16:49:20 tpm2-abrmd[2019]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 08 16:49:20 tpm2-abrmd[2019]: Tss2_Sys_Startup returned unexpected RC: 0xa0008
Mar 08 16:49:20 tpm2-abrmd[2019]: access_broker_sent_tpm_startup failed: 0xa0008
Mar 08 16:49:20 tpm2-abrmd[2019]: Failed to GetCapability: TPM2_CAP_TPM_PROPERTIES, TPM2_PT_FIXED: 0x80007
Mar 08 17:22:47 tpm2-abrmd[2117]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 08 17:22:47 tpm2-abrmd[2117]: access_broker_get_response: tcti_receive failed with RC 0xa0008
Mar 08 17:22:49 tpm2-abrmd[2117]: ERROR:tcti:src/util/io.c:102:write_all() failed to write to fd 5: Device or resource busy
Mar 08 17:22:49 tpm2-abrmd[2117]: ERROR:tcti:src/tss2-tcti/tcti-device.c:84:tcti_device_transmit() wrong number of bytes written. Expected 27, wrote 0.
Mar 08 17:22:49 tpm2-abrmd[2117]: AccessBroker 0x820dd9d05d6f4592 failed to transmit Tpm2Command 0x820dd9d0496e9832: 0xa000a
Mar 08 17:22:58 tpm2-abrmd[2117]: ERROR:tcti:src/util/io.c:102:write_all() failed to write to fd 5: Device or resource busy
Mar 08 17:22:58 tpm2-abrmd[2117]: ERROR:tcti:src/tss2-tcti/tcti-device.c:84:tcti_device_transmit() wrong number of bytes written. Expected 12, wrote 0.
Mar 08 17:22:58 tpm2-abrmd[2117]: AccessBroker 0x820dd9d05d6f4592 failed to transmit Tpm2Command 0x820dd9d0496e9872: 0xa000a
Mar 08 17:23:01 tpm2-abrmd[2117]: ERROR:tcti:src/util/io.c:102:write_all() failed to write to fd 5: Device or resource busy
Mar 08 17:23:01 tpm2-abrmd[2117]: ERROR:tcti:src/tss2-tcti/tcti-device.c:84:tcti_device_transmit() wrong number of bytes written. Expected 12, wrote 0.
Mar 08 17:23:01 tpm2-abrmd[2117]: AccessBroker 0x820dd9d05d6f4592 failed to transmit Tpm2Command 0x820dd9d0496e98b2: 0xa000a
Mar 08 17:23:09 tpm2-abrmd[2117]: ERROR:tcti:src/util/io.c:102:write_all() failed to write to fd 5: Device or resource busy
Mar 08 17:23:09 tpm2-abrmd[2117]: ERROR:tcti:src/tss2-tcti/tcti-device.c:84:tcti_device_transmit() wrong number of bytes written. Expected 27, wrote 0.
Mar 08 17:23:09 tpm2-abrmd[2117]: AccessBroker 0x820dd9d05d6f4592 failed to transmit Tpm2Command 0x820dd9d0496e98f2: 0xa000a


[root@localhost ~]# systemctl status tpm2-abrmd.service
● tpm2-abrmd.service - TPM2 Access Broker and Resource Management Daemon
   Loaded: loaded (/usr/lib/systemd/system/tpm2-abrmd.service; enabled; vendor preset: disabled)
   Active: active (running) since Fri 2019-03-08 16:49:30 EET; 37min ago
 Main PID: 2117 (tpm2-abrmd)
    Tasks: 6 (limit: 4915)
   Memory: 1.4M
   CGroup: /system.slice/tpm2-abrmd.service
           └─2117 /usr/sbin/tpm2-abrmd

swtpm log from host side:

Starting vTPM manufacturing as tss:tss @ pe  8. maaliskuuta 2019 13.56.13
Successfully created EK with handle 0x81010001.
  Invoking: /usr/share/swtpm/swtpm-localca --type ek --ek bac80d840000b12f0711ff92fede20f89a62b08aa494bca83868cbfbb44e75909a84bb4cc9722a0963bdbaf78a5e925ec9056039b8ecb3e11737c4bc5fc1d8e68371cfb996b0bbf213548b485f687462333d284e513f67e198746ba5723a8fb4a22594dc55693a44a4b0fcfcc115e22c28cbff197d14f914dc1f11c0bcfc2145da58846807d3c28a461af4e33d796cc4f681957a0bea08f47227bcd86d0824d4d8494c11ca84dc67a995e3ecf7f3fc92efa1c9500cce5234073d9705e1b99d32f79389bf32360b45f61ea90e75d31da7c65c5178cd300c48504df54d6350d77cb0e35086a7ecb50a52ae99c2e01abfc8e5d0f9408c49265c42331c96e10c37df --dir /var/lib/libvirt/swtpm/3a06cffd-e5f3-4293-a043-41b5d1bedfe7/tpm2 --logfile /var/log/swtpm/libvirt/qemu/fedora-30-beta-swtpm.log --vmid "fedora-30-beta:3a06cffd-e5f3-4293-a043-41b5d1bedfe7" --tpm-spec-family 2.0 --tpm-spec-level 00 --tpm-spec-revision 150 --tpm-manufacturer id:00001014 --tpm-model swtpm --tpm-version id:20170619 --configfile "/etc/swtpm-localca.conf" --optsfile "/etc/swtpm-localca.options" --tpm2
Successfully created EK certificate locally.
swtpm-localca: 
  Invoking: /usr/share/swtpm/swtpm-localca --type platform --ek bac80d840000b12f0711ff92fede20f89a62b08aa494bca83868cbfbb44e75909a84bb4cc9722a0963bdbaf78a5e925ec9056039b8ecb3e11737c4bc5fc1d8e68371cfb996b0bbf213548b485f687462333d284e513f67e198746ba5723a8fb4a22594dc55693a44a4b0fcfcc115e22c28cbff197d14f914dc1f11c0bcfc2145da58846807d3c28a461af4e33d796cc4f681957a0bea08f47227bcd86d0824d4d8494c11ca84dc67a995e3ecf7f3fc92efa1c9500cce5234073d9705e1b99d32f79389bf32360b45f61ea90e75d31da7c65c5178cd300c48504df54d6350d77cb0e35086a7ecb50a52ae99c2e01abfc8e5d0f9408c49265c42331c96e10c37df --dir /var/lib/libvirt/swtpm/3a06cffd-e5f3-4293-a043-41b5d1bedfe7/tpm2 --logfile /var/log/swtpm/libvirt/qemu/fedora-30-beta-swtpm.log --vmid "fedora-30-beta:3a06cffd-e5f3-4293-a043-41b5d1bedfe7" --tpm-spec-family 2.0 --tpm-spec-level 00 --tpm-spec-revision 150 --tpm-manufacturer id:00001014 --tpm-model swtpm --tpm-version id:20170619 --configfile "/etc/swtpm-localca.conf" --optsfile "/etc/swtpm-localca.options" --tpm2
Successfully created platform certificate locally.
swtpm-localca: 
Successfully created NVRAM area 0x01c00002 for EK certificate.
Successfully created NVRAM area 0x01c08000 for platform certificate.
Successfully activated PCR banks sha256,sha1 among sha1,sha256,sha384,sha512.
Successfully authored TPM state.
Ending vTPM manufacturing @ pe  8. maaliskuuta 2019 13.56.13

Version-Release number of selected component:
tpm2-abrmd-2.1.0-2.fc30

Additional info:
reporter:       libreport-2.10.0
backtrace_rating: 4
cmdline:        /usr/sbin/tpm2-abrmd
crash_function: access_broker_get_tpm_properties_fixed
executable:     /usr/sbin/tpm2-abrmd
journald_cursor: s=1dd43927da3c42788dfe9969a88e70c2;i=2bc1;b=092efbb158384c8c868a9f97df86b866;m=c28bcc;t=583962d541d50;x=22b19a9113d6e2b0
kernel:         5.0.0-0.rc8.git0.1.fc30.x86_64
rootdir:        /
runlevel:       N 3
type:           CCpp
uid:            59

Comment 1 Markus Linnala 2019-03-08 15:36:26 UTC
Created attachment 1542132 [details]
File: backtrace

Comment 2 Markus Linnala 2019-03-08 15:36:27 UTC
Created attachment 1542133 [details]
File: cgroup

Comment 3 Markus Linnala 2019-03-08 15:36:28 UTC
Created attachment 1542134 [details]
File: core_backtrace

Comment 4 Markus Linnala 2019-03-08 15:36:29 UTC
Created attachment 1542135 [details]
File: cpuinfo

Comment 5 Markus Linnala 2019-03-08 15:36:30 UTC
Created attachment 1542136 [details]
File: dso_list

Comment 6 Markus Linnala 2019-03-08 15:36:31 UTC
Created attachment 1542137 [details]
File: environ

Comment 7 Markus Linnala 2019-03-08 15:36:32 UTC
Created attachment 1542138 [details]
File: limits

Comment 8 Markus Linnala 2019-03-08 15:36:34 UTC
Created attachment 1542139 [details]
File: maps

Comment 9 Markus Linnala 2019-03-08 15:36:35 UTC
Created attachment 1542140 [details]
File: mountinfo

Comment 10 Markus Linnala 2019-03-08 15:36:37 UTC
Created attachment 1542141 [details]
File: open_fds

Comment 11 Markus Linnala 2019-03-08 15:36:38 UTC
Created attachment 1542142 [details]
File: proc_pid_status

Comment 12 Markus Linnala 2019-03-08 15:36:39 UTC
Created attachment 1542143 [details]
File: var_log_messages

Comment 13 taaem+bugzilla 2019-03-21 18:31:24 UTC
Similar problem has been detected:

This error spams my journalctl every few minutes after the update to Fedora 30, I don't know if this happened on Fedora 29 too. 
There seems to be nothing broken in particular but I also don't use an y tpm functionality:

Mär 21 19:11:03 x1 tpm2-abrmd[3690]: Failed to GetCapability: TPM2_CAP_TPM_PROPERTIES, TPM2_PT_FIXED: 0xa0008
Mär 21 19:10:58 x1 systemd[1]: Failed to start TPM2 Access Broker and Resource Management Daemon.

reporter:       libreport-2.10.0
backtrace_rating: 4
cmdline:        /usr/sbin/tpm2-abrmd
crash_function: access_broker_get_tpm_properties_fixed
executable:     /usr/sbin/tpm2-abrmd
journald_cursor: s=a30dfffc45574221b795f0750885dd38;i=12650f;b=00a451e91ce243c98ba3b54f707dee2b;m=2daba0f;t=5849e9e46cf81;x=f9c641a984159322
kernel:         5.0.0-300.fc30.x86_64
package:        tpm2-abrmd-2.1.1-1.fc30
reason:         tpm2-abrmd killed by SIGTRAP
rootdir:        /
runlevel:       N 5
type:           CCpp
uid:            59

Comment 14 Andrey Motoshkov 2019-03-24 16:00:17 UTC
*** Bug 1692146 has been marked as a duplicate of this bug. ***

Comment 15 angel.segarra 2019-03-25 23:02:33 UTC
On Fedora 29, just updated through DNF and after boot journal getting spammed non stop with this, as I don't use TPM I disabled the service.

Comment 16 Luke Hinds 2019-03-27 13:41:28 UTC
On Fedora 29 here and I can no longer communicate with my TPM.

If I connect directly it works OK:

# export TPM2TOOLS_TCTI="device:/dev/tpm0"

# tpm2_getrandom 8
0xA6 0xF9 0xB6 0x85 0x10 0xCC 0x71 0x92

Using tpm2-abrmd it fails:

# export TPM2TOOLS_TCTI="tabrmd:bus_name=com.intel.tss2.Tabrmd"   

# ystemctl status tpm2-abrmd  915  13:39:32
● tpm2-abrmd.service - TPM2 Access Broker and Resource Management Daemon
   Loaded: loaded (/usr/lib/systemd/system/tpm2-abrmd.service; enabled; vendor preset: disabled)
   Active: active (running) since Wed 2019-03-27 13:39:26 GMT; 13s ago
 Main PID: 2404 (tpm2-abrmd)
    Tasks: 7 (limit: 4915)
   Memory: 1.6M
   CGroup: /system.slice/tpm2-abrmd.service
           └─2404 /usr/sbin/tpm2-abrmd
Mar 27 13:39:26 neptune systemd[1]: Starting TPM2 Access Broker and Resource Management Daemon..

# tpm2_getrandom 8
ERROR: Tss2_Sys_GetRandom(0xA0008) - tcti:Fails to connect to next lower layer
ERROR: Unable to run tpm2_getrandom

# systemctl status tpm2-abrmd  917  13:39:42
● tpm2-abrmd.service - TPM2 Access Broker and Resource Management Daemon
   Loaded: loaded (/usr/lib/systemd/system/tpm2-abrmd.service; enabled; vendor preset: disabled)
   Active: active (running) since Wed 2019-03-27 13:39:26 GMT; 1min 10s ago
 Main PID: 2404 (tpm2-abrmd)
    Tasks: 6 (limit: 4915)
   Memory: 1.7M
   CGroup: /system.slice/tpm2-abrmd.service
           └─2404 /usr/sbin/tpm2-abrmd

Mar 27 13:39:26 neptune systemd[1]: Starting TPM2 Access Broker and Resource Management Daemon...
Mar 27 13:39:26 neptune systemd[1]: Started TPM2 Access Broker and Resource Management Daemon.
Mar 27 13:39:42 neptune tpm2-abrmd[2404]: WARNING:tcti:src/tss2-tcti/tcti-device.c:254:tcti_device_receive() Got EOF instead of response.
Mar 27 13:39:42 neptune tpm2-abrmd[2404]: access_broker_get_response: tcti_receive failed with RC 0xa0008

Comment 17 Milan Zink 2019-04-08 12:52:42 UTC
*** Bug 1697385 has been marked as a duplicate of this bug. ***


Note You need to log in before you can comment on or make changes to this bug.