Uploaded image for project: 'OpenShift Bugs'
  1. OpenShift Bugs
  2. OCPBUGS-13572

Agent service printed warning message: "No valid motherboard serial, using system UUID instead" file="machine_uuid_scanner.go:138"

XMLWordPrintable

    • Quality / Stability / Reliability
    • False
    • Hide

      None

      Show
      None
    • None
    • Important
    • No
    • None
    • None
    • Rejected
    • None
    • None
    • None
    • None
    • None
    • None
    • None
    • None

      Description of problem:

      Agent service printed warning message: "No valid motherboard serial, using system UUID instead" file="machine_uuid_scanner.go:138"

      Version-Release number of selected component (if applicable):

      OCP 4.12.11
      ACM 2.7.3
      CNV 4.12.2

      How reproducible:

      Always

      Steps to Reproduce:

      1. Installed OCP 4.12.11 + ACM 2.7.3 + CNV 4.12.2
      2. Created two VMs in OpenShift CNV
      3. Generated discovery ISO to boot VMs
      4. SSH into two VMs and got agnet.service log,the hostid that agent.service used was the same
      

      Actual results:

      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Motherboard serial number is " file="machine_uuid_scanner.go:101"
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=warning msg="No valid motherboard serial, using system UUID instead" file="machine_uuid_scanner.go:138"

      Expected results:

      Agent service could get motherboard serial and generate host id properly

      Additional info:

      -- Logs begin at Fri 2023-05-12 15:57:19 UTC, end at Fri 2023-05-12 16:13:16 UTC. --
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Motherboard serial number is " file="machine_uuid_scanner.go:101"
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=warning msg="No valid motherboard serial, using system UUID instead" file="machine_uuid_scanner.go:138"
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Validating next step runner with args [{\"agent_version\":\"registry.redhat.io/multicluster-engine/assisted-instal>
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Executing nsenter [--target 1 --cgroup --mount --ipc --net -- podman rm -i next-step-runner]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Running next step runner. Command: podman, Args: [run --rm -ti --privileged --pid=host --net=host --pids-limit=0 ->
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Executing nsenter [--target 1 --cgroup --mount --ipc --net -- podman rm -i next-step-runner]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain agent[1841]: time="12-05-2023 15:58:20" level=info msg="Executing nsenter [--target 1 --cgroup --mount --ipc --net -- podman run --rm -ti --privileged --pid=host --net=ho>
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Query for next steps" file="step_processor.go:233" request_id=49441bdf-85db-4046-a2e4-af5d7ff919b7
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Creating execution step for inventory inventory-364aee75 args <[01def923-f84b-5029-4e1c-94c6b0e756a2]>" >
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Validating inventory with args [01def923-f84b-5029-4e1c-94c6b0e756a2]" file="action.go:29"
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Creating execution step for ntp-synchronizer ntp-synchronizer-b924bf1e args <[{\"ntp_source\":\"10.30.0.>
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Executing nsenter [--target 1 --cgroup --mount --ipc --net -- sh -c cp /etc/mtab /root/mtab-01def923-f84>
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Validating ntp synchronizer with args [{\"ntp_source\":\"10.30.0.250\"}]" file="action.go:29"
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Executing timeout [30 chronyc -n sources]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Executing chronyc [add server 10.30.0.250 iburst]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain next_step_runne[1907]: time="12-05-2023 15:58:20" level=info msg="Executing timeout [30 chronyc -n sources]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 1]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 2]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 3]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 4]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 5]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 6]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 7]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 8]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 9]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 10]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 11]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan print 12]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 1 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 2 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 3 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 4 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 5 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 6 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 7 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 8 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 9 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 10 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 11 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing ipmitool [lan6 print 12 enables]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing lscpu [-J]" file="execute.go:39"
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Disk (name sr0 drive type ODD bus path pci-0000:00:1f.2-ata-1 vendor QEMU model QEMU_DVD-ROM partitions []) wa>
      May 12 15:58:20 localhost.localdomain inventory[1961]: time="12-05-2023 15:58:20" level=info msg="Executing file [-s /dev/sr0]" file="execute.go:39"
      

              Unassigned Unassigned
              fewang-redhat.com Feng Wang (Inactive)
              None
              None
              Lital Alon Lital Alon
              None
              Votes:
              0 Vote for this issue
              Watchers:
              4 Start watching this issue

                Created:
                Updated:
                Resolved: