Bug 2074228

Summary: DBus error: The name com.redhat.SubscriptionManager was not provided by any .service files
Product: Red Hat Enterprise Linux 8 Reporter: Jan Stavel <jstavel>
Component: subscription-managerAssignee: Jiri Hnidek <jhnidek>
Status: CLOSED ERRATA QA Contact: Red Hat subscription-manager QE Team <rhsm-qe>
Severity: medium Docs Contact:
Priority: medium    
Version: 8.7CC: jhnidek, jsefler, redakkan
Target Milestone: rcKeywords: Triaged
Target Release: 8.7   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: subscription-manager-1.28.30-1.el8 Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of:
: 2078817 (view as bug list) Environment:
Last Closed: 2022-11-08 10:48:23 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 2078817    

Description Jan Stavel 2022-04-11 19:35:11 UTC
Description of problem:

Hello, I've seen an error emitted in dbus (busctl format of the output):

Type=method_call  Endian=l  Flags=0  Version=1  Priority=0 Cookie=2  Timestamp="Mon 2022-04-11 18:34:36.389210 UTC"
  Sender=:1.2227  Destination=org.freedesktop.DBus  Path=/org/freedesktop/DBus  Interface=org.freedesktop.DBus  Member=GetNameOwner
  UniqueName=:1.2227                                         
  MESSAGE "s" {                                                                                                    
          STRING "com.redhat.SubscriptionManager";                                                                
  };                                                                                                                                                                                                                                         
                                                                                                                  
‣ Type=error  Endian=l  Flags=1  Version=1  Priority=0 Cookie=2  ReplyCookie=2  Timestamp="Mon 2022-04-11 18:34:36.389240 UTC"
  Sender=org.freedesktop.DBus  Destination=:1.2227            
  ErrorName=org.freedesktop.DBus.Error.NameHasNoOwner  ErrorMessage="Could not get owner of name 'com.redhat.SubscriptionManager': no such name"
  MESSAGE "s" {                                                                                                      
          STRING "Could not get owner of name 'com.redhat.SubscriptionManager': no such name";                                     
  };                                                                                                                               
                                                                                                                    
‣ Type=method_call  Endian=l  Flags=0  Version=1  Priority=0 Cookie=3  Timestamp="Mon 2022-04-11 18:34:36.389484 UTC"                          
  Sender=:1.2227  Destination=org.freedesktop.DBus  Path=/org/freedesktop/DBus  Interface=org.freedesktop.DBus  Member=StartServiceByName
  UniqueName=:1.2227                                                                                                
  MESSAGE "su" {                                                                                                                      
          STRING "com.redhat.SubscriptionManager";                                                                                                        
          UINT32 0;                                                                                                  
  };                                                                                                                                                      
                                                               
‣ Type=error  Endian=l  Flags=1  Version=1  Priority=0 Cookie=3  ReplyCookie=3  Timestamp="Mon 2022-04-11 18:34:36.389521 UTC"
  Sender=org.freedesktop.DBus  Destination=:1.2227                                                                                             
  ErrorName=org.freedesktop.DBus.Error.ServiceUnknown  ErrorMessage="The name com.redhat.SubscriptionManager was not provided by any .service files"
  MESSAGE "s" {
          STRING "The name com.redhat.SubscriptionManager was not provided by any .service files";
  };

‣ Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=38  Timestamp="Mon 2022-04-11 18:34:42.358005 UTC"
  Sender=org.freedesktop.DBus  Destination=:1.2227  Path=/org/freedesktop/DBus  Interface=org.freedesktop.DBus  Member=NameLost
  MESSAGE "s" {
          STRING ":1.2227";
  };

‣ Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=4452  Timestamp="Mon 2022-04-11 18:34:42.358063 UTC"
  Sender=org.freedesktop.DBus  Path=/org/freedesktop/DBus  Interface=org.freedesktop.DBus  Member=NameOwnerChanged
  MESSAGE "sss" {
          STRING ":1.2227";
          STRING ":1.2227";
          STRING "";
  };




a version of subscription manager:

            "subscription management server: 3.2.22-1",
            "subscription management rules: 5.41",
            "subscription-manager: 1.28.29-2.el8"


I did:

   - name: set the proper candlepin server
      shell:
        cmd: "subscription-manager config --server.hostname={{rhsm.server.hostname}} --server.port={{rhsm.server.port}} --server.insecure={{rhsm.server.insecure}}"


    - name: start a dbus monitor on background
      shell:
        cmd: |
          dbus-monitor --system \
          > {{ dbus_monitor_tempfile.path }}
            
      async: 100
      poll: 0
      register: dbus_monitor_task

    - name: register a system using activation key
      command:
        cmd: "subscription-manager register --activationkey={{ activation_key }} --org={{ rhsm.org }}"
      ignore_errors: yes
      register: out
    - debug: var=out
      

    - name: attach a pool to the system
      command:
        cmd: "subscription-manager attach --auto"
      register: out
    - debug: var=out

    - name: Check on an async task
      async_status:
        jid: "{{ dbus_monitor_task.ansible_job_id }}"
      register: job_status
      until: job_status.finished
      retries: 2
      delay: 10
      ignore_errors: yes

    - name: emitted dbus events
      command: "cat {{ dbus_monitor_tempfile.path }}"
      register:
        emitted_dbus_events
        
    - debug: var=emitted_dbus_events.stdout_lines



messages appeared in the dbus:

   "emitted_dbus_events.stdout_lines": [
        "signal time=1649702065.783780 sender=org.freedesktop.DBus -> destination=:1.2225 serial=33 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameAcquired",
        "   string \":1.2225\"",
        "signal time=1649702065.783830 sender=org.freedesktop.DBus -> destination=:1.2225 serial=34 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameLost",
        "   string \":1.2225\"",
        "signal time=1649702065.783836 sender=org.freedesktop.DBus -> destination=(null destination) serial=4448 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged",
        "   string \":1.2225\"",
        "   string \":1.2225\"",
        "   string \"\"",
        "method call time=1649702067.324053 sender=:1.2226 -> destination=org.freedesktop.DBus serial=1 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=Hello",
        "method return time=1649702067.324075 sender=org.freedesktop.DBus -> destination=:1.2226 serial=1 reply_serial=1",
        "   string \":1.2226\"",
        "signal time=1649702067.324095 sender=org.freedesktop.DBus -> destination=(null destination) serial=4449 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged",
        "   string \":1.2226\"",
        "   string \"\"",
        "   string \":1.2226\"",
        "signal time=1649702067.324104 sender=org.freedesktop.DBus -> destination=:1.2226 serial=35 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameAcquired",
        "   string \":1.2226\"",
        "method call time=1649702067.325278 sender=:1.2226 -> destination=org.freedesktop.DBus serial=2 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=GetNameOwner",
        "   string \"com.redhat.SubscriptionManager\"",
        "error time=1649702067.325298 sender=org.freedesktop.DBus -> destination=:1.2226 error_name=org.freedesktop.DBus.Error.NameHasNoOwner reply_serial=2",
        "   string \"Could not get owner of name 'com.redhat.SubscriptionManager': no such name\"",
        "method call time=1649702067.325731 sender=:1.2226 -> destination=org.freedesktop.DBus serial=3 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=StartServiceByName",
        "   string \"com.redhat.SubscriptionManager\"",
        "   uint32 0",
        "error time=1649702067.325744 sender=org.freedesktop.DBus -> destination=:1.2226 error_name=org.freedesktop.DBus.Error.ServiceUnknown reply_serial=3",
        "   string \"The name com.redhat.SubscriptionManager was not provided by any .service files\"",
        "signal time=1649702074.585223 sender=org.freedesktop.DBus -> destination=:1.2226 serial=36 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameLost",
        "   string \":1.2226\"",
        "signal time=1649702074.585272 sender=org.freedesktop.DBus -> destination=(null destination) serial=4450 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged",
        "   string \":1.2226\"",
        "   string \":1.2226\"",
        "   string \"\"",
        "method call time=1649702076.388675 sender=:1.2227 -> destination=org.freedesktop.DBus serial=1 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=Hello",
        "method return time=1649702076.388694 sender=org.freedesktop.DBus -> destination=:1.2227 serial=1 reply_serial=1",
        "   string \":1.2227\"",
        "signal time=1649702076.388701 sender=org.freedesktop.DBus -> destination=(null destination) serial=4451 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged",
        "   string \":1.2227\"",
        "   string \"\"",
        "   string \":1.2227\"",
        "signal time=1649702076.388710 sender=org.freedesktop.DBus -> destination=:1.2227 serial=37 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameAcquired",
        "   string \":1.2227\"",
        "method call time=1649702076.389171 sender=:1.2227 -> destination=org.freedesktop.DBus serial=2 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=GetNameOwner",
        "   string \"com.redhat.SubscriptionManager\"",
        "error time=1649702076.389186 sender=org.freedesktop.DBus -> destination=:1.2227 error_name=org.freedesktop.DBus.Error.NameHasNoOwner reply_serial=2",
        "   string \"Could not get owner of name 'com.redhat.SubscriptionManager': no such name\"",
        "method call time=1649702076.389425 sender=:1.2227 -> destination=org.freedesktop.DBus serial=3 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=StartServiceByName",
        "   string \"com.redhat.SubscriptionManager\"",
        "   uint32 0",
        "error time=1649702076.389457 sender=org.freedesktop.DBus -> destination=:1.2227 error_name=org.freedesktop.DBus.Error.ServiceUnknown reply_serial=3",
        "   string \"The name com.redhat.SubscriptionManager was not provided by any .service files\"",
        "signal time=1649702082.357926 sender=org.freedesktop.DBus -> destination=:1.2227 serial=38 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameLost",
        "   string \":1.2227\"",
        "signal time=1649702082.357946 sender=org.freedesktop.DBus -> destination=(null destination) serial=4452 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged",
        "   string \":1.2227\"",
        "   string \":1.2227\"",
        "   string \"\""
    ]

Comment 1 Jan Stavel 2022-04-12 09:30:37 UTC
I have started systemd service 'rhsm'.
I have replied the scenario.

The error "The name com.redhat.SubscriptionManager was not provided by any .service files" still appears.

The systemd service did not crash after I finish the scenario.

A list of messages appeared in dbus during the scenario run.



method call time=1649753910.010430 sender=:1.2273 -> destination=org.freedesktop.DBus serial=1 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=Hello
method return time=1649753910.010447 sender=org.freedesktop.DBus -> destination=:1.2273 serial=1 reply_serial=1
   string ":1.2273"
signal time=1649753910.010454 sender=org.freedesktop.DBus -> destination=(null destination) serial=4542 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged
   string ":1.2273"
   string ""
   string ":1.2273"
signal time=1649753910.010463 sender=org.freedesktop.DBus -> destination=:1.2273 serial=128 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameAcquired
   string ":1.2273"
method call time=1649753910.010948 sender=:1.2273 -> destination=org.freedesktop.DBus serial=2 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=GetNameOwner
   string "com.redhat.SubscriptionManager"
error time=1649753910.010976 sender=org.freedesktop.DBus -> destination=:1.2273 error_name=org.freedesktop.DBus.Error.NameHasNoOwner reply_serial=2
   string "Could not get owner of name 'com.redhat.SubscriptionManager': no such name"
method call time=1649753910.011337 sender=:1.2273 -> destination=org.freedesktop.DBus serial=3 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=StartServiceByName
   string "com.redhat.SubscriptionManager"
   uint32 0
error time=1649753910.011350 sender=org.freedesktop.DBus -> destination=:1.2273 error_name=org.freedesktop.DBus.Error.ServiceUnknown reply_serial=3
   string "The name com.redhat.SubscriptionManager was not provided by any .service files"
signal time=1649753911.570835 sender=:1.2267 -> destination=(null destination) serial=11 path=/com/redhat/RHSM1/Syspurpose; interface=com.redhat.RHSM1.Syspurpose; member=SyspurposeChanged
signal time=1649753911.723011 sender=:1.2267 -> destination=(null destination) serial=12 path=/com/redhat/RHSM1/Syspurpose; interface=com.redhat.RHSM1.Syspurpose; member=SyspurposeChanged
signal time=1649753912.872663 sender=:1.2267 -> destination=(null destination) serial=13 path=/com/redhat/RHSM1/Entitlement; interface=com.redhat.RHSM1.Entitlement; member=EntitlementChanged
signal time=1649753913.070471 sender=:1.2267 -> destination=(null destination) serial=14 path=/com/redhat/RHSM1/Entitlement; interface=com.redhat.RHSM1.Entitlement; member=EntitlementChanged
signal time=1649753916.301331 sender=org.freedesktop.DBus -> destination=:1.2273 serial=129 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameLost
   string ":1.2273"
signal time=1649753916.301351 sender=org.freedesktop.DBus -> destination=(null destination) serial=4543 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged
   string ":1.2273"
   string ":1.2273"
   string ""

Comment 2 Pino Toscano 2022-04-13 11:42:10 UTC
The unused com.redhat.SubscriptionManager D-Bus interface was removed only in main with commit 599af9b3b487373a443d676fb0935a2ac800fffb, from the PR #2864 [1].
It should be easy to backport it to the subscription-manager-1.28 branch as well.

[1] https://github.com/candlepin/subscription-manager/pull/2864

Comment 3 Jan Stavel 2022-05-02 12:07:29 UTC
No error message appears in dbus.

version of the package:

       "server type: Red Hat Subscription Management",
        "subscription management server: 3.2.22-1",
        "subscription management rules: 5.41",
        "subscription-manager: 1.28.29-1.git.25.680c90d.el8"

Comment 4 Jan Stavel 2022-05-02 12:43:41 UTC
a scenario I has played:

- I have used stage candlepin.

- dbus monitor in the second terminal

  busctl --system monitor

- registration using activation key

  subscription-manager register --activationkey=MY_STAGE_KEY --org=13267397

 > The system has been registered with ID: 67363d2a-d7bb-4cd4-a213-c759535c31f4
The registered system name is: kvm-01-guest08.lab.eng.brq.redhat.com
Installed Product Current Status:
Product Name: Red Hat Enterprise Linux for x86_64 Beta
Status:       Not Subscribed

Unable to find available subscriptions for all your installed products.


No message related SubscriptionManager appeared in the dbus monitor output.

Comment 8 Jan Stavel 2022-05-20 20:01:03 UTC
build version: RHEL-8.7.0-20220520.d.0 BaseOS x86_64

TASK [Version of installed subscription-manager] *******************************
ok: [hp-z600-02.ml3.eng.bos.redhat.com] => {}

MSG:

server type: This system is currently not registered.
subscription management server: 3.2.22-1
subscription management rules: 5.41
subscription-manager: 1.28.30-1.el8


systemctl enable --now rhsm

TASK [start a dbus monitor on background] **************************************

TASK [register a system using activation key] **********************************

subscription-manager register --activationkey=stage_rhsmqe_testuser01_13267397-ActivationKey1645276042189 --org=13267397
 > The system has been registered with ID: e96d7bd7-5168-4841-9e72-744138e006e3
The registered system name is: hp-z600-02.ml3.eng.bos.redhat.com
Installed Product Current Status:
Product Name: Red Hat Enterprise Linux for x86_64 Beta
Status:       Not Subscribed


dbus events appeared:

Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=11  Timestamp="Fri 2022-05-20 19:55:23.386210 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Consumer  Interface=com.redhat.RHSM1.Consumer  Member=ConsumerChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

> Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=12  Timestamp="Fri 2022-05-20 19:55:23.537152 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Consumer  Interface=com.redhat.RHSM1.Consumer  Member=ConsumerChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

> Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=13  Timestamp="Fri 2022-05-20 19:55:23.674829 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Syspurpose  Interface=com.redhat.RHSM1.Syspurpose  Member=SyspurposeChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

> Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=14  Timestamp="Fri 2022-05-20 19:55:28.900176 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Syspurpose  Interface=com.redhat.RHSM1.Syspurpose  Member=SyspurposeChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

> Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=15  Timestamp="Fri 2022-05-20 19:55:29.025862 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Syspurpose  Interface=com.redhat.RHSM1.Syspurpose  Member=SyspurposeChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

> Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=16  Timestamp="Fri 2022-05-20 19:55:30.215760 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Entitlement  Interface=com.redhat.RHSM1.Entitlement  Member=EntitlementChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

> Type=signal  Endian=l  Flags=1  Version=1  Priority=0 Cookie=17  Timestamp="Fri 2022-05-20 19:55:30.331608 UTC"
  Sender=:1.49  Path=/com/redhat/RHSM1/Entitlement  Interface=com.redhat.RHSM1.Entitlement  Member=EntitlementChanged
  UniqueName=:1.49
  MESSAGE "" {
  };

Comment 10 errata-xmlrpc 2022-11-08 10:48:23 UTC
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory (subscription-manager bug fix and enhancement update), and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHEA-2022:7719