Bug 202259 - dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3
Summary: dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3
Status: NEW
Alias: None
Product: Drivers
Classification: Unclassified
Component: Platform_x86 (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: drivers_platform_x86@kernel-bugs.osdl.org
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2019-01-14 11:31 UTC by Paul Menzel
Modified: 2019-01-31 19:21 UTC (History)
1 user (show)

See Also:
Kernel Version: 4.19.13
Tree: Mainline
Regression: No


Attachments

Description Paul Menzel 2019-01-14 11:31:57 UTC
On a Dell Latitude E7250, Linux 4.19.13 logs the error below.

    [    0.000000] DMI: Dell Inc. Latitude E7250/0TVD2T, BIOS A19 01/23/2018
    […]
    [   17.922137] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       3
    [   20.034189] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       2
    [   20.259803] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       3

Can this be fixed somehow? If this message is expected, please demote the log level?
Comment 1 Mario Limonciello 2019-01-28 14:30:55 UTC
This message is caused by something in userspace trying to use the dell-smbios WMI interface to control a filtered request.

You can see all the filtered requests in the source.
https://github.com/torvalds/linux/blob/master/drivers/platform/x86/dell-smbios-base.c#L48

> Can this be fixed somehow? If this message is expected, please demote the log
> level?

Take a look at what userspace apps you're using that talk to dell-smbios.
From kernel perspective this is expected behavior and a message in kernel logs lets you know why your userspace request has failed.
Comment 2 Paul Menzel 2019-01-28 15:06:40 UTC
(In reply to Mario Limonciello from comment #1)
> This message is caused by something in userspace trying to use the
> dell-smbios WMI interface to control a filtered request.
> 
> You can see all the filtered requests in the source.
> https://github.com/torvalds/linux/blob/master/drivers/platform/x86/dell-
> smbios-base.c#L48

According to `include/uapi/linux/wmi.h`,

    #define CLASS_FLASH_INTERFACE   7
    #define SELECT_FLASH_INTERFACE  3

shouldn’t 7/3 be the line below?

    {CAP_SYS_ADMIN, CLASS_FLASH_INTERFACE,  SELECT_FLASH_INTERFACE},

> > Can this be fixed somehow? If this message is expected, please demote the
> log
> > level?
> 
> Take a look at what userspace apps you're using that talk to dell-smbios.

I am pretty sure, it’s fwupdmgr. How would I find out? How should a normal user find out?

> From kernel perspective this is expected behavior and a message in kernel
> logs lets you know why your userspace request has failed.

If it is the expected behavior, the log level should be demoted to `KERN_NOTICE` or `KERN_INFO`.

#define KERN_EMERG      KERN_SOH "0"    /* system is unusable */
#define KERN_ALERT      KERN_SOH "1"    /* action must be taken immediately */
#define KERN_CRIT       KERN_SOH "2"    /* critical conditions */
#define KERN_ERR        KERN_SOH "3"    /* error conditions */
#define KERN_WARNING    KERN_SOH "4"    /* warning conditions */
#define KERN_NOTICE     KERN_SOH "5"    /* normal but significant condition */
#define KERN_INFO       KERN_SOH "6"    /* informational */
#define KERN_DEBUG      KERN_SOH "7"    /* debug-level messages */
Comment 3 Mario Limonciello 2019-01-28 15:20:10 UTC
I wouldn't see this as normal behavior.

Can you crank of dyndbg for dell-smbios and see if it better logs what's going on?
Comment 4 Paul Menzel 2019-01-28 17:46:16 UTC
(In reply to Mario Limonciello from comment #3)

[…]

> Can you crank of dyndbg for dell-smbios and see if it better logs what's
> going on?

Unfortunately, adding `dyndbg='file drivers/platform/x86/* +p'` to the Linux kernel command line using GRUB, the quotes are mangled.

    $ more /proc/cmdline 
BOOT_IMAGE=/vmlinuz-4.20.0-trunk-amd64 root=UUID=aa6034bb-8cc1-4111-a0a7-9
b3f5e13e281 ro quiet noisapnp initcall_debug log_buf_len=8M random.trust_c
pu=on cryptomgr.notests "dyndbg=file drivers/platform/x86/* +p"

Doing it during run-time and starting `fwupd` again, the messages below are logged.

$ echo "file drivers/platform/x86/* +p" | sudo tee /sys/kernel/debug/dynamic_debug/control
$ sudo systemctl restart fwupd
$ sudo dmesg
[…]
[  412.723376] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: whitelisted capable command: 17/22
[  412.723378] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: evaluating: 17/22 [0,0,0,0]
[  412.724466] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: result: [fffffffe,00000000,00000000,00000000]
[  412.724512] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: invalid command, supported commands: 0x40b61f5f
[  412.724514] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       3
[  414.771116] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: invalid command, supported commands: 0x40b61f5f
[  414.771126] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       2
[  414.993447] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: whitelisted capable command: 17/22
[  414.993450] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: evaluating: 17/22 [0,0,0,0]
[  414.994437] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: result: [fffffffe,00000000,00000000,00000000]
[  414.994478] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: invalid command, supported commands: 0x40b61f5f
[  414.994480] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       3
Comment 5 Mario Limonciello 2019-01-28 22:43:57 UTC
> invalid command, supported commands: 0x40b61f5f

Ah, that's the interesting one.  So what that's saying is this particular platform isn't supposed to support calling commands with class 7.

Can you please share fwupd daemon verbose output?  I'd like to see how the daemon is reacting to this.
Comment 6 Paul Menzel 2019-01-28 23:00:59 UTC
(In reply to Mario Limonciello from comment #5)

[…]

> Can you please share fwupd daemon verbose output?  I'd like to see how the
> daemon is reacting to this.

I added the switch `-v` to the ExecStart line in the service unit.

Jan 28 23:58:54 ersatz systemd[1]: Starting Firmware update daemon...
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0738 FuDebug              Verbose debugging enabled (on console 0)
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0739 FuConfig             loading config values from /etc/fwupd/daemon.conf
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0740 Fwupd                looking for os-release at /etc/os-release
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0741 As                   run AsStore:load-installed{/usr/share/fwupd/metainfo}
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0741 As                   adding existing file: /usr/share/fwupd/metainfo/org.freedesktop.fwupd.remotes.lvfs-testing.metainfo.xml
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0741 As                   adding existing file: /usr/share/fwupd/metainfo/org.freedesktop.fwupd.remotes.lvfs.metainfo.xml
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0741 As                   Emitting ::changed() [load-installed]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0741 FuConfig             using config path of /etc/fwupd
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             loading config from /etc/fwupd/remotes.d/vendor.conf
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             loading config from /etc/fwupd/remotes.d/lvfs-testing.conf
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             loading config from /etc/fwupd/remotes.d/lvfs.conf
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             loading config from /etc/fwupd/remotes.d/fwupd.conf
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             using config path of /usr/lib/fwupd
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             ordering lvfs-testing=lvfs+1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             ordering lvfs=fwupd+1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuConfig             ordering lvfs-testing=lvfs+1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property Manufacturer=Dell Inc.
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property EnclosureKind=9
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              ignoring Family: no data available
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property ProductName=Latitude E7250
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property ProductSku=062D
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property BiosVendor=Dell Inc.
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property BiosVersion=A19
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property BiosMajorRelease=41
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property BiosMinorRelease=13
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property BaseboardManufacturer=Dell Inc.
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0742 FuHwids              smbios property BaseboardProduct=0TVD2T
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-0 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-1 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {045d681f-35c2-5a0b-957a-2cce951ed346}   <- Dell Inc.&Latitude E7250&Dell Inc.&A19&41&13
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-3 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-4 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-5 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {22f213f6-3907-50d2-9dab-9ba735103209}   <- Dell Inc.&62D&Dell Inc.&TVD2T
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {d7782004-c9d3-5fec-be56-f28398fbb8ae}   <- Dell Inc.&62D
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {1a8bdf69-2384-5a00-9191-ae69be640556}   <- Dell Inc.&Latitude E7250&Dell Inc.&TVD2T
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {61599a10-55fd-569f-bae5-8cd6dc7fd5f8}   <- Dell Inc.&Latitude E7250
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-10 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              HardwareID-11 is not available, not available as 'Family' unknown
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {29ca4080-4d68-5395-afe2-35fa9a4f13bf}   <- Dell Inc.&9
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {bf33a40b-0eac-5baa-955a-86b011328d44}   <- Dell Inc.&Dell Inc.&TVD2T
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuHwids              {85d38fda-fc0e-5c6f-808f-076984ae7978}   <- Dell Inc.
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/altos.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/colorhug.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/csr-aiaiai.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0743 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/dell-dock.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0744 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/dell.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0744 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/dfu.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/ebitdo.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/flashrom.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/nitrokey.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/rts54hid.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/rts54hub.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/steelseries.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/superio.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/synapticsmst.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0745 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/unifying.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0746 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/wacomhid.quirk
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0746 FuQuirks             now 179 quirk entries
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0746 FuQuirks             no /var/lib/fwupd/quirks.d, skipping
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0746 FuEngine             remote lvfs-testing not enabled, so skipping
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0746 As                   run AsStore:store-from-file{/var/lib/fwupd/remotes.d/lvfs/metadata.xml.gz}
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0758 As                   run AsStore:store-from-root
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0758 As                   using icon path /var/lib/fwupd/remotes.d/lvfs/icons
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0763 As                   run AsStore:match-addons
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0763 As                   Emitting ::changed() [from-root]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   Emitting ::changed() [add-apps]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   run AsStore:store-from-file{/usr/share/fwupd/remotes.d/fwupd/metadata.xml}
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   run AsStore:store-from-root
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   using icon path /usr/share/fwupd/remotes.d/fwupd/icons
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   run AsStore:match-addons
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   Emitting ::changed() [from-root]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 As                   Emitting ::changed() [add-apps]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 FuEngine             remote vendor not enabled, so skipping
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0766 FuEngine             219 components now in store
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0771 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_synapticsmst.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0771 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_synapticsmst.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0771 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_redfish.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0772 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_redfish.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0772 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_steelseries.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0772 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_steelseries.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0772 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0772 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0772 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_unifying.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0773 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_unifying.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0773 FuPlugin             added udev subsystem watch of hidraw
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0773 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell_esrt.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0773 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_uefi.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_uefi.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hid.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hid.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nitrokey.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nitrokey.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hub.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hub.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_upower.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_upower.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0774 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0775 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0775 FuPluginDell         Using libsmbios 2.4
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0775 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dfu.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0775 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_colorhug.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0776 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_colorhug.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0776 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_csr.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0776 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_csr.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0776 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nvme.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0776 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nvme.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0777 FuPlugin             added udev subsystem watch of nvme
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0777 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_altos.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0777 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_altos.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0777 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_superio.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0777 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell_dock.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0777 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell_dock.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0778 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt_power.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0778 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt_power.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0779 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_ebitdo.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0779 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_ebitdo.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0779 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_flashrom.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0779 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_flashrom.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0779 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_udev.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0780 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_udev.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0780 FuPlugin             added udev subsystem watch of pci
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0780 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_wacomhid.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0780 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_wacomhid.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0780 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_amt.so
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0780 FuPluginList         synapticsmst [0] to be ordered after dell [0] so promoting to [1]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPluginList         uefi [0] to be ordered after upower [0] so promoting to [1]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPluginList         dell [0] to be ordered after uefi [1] so promoting to [2]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPluginList         synapticsmst [1] to be ordered after dell [2] so promoting to [3]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPluginList         thunderbolt_power [0] to be ordered after thunderbolt [0] so promoting to [1]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPluginList         dell_dock [0] better than synapticsmst [0] so bumping to [1]
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuEngine             Emitting PropertyChanged('Status'='loading')
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuMain               Emitting PropertyChanged('Status'='loading')
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPlugin             performing startup() on redfish
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuEngine             disabling plugin because: failed to startup redfish: no SMBIOS table
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPlugin             performing startup() on unifying
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuEngine             disabling plugin because: failed to startup unifying: no kernel support for CONFIG_HIDRAW
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPlugin             performing startup() on dell_esrt
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuEngine             disabling plugin because: failed to startup dell_esrt: token 1122 is not boolean
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0781 FuPlugin             performing startup() on upower
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0784 FuPlugin             performing startup() on flashrom
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0784 FuPlugin             performing startup() on uefi
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0784 FuPlugin             performing startup() on dell
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0784 FuPluginDell         UEFI capsule firmware updating not supported
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0784 FuPlugin             performing coldplug_prepare() on dell
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuPluginDell         failed to execute SMI: -1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuPluginDell         Unable to toggle MST hub GPIO to 1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuEngine             got coldplug delay of 2000ms, global maximum is now 2000ms
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuEngine             sleeping for 2000ms
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0786 FuPlugin             performing coldplug() on thunderbolt
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0787 FuPlugin             performing coldplug() on superio
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0787 FuPlugin             performing coldplug() on amt
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0814 FuPluginAmt          call write length = 12
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0822 FuPluginAmt          write success
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0823 FuPluginAmt          call read length = 5120
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0823 FuPluginAmt          read succeeded with result 525
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0823 FuDevice             using e2623122c99d58220498aacbfcfdb1baebbae3c5 for /dev/mei0
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0823 FuDevice             using 230c8b18-8d9b-53ec-838b-6cfc0383493a for main-system-firmware
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0823 FuPluginAmt          call write length = 12
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0827 FuPluginAmt          write success
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0827 FuPluginAmt          call read length = 5120
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0827 FuPluginAmt          read succeeded with result 20
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuPlugin             emit added from amt: e2623122c99d58220498aacbfcfdb1baebbae3c5
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuPlugin             performing fu_plugin_device_registered() on uefi
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuPlugin             performing fu_plugin_device_registered() on thunderbolt_power
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuPlugin             performing fu_plugin_device_registered() on dell
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuDeviceList         ::added e2623122c99d58220498aacbfcfdb1baebbae3c5
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuPlugin             performing coldplug() on uefi
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0828 FuEngine             disabling plugin because: failed to coldplug uefi: Fehler beim Öffnen des Ordners »/sys/firmware/efi/esrt/entries«: Datei oder Verzeichnis nicht gefunden
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0829 FuPlugin             performing coldplug() on thunderbolt_power
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0833 FuPluginThunderbolt  Bolt force power support: 0
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuEngine             disabling plugin because: failed to coldplug thunderbolt_power: No support for force power via kernel or bolt
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPlugin             performing coldplug() on dell
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPluginDell         failed to run query 7/3
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPluginDell         No switchable TPM detected
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPlugin             performing coldplug() on synapticsmst
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0945 FuPluginSynapticsMST No device found on drm_dp_aux1: failed to read device: failed to read
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0056 FuPluginSynapticsMST No device found on drm_dp_aux2: failed to read device: failed to read
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0057 FuPluginSynapticsMST No device found on drm_dp_aux0: no device
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0057 FuPlugin             performing coldplug_cleanup() on dell
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPluginDell         failed to execute SMI: -1
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPluginDell         Unable to toggle MST hub GPIO to 0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuEngine             using plugins: steelseries, thunderbolt, rts54hid, nitrokey, rts54hub, upower, dfu, colorhug, csr, nvme, altos, superio, dell_dock, ebitdo, flashrom, udev, wacomhid, amt, dell, synapticsmst
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using 5a915df0-b47a-5840-bd04-493fac9aeab4 for USB\VID_1BCF&PID_28AE&REV_4702
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using b791475e-4dc8-5598-97b5-c16d2ddf8eb9 for USB\VID_1BCF&PID_28AE
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using 51d4ea4b-7015-5538-8d94-4a1513e88367 for USB\VID_1BCF
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuEngine             no plugin specified for USB device 1bcf:28ae
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPlugin             performing usb_device_added() on dfu
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using 5a915df0-b47a-5840-bd04-493fac9aeab4 for USB\VID_1BCF&PID_28AE&REV_4702
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using b791475e-4dc8-5598-97b5-c16d2ddf8eb9 for USB\VID_1BCF&PID_28AE
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using 51d4ea4b-7015-5538-8d94-4a1513e88367 for USB\VID_1BCF
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuEngine             dfu ignoring: 1bcf:28ae is not supported: no DFU interfaces
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using 5aed4710-f3fa-5f12-8732-258e8390a5e2 for USB\VID_8087&PID_0A2A&REV_0001
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using 4a79a3ba-bbbc-5ae2-b5df-aecb1ac30e1d for USB\VID_8087&PID_0A2A
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuEngine             no plugin specified for USB device 8087:0a2a
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuPlugin             performing usb_device_added() on dfu
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 5aed4710-f3fa-5f12-8732-258e8390a5e2 for USB\VID_8087&PID_0A2A&REV_0001
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 4a79a3ba-bbbc-5ae2-b5df-aecb1ac30e1d for USB\VID_8087&PID_0A2A
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             dfu ignoring: 8087:0a2a is not supported: no DFU interfaces
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 3767ef82-48ca-58af-82ca-57f0103e56c7 for USB\VID_8087&PID_8001&REV_0003
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 17d1ef41-ec58-5934-940b-a43c3cea0f76 for USB\VID_8087&PID_8001
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             no plugin specified for USB device 8087:8001
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuPlugin             performing usb_device_added() on dfu
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 3767ef82-48ca-58af-82ca-57f0103e56c7 for USB\VID_8087&PID_8001&REV_0003
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 17d1ef41-ec58-5934-940b-a43c3cea0f76 for USB\VID_8087&PID_8001
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             dfu ignoring: 8087:8001 is not supported: no DFU interfaces
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             no plugin specified for USB device 1d6b:0002
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuPlugin             performing usb_device_added() on dfu
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             dfu ignoring: 1d6b:0002 is not supported: no DFU interfaces
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 2c4ea2a1-04c3-5dc9-adcf-e9ddc7a9663e for USB\VID_1D6B&PID_0003&REV_0420
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 69c66597-c59d-5593-8d85-b261deae893a for USB\VID_1D6B&PID_0003
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             no plugin specified for USB device 1d6b:0003
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuPlugin             performing usb_device_added() on dfu
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 2c4ea2a1-04c3-5dc9-adcf-e9ddc7a9663e for USB\VID_1D6B&PID_0003&REV_0420
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 69c66597-c59d-5593-8d85-b261deae893a for USB\VID_1D6B&PID_0003
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             dfu ignoring: 1d6b:0003 is not supported: no DFU interfaces
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             no plugin specified for USB device 1d6b:0002
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuPlugin             performing usb_device_added() on dfu
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0059 FuEngine             dfu ignoring: 1d6b:0002 is not supported: no DFU interfaces
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0060 FuEngine             0 devices with subsystem hidraw
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0060 FuEngine             0 devices with subsystem nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0063 FuEngine             16 devices with subsystem pci
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using c5433995-45aa-5a63-88b5-c123891798bf for PCI\VEN_8086&DEV_1604&REV_09
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using b1127e0c-835f-5835-97a0-e8474eee4d3a for PCI\VEN_8086&DEV_1604
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:00.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using 19e1f63e-bef0-5582-b236-364069bcd0d5 for PCI\VEN_8086&DEV_1616&REV_09
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using 64c0ea55-d861-5dd0-aa6b-bcc307f57ed4 for PCI\VEN_8086&DEV_1616
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:02.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDevice             using bbbf1ce3d1cf15550c3760b354592040292415bb for PCI_SLOT_NAME=0000:00:02.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuPlugin             emit added from udev: bbbf1ce3d1cf15550c3760b354592040292415bb
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuPlugin             performing fu_plugin_device_registered() on dell
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0064 FuDeviceList         ::added bbbf1ce3d1cf15550c3760b354592040292415bb
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using d70cd485-679e-5c82-bc3f-feed70ee0009 for PCI\VEN_8086&DEV_160C&REV_09
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using e45da685-52fa-5fb8-80fa-8d29b5b23313 for PCI\VEN_8086&DEV_160C
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:03.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using 020eea0b-04ca-54e3-b770-45bbf97f8513 for PCI\VEN_8086&DEV_1603&REV_09
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using 8ac4050e-5982-5ffe-9432-8bc1c87613d0 for PCI\VEN_8086&DEV_1603
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:04.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using b991e033-5993-5e13-9b1a-e74411c71d73 for PCI\VEN_8086&DEV_9CB1&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using 73d59868-686e-5eff-a75a-0b039687a111 for PCI\VEN_8086&DEV_9CB1
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0065 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:14.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuDevice             using 0315bf8e-e557-5818-b25e-721868f994b3 for PCI\VEN_8086&DEV_9CBA&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuDevice             using 6373f81f-4625-5859-a1e9-075dc184c890 for PCI\VEN_8086&DEV_9CBA
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:16.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuDevice             using a80f2a70-1cfc-5fff-a7ef-00720fe5c12d for PCI\VEN_8086&DEV_15A2&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuDevice             using d2adad9f-bfdf-5f6a-b094-1b7912e95d20 for PCI\VEN_8086&DEV_15A2
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:19.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0066 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using 0ff9708f-5ac7-5138-88b7-914ceedebc5e for PCI\VEN_8086&DEV_9CA0&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using 09db5ff1-7c50-592a-a81c-43dcca0d4850 for PCI\VEN_8086&DEV_9CA0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1b.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using 0a2cfedb-5fe4-5aa3-ade0-3ba1a7cd17f9 for PCI\VEN_8086&DEV_9C90&REV_E3
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using 63ebb625-2f2b-5ef4-92ab-1421d8a327fd for PCI\VEN_8086&DEV_9C90
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using 8134a7e2-e89c-5fa6-9956-24a72594fd6c for PCI\VEN_1217&DEV_8520&REV_01
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using 2c36d225-047b-593e-81d2-30da1aa8a9b3 for PCI\VEN_1217&DEV_8520
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuDevice             using dda0fd11-2cfa-590c-a99d-f138ee552297 for PCI\VEN_1217
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.0/0000:01:00.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0067 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using f3369ade-7ee6-56e6-938e-39adcd68e1d5 for PCI\VEN_8086&DEV_9C96&REV_E3
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using fcc4db8f-5b19-5fde-b49f-28a957ec6be4 for PCI\VEN_8086&DEV_9C96
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.3
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using ee141425-2454-59f8-9f48-38c54caa231e for PCI\VEN_8086&DEV_095A&REV_59
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using 7535d47f-d149-5cb4-9999-082b9475f74e for PCI\VEN_8086&DEV_095A
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using d6a8f1d4-6fa5-5657-b53f-40c05f3fc98b for PCI\VEN_8086&DEV_9CA6&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using 9bd83d10-1f99-5324-b297-357d712d0f2b for PCI\VEN_8086&DEV_9CA6
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1d.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0068 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using 62449711-aa22-594c-aff9-a9079ac5e471 for PCI\VEN_8086&DEV_9CC3&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using 5edbaecf-0cd5-5d8e-bb10-623ab77bf58b for PCI\VEN_8086&DEV_9CC3
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1f.0
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using f6e717f1-a386-5821-b2d5-7fd5a709d993 for PCI\VEN_8086&DEV_282A&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using ab5c1187-5e30-571c-a71b-2fbcd0b72eac for PCI\VEN_8086&DEV_282A
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1f.2
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using 6a075191-a79f-5c57-a9d3-9fc91b96860a for PCI\VEN_8086&DEV_9CA2&REV_03
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using 2e3cdbdd-8ebe-5eed-9ff3-c6625d6c3cd0 for PCI\VEN_8086&DEV_9CA2
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0069 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1f.3
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0070 FuPlugin             performing udev_device_added() on nvme
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0070 FuPlugin             performing udev_device_added() on udev
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0070 FuHistory            trying to open database '/var/lib/fwupd/pending.db'
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0070 FuHistory            got schema version of 2
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0070 FuEngine             Emitting PropertyChanged('Status'='idle')
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0070 FuMain               Emitting PropertyChanged('Status'='idle')
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0072 FuMain               Daemon ready for requests
Jan 28 23:58:57 ersatz systemd[1]: Started Firmware update daemon.
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0073 FuMain               FuMain: acquired name: org.freedesktop.fwupd
Comment 7 Mario Limonciello 2019-01-28 23:09:20 UTC
Here's the relevant pieces.

Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0784 FuPlugin             performing coldplug_prepare() on dell
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuPluginDell         failed to execute SMI: -1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuPluginDell         Unable to toggle MST hub GPIO to 1
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuEngine             got coldplug delay of 2000ms, global maximum is now 2000ms
Jan 28 23:58:54 ersatz fwupd[9364]: 22:58:54:0786 FuEngine             sleeping for 2000ms

Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPlugin             performing coldplug() on dell
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPluginDell         failed to run query 7/3
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPluginDell         No switchable TPM detected
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0834 FuPlugin             performing coldplug() on synapticsmst
Jan 28 23:58:56 ersatz fwupd[9364]: 22:58:56:0945 FuPluginSynapticsMST No device found on drm_dp_aux1: failed to read device: failed to read
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0056 FuPluginSynapticsMST No device found on drm_dp_aux2: failed to read device: failed to read
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0057 FuPluginSynapticsMST No device found on drm_dp_aux0: no device
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0057 FuPlugin             performing coldplug_cleanup() on dell
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPluginDell         failed to execute SMI: -1
Jan 28 23:58:57 ersatz fwupd[9364]: 22:58:57:0058 FuPluginDell         Unable to toggle MST hub GPIO to 0

So next questions:
* Does this system have type-C?
* Does your TPM work?
Comment 8 Paul Menzel 2019-01-29 08:43:55 UTC
(In reply to Mario Limonciello from comment #7)

[…]

> So next questions:
> * Does this system have type-C?

It’s a Dell Latitude E7250. I do not see a USB Type-C port on the laptop. No idea, if there is one on a docking station.

> * Does your TPM work?

No idea.

    $ more /proc/version
    Linux version 4.20.0-trunk-amd64 (debian-kernel@lists.debian.org) (gcc version 8
.2.0 (Debian 8.2.0-13)) #1 SMP Debian 4.20-1~exp1 (2018-12-24)
    $ journalctl -k | grep -i tpm
    $ lsmod | grep tpm

Probably not.
Comment 9 Paul Menzel 2019-01-29 09:54:09 UTC
(In reply to Paul Menzel from comment #8)
> (In reply to Mario Limonciello from comment #7)
> 
> […]
> 
> > So next questions:
> > * Does this system have type-C?
> 
> It’s a Dell Latitude E7250. I do not see a USB Type-C port on the laptop. No
> idea, if there is one on a docking station.
> 
> > * Does your TPM work?
> 
> No idea.
> 
>     $ more /proc/version
>     Linux version 4.20.0-trunk-amd64 (debian-kernel@lists.debian.org) (gcc
> version 8
> .2.0 (Debian 8.2.0-13)) #1 SMP Debian 4.20-1~exp1 (2018-12-24)
>     $ journalctl -k | grep -i tpm
>     $ lsmod | grep tpm
> 
> Probably not.

It was disabled in the firmware. With the TPM 1.2 (considered insecure) enabled, the messages below are logged, but it looks like nothing changed regarding the errors.

$ sudo dmesg | grep -i -e tpm -e dell-smbios
[    6.036066] calling  tpm_init+0x0/0xf4 [tpm] @ 341
[    6.036283] initcall tpm_init+0x0/0xf4 [tpm] returned 0 after 206 usecs
[    6.081192] calling  init_tis+0x0/0x1000 [tpm_tis] @ 341
[    6.082901] tpm_tis 00:08: 1.2 TPM (device-id 0x3205, rev-id 80)
[    6.099385] probe of dell-smbios.0 returned 1 after 10 usecs
[    6.100427] probe of dell-smbios.1 returned 1 after 5 usecs
[    6.112657] initcall init_tis+0x0/0x1000 [tpm_tis] returned 0 after 2300 usecs
[   17.636334] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       3
[   19.751227] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       2
[   19.974032] dell-smbios A80593CE-A997-11DA-B012-B622A1EF5492: Invalid call 7/3:       3
Comment 10 Mario Limonciello 2019-01-29 20:17:03 UTC
Well it's good that fwupd doesn't explode on these failures and properly handles them.

I'd like to confirm one more thing though:  Can you please unbind the WMI GUID from dell-smbios (example: # echo A80593CE-A997-11DA-B012-B622A1EF5492 | tee /sys/bus/wmi/drivers/dell-smbios/unbind)?

After doing that can you run fwupd one more time?  My expectation is that dell-smbios errors in the console will go away, but I would like to see what fwupd does on this system.

Based on that we should have a better idea how to handle this scenario.
Comment 11 Paul Menzel 2019-01-30 12:03:08 UTC
(In reply to Mario Limonciello from comment #10)
> Well it's good that fwupd doesn't explode on these failures and properly
> handles them.
> 
> I'd like to confirm one more thing though:  Can you please unbind the WMI
> GUID from dell-smbios (example: # echo A80593CE-A997-11DA-B012-B622A1EF5492
> | tee /sys/bus/wmi/drivers/dell-smbios/unbind)?
> 
> After doing that can you run fwupd one more time?  My expectation is that
> dell-smbios errors in the console will go away, but I would like to see what
> fwupd does on this system.
> 
> Based on that we should have a better idea how to handle this scenario.

Please find the fwupd log after the unbinding below. There were indeed no Linux error messages after restarting fwupd.

Jan 30 13:00:46 ersatz systemd[1]: Stopped Firmware update daemon.
Jan 30 13:00:46 ersatz systemd[1]: Starting Firmware update daemon...
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0505 FuDebug              Verbose debugging enabled (on console 0)
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0505 FuConfig             loading config values from /etc/fwupd/daemon.conf
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0507 Fwupd                looking for os-release at /etc/os-release
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0507 As                   run AsStore:load-installed{/usr/share/fwupd/metainfo}
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0507 As                   adding existing file: /usr/share/fwupd/metainfo/org.freedesktop.fwupd.remotes.lvfs-testing.metainfo.xml
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0507 As                   adding existing file: /usr/share/fwupd/metainfo/org.freedesktop.fwupd.remotes.lvfs.metainfo.xml
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 As                   Emitting ::changed() [load-installed]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 FuConfig             using config path of /etc/fwupd
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 FuConfig             loading config from /etc/fwupd/remotes.d/vendor.conf
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 FuConfig             loading config from /etc/fwupd/remotes.d/lvfs-testing.conf
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 FuConfig             loading config from /etc/fwupd/remotes.d/lvfs.conf
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 FuConfig             loading config from /etc/fwupd/remotes.d/fwupd.conf
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0508 FuConfig             using config path of /usr/lib/fwupd
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuConfig             ordering lvfs-testing=lvfs+1
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuConfig             ordering lvfs=fwupd+1
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuConfig             ordering lvfs-testing=lvfs+1
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property Manufacturer=Dell Inc.
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property EnclosureKind=9
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              ignoring Family: no data available
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property ProductName=Latitude E7250
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property ProductSku=062D
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property BiosVendor=Dell Inc.
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property BiosVersion=A19
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property BiosMajorRelease=41
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property BiosMinorRelease=13
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property BaseboardManufacturer=Dell Inc.
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              smbios property BaseboardProduct=0TVD2T
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-0 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-1 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {045d681f-35c2-5a0b-957a-2cce951ed346}   <- Dell Inc.&Latitude E7250&Dell Inc.&A19&41&13
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-3 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-4 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-5 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {22f213f6-3907-50d2-9dab-9ba735103209}   <- Dell Inc.&62D&Dell Inc.&TVD2T
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {d7782004-c9d3-5fec-be56-f28398fbb8ae}   <- Dell Inc.&62D
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {1a8bdf69-2384-5a00-9191-ae69be640556}   <- Dell Inc.&Latitude E7250&Dell Inc.&TVD2T
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {61599a10-55fd-569f-bae5-8cd6dc7fd5f8}   <- Dell Inc.&Latitude E7250
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-10 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              HardwareID-11 is not available, not available as 'Family' unknown
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {29ca4080-4d68-5395-afe2-35fa9a4f13bf}   <- Dell Inc.&9
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {bf33a40b-0eac-5baa-955a-86b011328d44}   <- Dell Inc.&Dell Inc.&TVD2T
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuHwids              {85d38fda-fc0e-5c6f-808f-076984ae7978}   <- Dell Inc.
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/altos.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0509 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/colorhug.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0510 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/csr-aiaiai.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0510 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/dell-dock.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0510 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/dell.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0510 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/dfu.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0511 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/ebitdo.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0511 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/flashrom.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0511 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/nitrokey.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0511 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/rts54hid.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0511 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/rts54hub.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0511 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/steelseries.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/superio.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/synapticsmst.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/unifying.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuQuirks             loading quirks from /usr/share/fwupd/quirks.d/wacomhid.quirk
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuQuirks             now 179 quirk entries
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuQuirks             no /var/lib/fwupd/quirks.d, skipping
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 FuEngine             remote lvfs-testing not enabled, so skipping
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0512 As                   run AsStore:store-from-file{/var/lib/fwupd/remotes.d/lvfs/metadata.xml.gz}
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0524 As                   run AsStore:store-from-root
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0524 As                   using icon path /var/lib/fwupd/remotes.d/lvfs/icons
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0529 As                   run AsStore:match-addons
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0529 As                   Emitting ::changed() [from-root]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0531 As                   Emitting ::changed() [add-apps]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 As                   run AsStore:store-from-file{/usr/share/fwupd/remotes.d/fwupd/metadata.xml}
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 As                   run AsStore:store-from-root
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 As                   using icon path /usr/share/fwupd/remotes.d/fwupd/icons
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 As                   run AsStore:match-addons
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 As                   Emitting ::changed() [from-root]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 As                   Emitting ::changed() [add-apps]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 FuEngine             remote vendor not enabled, so skipping
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0532 FuEngine             219 components now in store
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0537 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_synapticsmst.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0537 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_synapticsmst.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0537 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_redfish.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0538 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_redfish.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0538 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_steelseries.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0538 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_steelseries.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0538 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0538 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0539 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_unifying.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0539 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_unifying.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0539 FuPlugin             added udev subsystem watch of hidraw
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0539 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell_esrt.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0539 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_uefi.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_uefi.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hid.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hid.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nitrokey.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nitrokey.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hub.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_rts54hub.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0540 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_upower.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0541 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_upower.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0541 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0541 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0541 FuPluginDell         Using libsmbios 2.4
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0541 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dfu.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0542 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_colorhug.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0542 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_colorhug.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0542 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_csr.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0542 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_csr.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0542 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nvme.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0543 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_nvme.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0543 FuPlugin             added udev subsystem watch of nvme
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0543 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_altos.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0543 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_altos.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0543 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_superio.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0543 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell_dock.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0544 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_dell_dock.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0544 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt_power.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0544 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_thunderbolt_power.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0545 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_ebitdo.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0545 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_ebitdo.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0545 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_flashrom.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0546 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_flashrom.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0546 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_udev.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0546 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_udev.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0546 FuPlugin             added udev subsystem watch of pci
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0546 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_wacomhid.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0546 FuPlugin             performing init() on /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_wacomhid.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuEngine             adding plugin /usr/lib/x86_64-linux-gnu/fwupd-plugins-3/libfu_plugin_amt.so
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPluginList         synapticsmst [0] to be ordered after dell [0] so promoting to [1]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPluginList         uefi [0] to be ordered after upower [0] so promoting to [1]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPluginList         dell [0] to be ordered after uefi [1] so promoting to [2]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPluginList         synapticsmst [1] to be ordered after dell [2] so promoting to [3]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPluginList         thunderbolt_power [0] to be ordered after thunderbolt [0] so promoting to [1]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPluginList         dell_dock [0] better than synapticsmst [0] so bumping to [1]
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuEngine             Emitting PropertyChanged('Status'='loading')
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuMain               Emitting PropertyChanged('Status'='loading')
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPlugin             performing startup() on redfish
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuEngine             disabling plugin because: failed to startup redfish: no SMBIOS table
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPlugin             performing startup() on unifying
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuEngine             disabling plugin because: failed to startup unifying: no kernel support for CONFIG_HIDRAW
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0547 FuPlugin             performing startup() on dell_esrt
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0548 FuEngine             disabling plugin because: failed to startup dell_esrt: token 1122 is not boolean
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0548 FuPlugin             performing startup() on upower
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPlugin             performing startup() on flashrom
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPlugin             performing startup() on uefi
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPlugin             performing startup() on dell
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPluginDell         UEFI capsule firmware updating not supported
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPlugin             performing coldplug_prepare() on dell
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPluginDell         SMI execution returned error: -3
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuPluginDell         Unable to toggle MST hub GPIO to 1
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuEngine             got coldplug delay of 2000ms, global maximum is now 2000ms
Jan 30 13:00:46 ersatz fwupd[4889]: 12:00:46:0551 FuEngine             sleeping for 2000ms
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0552 FuPlugin             performing coldplug() on thunderbolt
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0552 FuPlugin             performing coldplug() on superio
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0552 FuPlugin             performing coldplug() on amt
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0584 FuPluginAmt          call write length = 12
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0587 FuPluginAmt          write success
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0587 FuPluginAmt          call read length = 5120
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0587 FuPluginAmt          read succeeded with result 525
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0587 FuDevice             using e2623122c99d58220498aacbfcfdb1baebbae3c5 for /dev/mei0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0587 FuDevice             using 230c8b18-8d9b-53ec-838b-6cfc0383493a for main-system-firmware
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0587 FuPluginAmt          call write length = 12
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0593 FuPluginAmt          write success
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0593 FuPluginAmt          call read length = 5120
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0593 FuPluginAmt          read succeeded with result 20
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0593 FuPlugin             emit added from amt: e2623122c99d58220498aacbfcfdb1baebbae3c5
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0594 FuPlugin             performing fu_plugin_device_registered() on uefi
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0594 FuPlugin             performing fu_plugin_device_registered() on thunderbolt_power
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0594 FuPlugin             performing fu_plugin_device_registered() on dell
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0594 FuDeviceList         ::added e2623122c99d58220498aacbfcfdb1baebbae3c5
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0594 FuPlugin             performing coldplug() on uefi
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0595 FuEngine             disabling plugin because: failed to coldplug uefi: Fehler beim Öffnen des Ordners »/sys/firmware/efi/esrt/entries«: Datei oder Verzeichnis nicht gefunden
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0595 FuPlugin             performing coldplug() on thunderbolt_power
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0600 FuPluginThunderbolt  Bolt force power support: 0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0600 FuEngine             disabling plugin because: failed to coldplug thunderbolt_power: No support for force power via kernel or bolt
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0600 FuPlugin             performing coldplug() on dell
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0601 FuPluginDell         Failed to query system for TPM information: (4294967293)
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0601 FuPluginDell         No switchable TPM detected
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0601 FuPlugin             performing coldplug() on synapticsmst
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0712 FuPluginSynapticsMST No device found on drm_dp_aux1: failed to read device: failed to read
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0823 FuPluginSynapticsMST No device found on drm_dp_aux2: failed to read device: failed to read
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0823 FuPluginSynapticsMST No device found on drm_dp_aux0: no device
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPlugin             performing coldplug_cleanup() on dell
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPluginDell         SMI execution returned error: -3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPluginDell         Unable to toggle MST hub GPIO to 0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuEngine             using plugins: steelseries, thunderbolt, rts54hid, nitrokey, rts54hub, upower, dfu, colorhug, csr, nvme, altos, superio, dell_dock, ebitdo, flashrom, udev, wacomhid, amt, dell, synapticsmst
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuDevice             using 5a915df0-b47a-5840-bd04-493fac9aeab4 for USB\VID_1BCF&PID_28AE&REV_4702
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuDevice             using b791475e-4dc8-5598-97b5-c16d2ddf8eb9 for USB\VID_1BCF&PID_28AE
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuDevice             using 51d4ea4b-7015-5538-8d94-4a1513e88367 for USB\VID_1BCF
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuEngine             no plugin specified for USB device 1bcf:28ae
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPlugin             performing usb_device_added() on dfu
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuDevice             using 5a915df0-b47a-5840-bd04-493fac9aeab4 for USB\VID_1BCF&PID_28AE&REV_4702
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuDevice             using b791475e-4dc8-5598-97b5-c16d2ddf8eb9 for USB\VID_1BCF&PID_28AE
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuDevice             using 51d4ea4b-7015-5538-8d94-4a1513e88367 for USB\VID_1BCF
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuEngine             dfu ignoring: 1bcf:28ae is not supported: no DFU interfaces
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 5aed4710-f3fa-5f12-8732-258e8390a5e2 for USB\VID_8087&PID_0A2A&REV_0001
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 4a79a3ba-bbbc-5ae2-b5df-aecb1ac30e1d for USB\VID_8087&PID_0A2A
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             no plugin specified for USB device 8087:0a2a
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuPlugin             performing usb_device_added() on dfu
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 5aed4710-f3fa-5f12-8732-258e8390a5e2 for USB\VID_8087&PID_0A2A&REV_0001
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 4a79a3ba-bbbc-5ae2-b5df-aecb1ac30e1d for USB\VID_8087&PID_0A2A
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             dfu ignoring: 8087:0a2a is not supported: no DFU interfaces
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 3767ef82-48ca-58af-82ca-57f0103e56c7 for USB\VID_8087&PID_8001&REV_0003
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 17d1ef41-ec58-5934-940b-a43c3cea0f76 for USB\VID_8087&PID_8001
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             no plugin specified for USB device 8087:8001
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuPlugin             performing usb_device_added() on dfu
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 3767ef82-48ca-58af-82ca-57f0103e56c7 for USB\VID_8087&PID_8001&REV_0003
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 17d1ef41-ec58-5934-940b-a43c3cea0f76 for USB\VID_8087&PID_8001
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using f77714f3-94cc-5919-8306-bd46e37a0030 for USB\VID_8087
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             dfu ignoring: 8087:8001 is not supported: no DFU interfaces
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             no plugin specified for USB device 1d6b:0002
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuPlugin             performing usb_device_added() on dfu
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             dfu ignoring: 1d6b:0002 is not supported: no DFU interfaces
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 2c4ea2a1-04c3-5dc9-adcf-e9ddc7a9663e for USB\VID_1D6B&PID_0003&REV_0420
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 69c66597-c59d-5593-8d85-b261deae893a for USB\VID_1D6B&PID_0003
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             no plugin specified for USB device 1d6b:0003
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuPlugin             performing usb_device_added() on dfu
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 2c4ea2a1-04c3-5dc9-adcf-e9ddc7a9663e for USB\VID_1D6B&PID_0003&REV_0420
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 69c66597-c59d-5593-8d85-b261deae893a for USB\VID_1D6B&PID_0003
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             dfu ignoring: 1d6b:0003 is not supported: no DFU interfaces
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuEngine             no plugin specified for USB device 1d6b:0002
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuPlugin             performing usb_device_added() on dfu
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using a85a2475-e713-5f28-b29c-cebe07cedff4 for USB\VID_1D6B&PID_0002&REV_0420
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0825 FuDevice             using 04056ce4-bb53-53d9-8089-3621cfa73e31 for USB\VID_1D6B&PID_0002
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0826 FuDevice             using 32343899-27e0-55e5-8f9f-12ff7541e488 for USB\VID_1D6B
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0826 FuEngine             dfu ignoring: 1d6b:0002 is not supported: no DFU interfaces
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0826 FuEngine             0 devices with subsystem hidraw
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0826 FuEngine             0 devices with subsystem nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0829 FuEngine             16 devices with subsystem pci
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using c5433995-45aa-5a63-88b5-c123891798bf for PCI\VEN_8086&DEV_1604&REV_09
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using b1127e0c-835f-5835-97a0-e8474eee4d3a for PCI\VEN_8086&DEV_1604
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:00.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using 19e1f63e-bef0-5582-b236-364069bcd0d5 for PCI\VEN_8086&DEV_1616&REV_09
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using 64c0ea55-d861-5dd0-aa6b-bcc307f57ed4 for PCI\VEN_8086&DEV_1616
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:02.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using bbbf1ce3d1cf15550c3760b354592040292415bb for PCI_SLOT_NAME=0000:00:02.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuPlugin             emit added from udev: bbbf1ce3d1cf15550c3760b354592040292415bb
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuPlugin             performing fu_plugin_device_registered() on dell
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDeviceList         ::added bbbf1ce3d1cf15550c3760b354592040292415bb
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using d70cd485-679e-5c82-bc3f-feed70ee0009 for PCI\VEN_8086&DEV_160C&REV_09
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using e45da685-52fa-5fb8-80fa-8d29b5b23313 for PCI\VEN_8086&DEV_160C
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0830 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:03.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz systemd[1]: Started Firmware update daemon.
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using 020eea0b-04ca-54e3-b770-45bbf97f8513 for PCI\VEN_8086&DEV_1603&REV_09
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using 8ac4050e-5982-5ffe-9432-8bc1c87613d0 for PCI\VEN_8086&DEV_1603
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:04.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using b991e033-5993-5e13-9b1a-e74411c71d73 for PCI\VEN_8086&DEV_9CB1&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using 73d59868-686e-5eff-a75a-0b039687a111 for PCI\VEN_8086&DEV_9CB1
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:14.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using 0315bf8e-e557-5818-b25e-721868f994b3 for PCI\VEN_8086&DEV_9CBA&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using 6373f81f-4625-5859-a1e9-075dc184c890 for PCI\VEN_8086&DEV_9CBA
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:16.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0831 FuDevice             using a80f2a70-1cfc-5fff-a7ef-00720fe5c12d for PCI\VEN_8086&DEV_15A2&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using d2adad9f-bfdf-5f6a-b094-1b7912e95d20 for PCI\VEN_8086&DEV_15A2
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:19.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using 0ff9708f-5ac7-5138-88b7-914ceedebc5e for PCI\VEN_8086&DEV_9CA0&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using 09db5ff1-7c50-592a-a81c-43dcca0d4850 for PCI\VEN_8086&DEV_9CA0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1b.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using 0a2cfedb-5fe4-5aa3-ade0-3ba1a7cd17f9 for PCI\VEN_8086&DEV_9C90&REV_E3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using 63ebb625-2f2b-5ef4-92ab-1421d8a327fd for PCI\VEN_8086&DEV_9C90
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using 8134a7e2-e89c-5fa6-9956-24a72594fd6c for PCI\VEN_1217&DEV_8520&REV_01
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using 2c36d225-047b-593e-81d2-30da1aa8a9b3 for PCI\VEN_1217&DEV_8520
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuDevice             using dda0fd11-2cfa-590c-a99d-f138ee552297 for PCI\VEN_1217
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.0/0000:01:00.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0832 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using f3369ade-7ee6-56e6-938e-39adcd68e1d5 for PCI\VEN_8086&DEV_9C96&REV_E3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using fcc4db8f-5b19-5fde-b49f-28a957ec6be4 for PCI\VEN_8086&DEV_9C96
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using ee141425-2454-59f8-9f48-38c54caa231e for PCI\VEN_8086&DEV_095A&REV_59
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using 7535d47f-d149-5cb4-9999-082b9475f74e for PCI\VEN_8086&DEV_095A
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1c.3/0000:02:00.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using d6a8f1d4-6fa5-5657-b53f-40c05f3fc98b for PCI\VEN_8086&DEV_9CA6&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using 9bd83d10-1f99-5324-b297-357d712d0f2b for PCI\VEN_8086&DEV_9CA6
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1d.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using 62449711-aa22-594c-aff9-a9079ac5e471 for PCI\VEN_8086&DEV_9CC3&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using 5edbaecf-0cd5-5d8e-bb10-623ab77bf58b for PCI\VEN_8086&DEV_9CC3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1f.0
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0833 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuDevice             using f6e717f1-a386-5821-b2d5-7fd5a709d993 for PCI\VEN_8086&DEV_282A&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuDevice             using ab5c1187-5e30-571c-a71b-2fbcd0b72eac for PCI\VEN_8086&DEV_282A
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1f.2
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuDevice             using 6a075191-a79f-5c57-a9d3-9fc91b96860a for PCI\VEN_8086&DEV_9CA2&REV_03
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuDevice             using 2e3cdbdd-8ebe-5eed-9ff3-c6625d6c3cd0 for PCI\VEN_8086&DEV_9CA2
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuDevice             using afb8c4ec-b9a5-5cd3-aaef-cc976ce37eb8 for PCI\VEN_8086
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuEngine             no plugin specified for udev device /sys/devices/pci0000:00/0000:00:1f.3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuPlugin             performing udev_device_added() on nvme
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuPlugin             performing udev_device_added() on udev
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuHistory            trying to open database '/var/lib/fwupd/pending.db'
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuHistory            got schema version of 2
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuEngine             Emitting PropertyChanged('Status'='idle')
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0834 FuMain               Emitting PropertyChanged('Status'='idle')
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0836 FuMain               Daemon ready for requests
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0837 FuMain               FuMain: acquired name: org.freedesktop.fwupd
Comment 12 Mario Limonciello 2019-01-30 16:42:51 UTC
Relevant snippet:

Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0601 FuPluginDell         Failed to query system for TPM information: (4294967293)
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0601 FuPluginDell         No switchable TPM detected
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0601 FuPlugin             performing coldplug() on synapticsmst
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0712 FuPluginSynapticsMST No device found on drm_dp_aux1: failed to read device: failed to read
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0823 FuPluginSynapticsMST No device found on drm_dp_aux2: failed to read device: failed to read
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0823 FuPluginSynapticsMST No device found on drm_dp_aux0: no device
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPlugin             performing coldplug_cleanup() on dell
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPluginDell         Failed to query system for dock count: (4294967294)
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPluginDell         SMI execution returned error: -3
Jan 30 13:00:48 ersatz fwupd[4889]: 12:00:48:0824 FuPluginDell         Unable to toggle MST hub GPIO to 0

Good; that confirms that fwupd still can't do it with the "legacy" SMI interface either on this platform.

There's two ways to go about resolution.
1) Userspace: make fwupd check the SMBIOS DA table for this offset that indicates supported commands and prevent the fwupd "Dell" plugin from loading.

2) Kernel: Downgrade the error messages in this particular scenario from kernel.

I feel that it's better to do it in userspace and so I've filed this:
https://github.com/hughsie/fwupd/pull/976
Comment 13 Mario Limonciello 2019-01-31 19:21:41 UTC
That one was merged, this bug should be closed.

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