2512LTS 版本升级问题

交流互助区
1

// 此模板仅供参考，如果不适用可以修改

问题描述

[2512LTS版本升级失败 log 提示：2026-01-29T15:32:22.999904+08:00 H180287249260100080 [Service:bmc_core] [SearchCommand:102] Failed to search command: /bin/tar xvf /dev/shm/upgrade/rootfs_img.tar.gz -O rootfs_BMC.img
2026-01-29T15:32:23.000109+08:00 H180287249260100080 [Service:bmc_core] [CheckCommandForExecFamily:118] Function execve : mismatched command /bin/tar .]

环境信息

  • 操作系统：[如 Ubuntu 24.04]

  • 软件版本：[如 OpenUBMC2512]

  • 硬件配置：[S920X20]

重现步骤

社区发行版的源码，只增加了签名校验

期望结果

如何才能升级升高

日志

2026-01-29T15:32:22.999904+08:00 H180287249260100080 [Service:bmc_core] [SearchCommand:102] Failed to search command: /bin/tar xvf /dev/shm/upgrade/rootfs_img.tar.gz -O rootfs_BMC.img
2026-01-29T15:32:23.000109+08:00 H180287249260100080 [Service:bmc_core] [CheckCommandForExecFamily:118] Function execve : mismatched command /bin/tar .
2026-01-29 15:31:16.858900 firmware_mgmt NOTICE: task_instance.lua(238): upgrade_task file_name=S920X20-CMT_25.12.00.02.hpm, FirmwareType=BMC, fw_num=1, UniqueIDList=table: 0x2eeff68bd860, sys_id=1, info_key=1_BMC_388651854

2026-01-29 15:31:16.865926 web_backend NOTICE: base.lua(518): processing_flow_1 foreach_1 timeout,[path=/bmc/kepler/UpdateService, interface=bmc.kepler.UpdateService], t1=1769671873876, t2=1769671876865, time=2989

2026-01-29 15:31:16.867667 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 54.381306425916

2026-01-29 15:31:16.871846 web_backend NOTICE: route_mapper.lua(388): uri:/UI/Rest/BMCSettings/UpdateService/UpdateProgress, method:get timeout, t1=1769671873869, t2=1769671876871, time=3002

2026-01-29 15:31:16.953386 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 0, State: Running] successfully

2026-01-29 15:31:16.953910 firmware_mgmt NOTICE: info_mgmt.lua(71): create_info: info_key=1_BMC_388651854, FirmwareType=BMC, task_id=388651854, upg_fw_num=1

2026-01-29 15:31:17.054432 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 0, State: Running] successfully

2026-01-29 15:31:17.055177 firmware_mgmt NOTICE: task_instance.lua(702): firmware(info key:1_BMC_388651854) do upgrade stages

2026-01-29 15:31:17.055717 firmware_mgmt NOTICE: task_instance.lua(316): public initialize action start

2026-01-29 15:31:17.056092 firmware_mgmt NOTICE: hpm_package.lua(485): get obj table: 0x2eeff6cc6480 for Id=25

2026-01-29 15:31:17.056492 firmware_mgmt NOTICE: hpm_package.lua(539): now uptime = 25324, firmware init time = 0

2026-01-29 15:31:17.057762 firmware_mgmt NOTICE: hpm_package.lua(456): get obj table: 0x2eeff6cc6480 for Id=25

2026-01-29 15:31:17.058190 firmware_mgmt NOTICE: task_instance.lua(321): public initialize action end

2026-01-29 15:31:17.060066 firmware_mgmt NOTICE: info_mgmt.lua(230): info_key(1_BMC_388651854) upgrade set_stage: INITIALIZE → DIFF_PREPARE, 5

2026-01-29 15:31:17.156317 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 5, State: Starting] successfully

2026-01-29 15:31:17.164263 general_hardware NOTICE: upgrade_subject.lua(112): [on_upgrade_prepare] firmware_type:BMC cur_firmware_type:BMC

2026-01-29 15:31:17.164630 general_hardware ERROR: upgrade_subject.lua(116): [on_upgrade_prepare] does not exists the observer, firmware_type:BMC

2026-01-29 15:31:17.166672 bmc_upgrade NOTICE: update_mgmt.lua(83): update UpdateStage to 1

2026-01-29 15:31:17.241499 bmc_upgrade NOTICE: upgrade.lua(184): base_ver = 5.12.00.01, target_ver = 25.12.00.02

2026-01-29 15:31:17.242018 bmc_upgrade NOTICE: update_mgmt.lua(62): get_downgrade_allowed: DowngradeAllowed true

2026-01-29 15:31:17.242651 bmc_upgrade NOTICE: revoked_version.lua(69): get_revoked_enabled: RevokedVersionEnabled false

2026-01-29 15:31:17.243045 bmc_upgrade NOTICE: upgrade.lua(126): revoked version is not support now

2026-01-29 15:31:17.245267 bmc_upgrade NOTICE: upgrade.lua(109): customer is invalid. customer version is ignored.

2026-01-29 15:31:17.245613 bmc_upgrade ERROR: upgrade.lua(197): customer version is ignored.

2026-01-29 15:31:17.247035 bmc_upgrade NOTICE: update_mgmt.lua(83): update UpdateStage to 0

2026-01-29 15:31:17.253720 firmware_mgmt NOTICE: info_mgmt.lua(384): set FirmwareMode to Single

2026-01-29 15:31:17.254123 firmware_mgmt NOTICE: info_mgmt.lua(230): info_key(1_BMC_388651854) upgrade set_stage: DIFF_PREPARE → COMMON_PREPARE, 5

2026-01-29 15:31:17.257418 firmware_mgmt NOTICE: action.lua(86): public prepare action start

2026-01-29 15:31:17.292621 firmware_mgmt NOTICE: worker: start action in worker

2026-01-29 15:31:17.349321 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 5, State: Starting] successfully

2026-01-29 15:31:17.369724 firmware_mgmt NOTICE: worker: execute before action script successfully

2026-01-29 15:31:17.866195 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 80.103359173127

2026-01-29 15:31:18.291634 firmware_mgmt NOTICE: action.lua(92): public prepare action end

2026-01-29 15:31:18.292139 firmware_mgmt NOTICE: task_instance.lua(714): upgrade FirmwareMode Single

2026-01-29 15:31:18.392231 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 5, State: Starting] successfully

2026-01-29 15:31:18.392849 firmware_mgmt NOTICE: info_mgmt.lua(230): info_key(1_BMC_388651854) upgrade set_stage: COMMON_PREPARE → PROCESS, 15

2026-01-29 15:31:18.493375 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 15, State: Starting] successfully

2026-01-29 15:31:18.494156 firmware_mgmt NOTICE: task_instance.lua(346): sys_id=1, fw_type=BMC, filename=/dev/shm/upgrade/388651854/Firmware1

2026-01-29 15:31:18.498322 firmware_mgmt NOTICE: hpm_package.lua(492): get obj table: 0x2eeff6cc6480 for Id=25

2026-01-29 15:31:18.500057 general_hardware ERROR: upgrade_subject.lua(135): [on_upgrade_process] does not exists the observer, firmware_type:BMC

2026-01-29 15:31:18.501371 bmc_upgrade NOTICE: update_mgmt.lua(83): update UpdateStage to 2

2026-01-29 15:31:18.584957 bmc_upgrade NOTICE: fw_decrypt_work.lua(80): start fw decrypt work

2026-01-29 15:31:18.767159 unknown_service ERROR: km_api.lua(176): Get keyboard state fail, usb_id=0, fn_id=0, ret=22 [repeated 31 times in 5s from 2026-01-29 15:25:47.131185 to 2026-01-29 15:25:52.846739][flush]

2026-01-29 15:31:18.869112 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 71.618037135279

2026-01-29 15:31:19.869480 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 44.53125

2026-01-29 15:31:20.863731 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 55.412371134021

2026-01-29 15:31:21.870233 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 50

2026-01-29 15:31:22.867183 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 58.838383838384

2026-01-29 15:31:23.868309 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 46.055979643766

2026-01-29 15:31:24.867467 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 45.012787723785

2026-01-29 15:31:25.502348 bmc_upgrade NOTICE: fw_decrypt_work.lua(105): end fw decrypt work, use time:6.771572

2026-01-29 15:31:25.866356 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 58.22454308094

2026-01-29 15:31:25.991802 bmc_upgrade NOTICE: fw_pkg_work.lua(21): start write package to flash

2026-01-29 15:31:26.863233 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 60.321715817694

2026-01-29 15:31:27.862139 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 58.055555555556

2026-01-29 15:31:28.294210 general_hardware NOTICE: fructl_handler.lua(76): get_power_state: system[1] get power power OFF

2026-01-29 15:31:28.868577 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 66.295264623955

2026-01-29 15:31:29.862707 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 56.639566395664

2026-01-29 15:31:30.868591 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 49.056603773585

2026-01-29 15:31:31.867060 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 57.681940700809

2026-01-29 15:31:32.862693 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 63.509749303621

2026-01-29 15:31:33.869257 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 50.397877984085

2026-01-29 15:31:34.862933 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 59.408602150538

2026-01-29 15:31:35.863537 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 38.743455497382

2026-01-29 15:31:36.871089 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 55.968169761273

2026-01-29 15:31:37.865470 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 51.302083333333

2026-01-29 15:31:38.870823 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 58.530183727034

2026-01-29 15:31:39.863023 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 55.670103092784

2026-01-29 15:31:40.861384 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 55.820105820106

2026-01-29 15:31:41.867969 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 57.622739018088

2026-01-29 15:31:42.863189 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 54.427083333333

2026-01-29 15:31:43.870126 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 48.737373737374

2026-01-29 15:31:44.865789 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 56.847545219638

2026-01-29 15:31:45.865186 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 55.384615384615

2026-01-29 15:31:46.869164 firmware_mgmt WARNING: listen_cpu_idle.lua(81): cpu idle rate : 55.699481865285

2026-01-29 15:31:52.518471 bmc_upgrade NOTICE: fw_pkg.lua(415): rootfs image file len: 394272768

2026-01-29 15:31:53.000172 bmc_upgrade NOTICE: fw_pkg_work.lua(35): end write package to flash, use time:7.145301

2026-01-29 15:31:54.256372 bmc_upgrade NOTICE: decompress_img.lua(75): begin to extract img file

2026-01-29 15:31:55.357462 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 15, State: Running] successfully

2026-01-29 15:31:56.458026 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 15, State: Running] successfully

2026-01-29 15:31:57.554506 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 16, State: Running] successfully

2026-01-29 15:31:58.659238 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 17, State: Running] successfully

2026-01-29 15:31:59.755456 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 18, State: Running] successfully

2026-01-29 15:32:00.858035 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 19, State: Running] successfully

2026-01-29 15:32:01.959508 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 19, State: Running] successfully

2026-01-29 15:32:03.051747 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 20, State: Running] successfully

2026-01-29 15:32:04.160021 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 21, State: Running] successfully

2026-01-29 15:32:05.253790 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 22, State: Running] successfully

2026-01-29 15:32:06.361883 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 23, State: Running] successfully

2026-01-29 15:32:08.205159 bmc_upgrade NOTICE: utils.lua(192): before unzip check [rootfs_img.tar.gz] file count:1, total size:394272768

2026-01-29 15:32:08.305918 general_hardware NOTICE: fructl_handler.lua(76): get_power_state: system[1] get power power OFF

2026-01-29 15:32:08.569701 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 24, State: Running] successfully

2026-01-29 15:32:09.053650 firmware_mgmt WARNING: tasks_scheduling.lua(132): CPU Idle Rate 18.298969072165 is less than 20. The upgrade cannot be supported and needs to retry.

2026-01-29 15:32:09.665817 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 25, State: Running] successfully

2026-01-29 15:32:10.768743 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 26, State: Running] successfully

2026-01-29 15:32:11.864695 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 27, State: Running] successfully

2026-01-29 15:32:14.052482 firmware_mgmt WARNING: tasks_scheduling.lua(132): CPU Idle Rate 16.452442159383 is less than 20. The upgrade cannot be supported and needs to retry.

2026-01-29 15:32:14.062770 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 28, State: Running] successfully

2026-01-29 15:32:15.164563 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 29, State: Running] successfully

2026-01-29 15:32:16.265336 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 30, State: Running] successfully

2026-01-29 15:32:17.370107 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 31, State: Running] successfully

2026-01-29 15:32:18.639898 firmware_mgmt NOTICE: task_instance.lua(310): waiting for firmware reply…

2026-01-29 15:32:19.564458 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 32, State: Running] successfully

2026-01-29 15:32:20.669272 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 33, State: Running] successfully

2026-01-29 15:32:21.769201 firmware_mgmt NOTICE: task_mgmt.lua(443): Update task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 34, State: Running] successfully

2026-01-29 15:32:23.004906 bmc_upgrade ERROR: decompress_img.lua(68): tar rootfs img to flash failed, ret: 126

2026-01-29 15:32:24.046864 bmc_upgrade NOTICE: process.lua(73): end extract rootfs img to flash

2026-01-29 15:32:24.055196 bmc_upgrade ERROR: process.lua(138): Write bmc package to flash failed

2026-01-29 15:32:24.058136 bmc_upgrade NOTICE: update_mgmt.lua(83): update UpdateStage to 0

2026-01-29 15:32:24.072520 firmware_mgmt ERROR: control.lua(205): Upgrade BMC process failed, ret=1

2026-01-29 15:32:24.073423 firmware_mgmt NOTICE: info_mgmt.lua(230): info_key(1_BMC_388651854) upgrade set_stage: PROCESS → COMMON_FINISH, 95

2026-01-29 15:32:24.142858 firmware_mgmt NOTICE: task_instance.lua(287): wait_msg_result stage=COMMON_FINISH, timeout=7200S, loop=654

2026-01-29 15:32:24.143499 firmware_mgmt NOTICE: info_mgmt.lua(341): info_key(1_BMC_388651854) upgrade failed, set_stage: COMMON_FINISH → COMPLETED

2026-01-29 15:32:24.275180 firmware_mgmt NOTICE: task_mgmt.lua(174): task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 95, State: Exception] will be destroyed after 10 minutes

2026-01-29 15:32:24.276202 firmware_mgmt NOTICE: task_instance.lua(626): firmware(info key:1_BMC_388651854) do upgrade ret:1, pre_version:5.12.00.01

2026-01-29 15:32:24.275956 firmware_mgmt NOTICE: task_instance.lua(620): Upgrade 1_BMC_388651854 completely, pre_version=5.12.00.01

2026-01-29 15:32:25.054364 firmware_mgmt NOTICE: tasks_scheduling.lua(127): upgrade queue is empty, exit the tasks processer

2026-01-29 15:32:25.054654 firmware_mgmt NOTICE: tasks_scheduling.lua(146): stop tasks processer

2026-01-29 15:32:25.055768 firmware_mgmt NOTICE: active_fructl.lua(96): get host type is Singlehost

2026-01-29 15:32:25.056237 firmware_mgmt NOTICE: active_single_host_fructrl.lua(73): active_single_host_fructrl fructrl get power status

2026-01-29 15:32:25.058545 firmware_mgmt NOTICE: state_simple_upgrading.lua(97): simple upgraded, current active mode is:nil, wait restart seconds:360000

2026-01-29 15:32:25.064823 firmware_mgmt NOTICE: init.lua(41): update status to FS_IDLE.

2026-01-29 15:32:25.065943 firmware_mgmt NOTICE: init.lua(80): Upgrading_Flag is false

2026-01-29 15:32:36.586841 oms NOTICE: task_mgmt.lua(312): Create task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 0, State: New] successfully

2026-01-29 15:32:36.686168 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 0, State: Running] successfully

2026-01-29 15:32:36.786686 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 10, State: Running] successfully

2026-01-29 15:32:38.806307 bmc_soc NOTICE: uart_data.lua(998): start collecting all com logs

2026-01-29 15:32:38.893466 devmon NOTICE: device_topo.cpp(630): Start dump topology to topology.txt

2026-01-29 15:32:38.899681 devmon NOTICE: device_topo.cpp(658): dump topology to topology.txt successfully

2026-01-29 15:32:39.285317 firmware_mgmt NOTICE: task_service.lua(31): destroy_task_by_id: 388651854

2026-01-29 15:32:39.316814 firmware_mgmt NOTICE: task_mgmt.lua(461): Destroy task[Id: 388651854, StartTime: 2026-01-29T15:31:09+08:00, Progress: 95, State: Exception] successfully

2026-01-29 15:32:39.317389 firmware_mgmt NOTICE: task_id_mgmt.lua(40): delete serial task id(388651854) successfully

2026-01-29 15:32:39.317859 firmware_mgmt NOTICE: task_id_mgmt.lua(42): del serial task id(388651854) successfully

2026-01-29 15:32:39.318298 firmware_mgmt NOTICE: task_instance.lua(102): destroy task info(id:388651854) successfully

2026-01-29 15:32:39.570310 bmc_soc NOTICE: uart_data.lua(1033): all com logs have been compressed

2026-01-29 15:32:39.693898 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 16, State: Running] successfully

2026-01-29 15:32:40.794602 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 19, State: Running] successfully

2026-01-29 15:32:41.436963 bios NOTICE: log_collector.lua(97): [bios]start to dump bios log

2026-01-29 15:32:41.437523 bios NOTICE: log_collector.lua(72): [bios]start collect bios info

2026-01-29 15:32:41.587531 event NOTICE: event_app.lua(300): event dump is started.

2026-01-29 15:32:41.600521 bmc_network NOTICE: network_dump.lua(229): bmc_network dump begin.

2026-01-29 15:32:41.602353 fusionpod NOTICE: debug.lua(877): dump bmc_network/service/fusionpod rpc count records success

2026-01-29 15:32:41.610457 bmc_network NOTICE: network_dump.lua(37): dump_phy_info start

2026-01-29 15:32:41.612267 bmc_network NOTICE: network_dump.lua(54): dump_phy_info buf is eth2: PHY_ID: 11a Phy Speed: 1000M/s

2026-01-29 15:32:41.615074 bmc_network NOTICE: network_dump.lua(65): dump_phy_info end

2026-01-29 15:32:41.811142 event NOTICE: event_common.lua(1046): start clear database info

2026-01-29 15:32:41.844040 event NOTICE: event_common.lua(1058): finish clear database info

2026-01-29 15:32:41.844601 event NOTICE: event_app.lua(330): event dump is completed.

2026-01-29 15:32:41.900529 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 21, State: Running] successfully

2026-01-29 15:32:41.906908 bmc_network NOTICE: network_dump.lua(240): bmc_network dump end.

2026-01-29 15:32:42.660065 fault_diagnosis NOTICE: fdm_dump.lua(97): per_temporary.db dump completed

2026-01-29 15:32:42.667850 fault_diagnosis NOTICE: fdm_dump.lua(97): per_reset.db dump completed

2026-01-29 15:32:42.992233 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 24, State: Running] successfully

2026-01-29 15:32:43.720899 ipmi_core NOTICE: ipmi_dump.lua(53): ipmi_core dump begin.

2026-01-29 15:32:43.746805 unknown_service NOTICE: debug.lua(877): dump ipmi_core/service/edma rpc count records success

2026-01-29 15:32:43.747231 unknown_service NOTICE: debug.lua(877): dump ipmi_core/service/btc rpc count records success

2026-01-29 15:32:43.749513 unknown_service NOTICE: debug.lua(877): dump ipmi_core/service/ipmb rpc count records success

2026-01-29 15:32:43.751225 unknown_service NOTICE: debug.lua(877): dump ipmi_core/service/btc rpc count records success

2026-01-29 15:32:43.868874 ipmi_core NOTICE: ipmi_dump.lua(33): dump_route_table collect start.

2026-01-29 15:32:44.006886 ipmi_core NOTICE: ipmi_dump.lua(46): dump_route_table collect end.

2026-01-29 15:32:44.009274 ipmi_core NOTICE: ipmi_dump.lua(96): ipmi_core dump end.

2026-01-29 15:32:44.113779 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 26, State: Running] successfully

2026-01-29 15:32:44.298239 om ERROR: alm_callback.c(789): [license][alm] 0012[TIME]2026-01-29 15:32:44 lgen-ck err–350: Prd[1] ALM_LicGeneralQuery end 2030043238

2026-01-29 15:32:44.298851 license ERROR: license_dump.lua(111): Get revoke ticket from db failed

2026-01-29 15:32:44.300831 om ERROR: alm_callback.c(789): [license][alm] 0013[TIME]2026-01-29 15:32:44 lgen-ck err–350: Prd[1] ALM_LicGeneralQuery end 2030043238

2026-01-29 15:32:44.301695 license ERROR: license_dump.lua(64): failed to get feature key info, ret = 0x79000066

2026-01-29 15:32:44.400804 om ERROR: alm_callback.c(789): [license][alm] 0014[TIME]2026-01-29 15:32:44 lgen-ck err–350: Prd[1] ALM_LicGeneralQuery end 2030043238

2026-01-29 15:32:44.930473 general_hardware NOTICE: unit_manager.lua(651): unit_manager:on_dump_cb start.

2026-01-29 15:32:45.216354 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 28, State: Running] successfully

2026-01-29 15:32:45.440125 metric_analyzer NOTICE: metric_dump.lua(90): metric.db dump completed

2026-01-29 15:32:45.442522 metric_analyzer NOTICE: metric_dump.lua(90): per_poweroff.db dump completed

2026-01-29 15:32:45.444725 metric_analyzer NOTICE: metric_dump.lua(90): per_reset.db dump completed

2026-01-29 15:32:45.774042 general_hardware NOTICE: unit.lua(487): CpuBoard1 collect finish

2026-01-29 15:32:46.187855 general_hardware NOTICE: unit.lua(487): ExpBoard1 collect finish

2026-01-29 15:32:46.312861 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 31, State: Running] successfully

2026-01-29 15:32:46.421143 network_adapter NOTICE: log_netcard_info.lua(149): log_dump_cb: collect netcard info

2026-01-29 15:32:46.422002 network_adapter NOTICE: log_netcard_info.lua(93): start collect netcard info

2026-01-29 15:32:46.484920 network_adapter NOTICE: log_netcard_info.lua(154): finish to dump net card info log

2026-01-29 15:32:46.485411 network_adapter NOTICE: network_adapter.lua(2376): optical module cnt is 0

2026-01-29 15:32:46.520765 general_hardware NOTICE: unit.lua(487): FanBoard1 collect finish

2026-01-29 15:32:47.034355 general_hardware NOTICE: unit.lua(487): DiskBP1 collect finish

2026-01-29 15:32:47.038019 general_hardware NOTICE: unit_manager.lua(731): HDD Backplane Info collect finish

2026-01-29 15:32:47.041149 general_hardware NOTICE: unit_manager.lua(731): FAN board Info collect finish

2026-01-29 15:32:47.044331 general_hardware NOTICE: unit_manager.lua(731): EXP board info collect finish

2026-01-29 15:32:47.047577 general_hardware NOTICE: unit_manager.lua(731): CPU board Info collect finish

2026-01-29 15:32:47.051466 general_hardware NOTICE: unit_manager.lua(731): Riser Card Info collect finish

2026-01-29 15:32:47.054237 general_hardware NOTICE: mcu_service.lua(207): mcu info collect finish

2026-01-29 15:32:47.067914 general_hardware NOTICE: smc_interface.lua(516): total_len:120 register_num:8 register_len:15

2026-01-29 15:32:47.068951 general_hardware NOTICE: smc_interface.lua(540): [vrd_dump]The 0 times get vrd data, read_len: 120

2026-01-29 15:32:47.422381 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 33, State: Running] successfully

2026-01-29 15:32:47.895773 general_hardware NOTICE: smc_interface.lua(569): [VrdDump]get vrd dump cnt 120

2026-01-29 15:32:47.929529 general_hardware NOTICE: mcu_service.lua(175): vrd ifx info collect finish

2026-01-29 15:32:47.991575 general_hardware NOTICE: vrd_manager.lua(204): vrd info collect finish

2026-01-29 15:32:48.306684 power_mgmt NOTICE: log_service.lua(156): collect power_mgmt log start

2026-01-29 15:32:48.317378 general_hardware NOTICE: fructl_handler.lua(76): get_power_state: system[1] get power power OFF

2026-01-29 15:32:48.528657 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 36, State: Running] successfully

2026-01-29 15:32:48.657367 pcie_device NOTICE: biz_topo.lua(1239): [BizTopo] cable log dump.

2026-01-29 15:32:49.453529 remote_console NOTICE: dump.lua(51): remote_console dump begin

2026-01-29 15:32:49.457097 unknown_service NOTICE: debug.lua(877): dump remote_console/service/console_agent rpc count records success

2026-01-29 15:32:49.458364 unknown_service NOTICE: debug.lua(877): dump remote_console/service/vmm_agent rpc count records success

2026-01-29 15:32:49.459590 unknown_service NOTICE: debug.lua(877): dump remote_console/service/vmm_client rpc count records success

2026-01-29 15:32:49.459610 unknown_service NOTICE: debug.lua(877): dump remote_console/service/vmm_core rpc count records success

2026-01-29 15:32:49.461474 unknown_service NOTICE: debug.lua(877): dump remote_console/service/videoplay_agent rpc count records success

2026-01-29 15:32:49.578427 redfish NOTICE: event.lua(621): redfish dump is started

2026-01-29 15:32:49.585095 redfish NOTICE: event.lua(634): redfish dump is completed

2026-01-29 15:32:49.596110 redfish NOTICE: debug.lua(877): dump route_mapper/worker_service/main rpc count records success

2026-01-29 15:32:49.621306 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 38, State: Running] successfully

2026-01-29 15:32:50.729380 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 40, State: Running] successfully

2026-01-29 15:32:51.212922 unknown_service NOTICE: debug.lua(877): dump storage/service/smld rpc count records success

2026-01-29 15:32:51.658282 storage NOTICE: storage_app.lua(78): Dumping bios_boot_stage

2026-01-29 15:32:51.668431 storage NOTICE: storage_app.lua(80): Dumping controllers info

2026-01-29 15:32:51.828252 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 42, State: Running] successfully

2026-01-29 15:32:52.928936 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 45, State: Running] successfully

2026-01-29 15:32:53.034696 web_backend NOTICE: debug.lua(877): dump route_mapper/worker_service/main rpc count records success

2026-01-29 15:32:55.761923 unknown_service ERROR: screenshot_api.lua(48): get lastframe ready state failed

2026-01-29 15:32:55.763117 remote_console NOTICE: dump.lua(47): VCE info collect finish

2026-01-29 15:32:55.769532 remote_console NOTICE: dump.lua(61): remote_console dump end

2026-01-29 15:32:56.228364 oms NOTICE: task_mgmt.lua(443): Update task[Id: 2394703721, StartTime: 2026-01-29T15:32:36+08:00, Progress: 46, State: Running] successfully

2026-01-29 15:32:58.476105 power_mgmt NOTICE: psu_service.lua(86): get_black_box_info start

2026-01-29 15:32:58.476728 power_mgmt NOTICE: PsuSlot.lua(159): ps1 power monitor stop

2026-01-29 15:32:58.477279 power_mgmt NOTICE: PsuSlot.lua(326): stop psu power monitor remote task, ps_id=1, monitor task_id=2

2026-01-29 15:32:58.552359 power_mgmt NOTICE: PsuSlot.lua(159): ps2 power monitor stop

2026-01-29 15:32:58.552927 power_mgmt NOTICE: PsuSlot.lua(326): stop psu power monitor remote task, ps_id=2, monitor task_id=1

2026-01-29 15:32:58.751302 power_mgmt NOTICE: psu_service.lua(93): psm_get_black_box_info psu_id 1 start

2026-01-29 15:33:02.504999 thermal_mgmt NOTICE: fan_service.lua(270): Get fans config obj success.

2026-01-29 15:33:02.511104 thermal_mgmt NOTICE: fan_service.lua(246): Get fan(id: 3) pwm success, hardware pwm: 89, pwm: 0

2026-01-29 15:33:02.924931 bios NOTICE: log_collector.lua(99): [bios]finish to dump bios log

2026-01-29 15:33:03.268786 power_mgmt NOTICE: psu_service.lua(104): psm_get_black_box_info psu_id 1 end

2026-01-29 15:33:03.269444 power_mgmt NOTICE: psu_service.lua(93): psm_get_black_box_info psu_id 2 start

2026-01-29 15:33:04.007106 thermal_mgmt NOTICE: fan_service.lua(246): Get fan(id: 2) pwm success, hardware pwm: 89, pwm: 0

2026-01-29 15:33:05.512060 thermal_mgmt NOTICE: fan_service.lua(246): Get fan(id: 4) pwm success, hardware pwm: 89, pwm: 0

2026-01-29 15:33:07.009158 thermal_mgmt NOTICE: fan_service.lua(246): Get fan(id: 1) pwm success, hardware pwm: 89, pwm: 0

2026-01-29 15:33:07.686100 power_mgmt NOTICE: psu_service.lua(104): psm_get_black_box_info psu_id 2 end

2026-01-29 15:33:07.687658 power_mgmt NOTICE: psu_service.lua(107): get_black_box_info end

2026-01-29 15:33:07.696749 power_mgmt NOTICE: PsuSlot.lua(533): Current bmc uptime is 25434

2026-01-29 15:33:07.697041 power_mgmt NOTICE: PsuSlot.lua(539): ps1 power monitor start

2026-01-29 15:33:07.698321 power_mgmt NOTICE: PsuSlot.lua(533): Current bmc uptime is 25434

2026-01-29 15:33:07.698567 power_mgmt NOTICE: PsuSlot.lua(539): ps2 power monitor start

2026-01-29 15:33:08.511451 thermal_mgmt NOTICE: thermal_mgmt_app.lua(225): collect log finished

2026-01-29 15:33:10.407770 power_mgmt NOTICE: PsuSlot.lua(303): start psu power monitor remote task, ps_id=1, task_id=1

2026-01-29 15:33:10.809985 power_mgmt NOTICE: PsuSlot.lua(303): start psu power monitor remote task, ps_id=2, task_id=2

2026-01-29 15:33:11.700505 power_mgmt NOTICE: log_service.lua(165): collect power_mgmt log finished

2026-01-29 15:33:17.839852 native_code NOTICE: shmlock_manager_core.cpp(68): LockManager: attached to block lock_manager via handle, size=10223376

尝试过的解决方案

[列出您已经尝试过的解决方案及结果]

2

答案内容由AI生成，仅供参考：

openUBMC 2512LTS 版本升级失败问题分析与解决建议

根据您提供的日志和环境信息，本次升级失败的核心问题已定位，以下是详细的分析和解决方案。

问题概述

在尝试将 openUBMC 升级至 25.12.00.02 版本（2512LTS）时，升级过程在解压镜像阶段失败。核心错误日志如下：

Failed to search command: /bin/tar xvf /dev/shm/upgrade/rootfs_img.tar.gz -O rootfs_BMC.img
Function execve : mismatched command /bin/tar
tar rootfs img to flash failed, ret: 126
Write bmc package to flash failed

这表明系统未能成功执行 tar 命令来解压固件包。

根本原因分析

结合知识库信息和当前日志，失败的根本原因在于：

  1. 签名校验与系统完整性策略冲突：您在社区发行版源码的基础上增加了签名校验。mismatched command /bin/tar 这一错误强烈暗示系统中存在某种安全沙箱、执行策略或权限控制机制（如 SELinux、AppArmor 或自定义的执行钩子），该机制拦截了对 /bin/tar 的调用。
  2. 命令执行被拒绝：错误码 126 通常表示 “command invoked cannot execute”（无法执行命令），与“命令不匹配 (mismatched command)”的错误信息相吻合。这说明 bmc_upgrade 进程虽然发起了系统调用，但内核或中间件出于安全策略的考虑，拒绝了此执行请求。
  3. 自定义签名校验的副作用：您增加的签名校验可能触发了更严格的安全模型，不仅校验了固件，还对升级过程中调用的所有系统工具（如 tar）的合法性和权限进行了审查，导致合法的 tar 命令被执行时被误判为不匹配或不可信。

与知识库信息的关联

  1. bmc_upgrade 服务在流程中的角色：日志中 bmc_upgrade NOTICE: decompress_img.lua(75): begin to extract img file 和 firmware_mgmt 模块的协同工作，与知识库中描述的 Bmc_Upgrade 服务负责“提取和写入包到闪存”完全一致。
  2. AppDump 路径信息：日志中出现的 /dev/shm/upgrade/ 路径，是 bmc_upgrade 服务用于临时存放升级包的标准位置，与知识库中 bmc_upgrade 服务的功能相符。
  3. 类似升级失败模式：知识库中的 Upgrade Failure 和 Firmware Upgrade Failure 描述了升级过程因执行阶段报错而失败的情况，与本次 write package to flash 后的 decompress_img 步骤失败形成高度相似的模式。

解决方案与建议

方案一：审查并调整签名校验引入的安全策略（推荐）

这是最根本的解决方法。

  1. 检查安全模块：登录BMC系统，检查是否启用了SELinux、AppArmor或类似的MAC（强制访问控制）模块。
    # 检查 SELinux
sestatus
# 检查 AppArmor
systemctl status apparmor
  2. 审查自定义代码：仔细检查您添加的签名校验代码，确认是否在 bmc_upgrade 服务的启动脚本或执行环境中添加了限制性的安全上下文或执行钩子。
  3. 白名单机制：如果安全策略是您自定义签名校验的一部分，务必为 bmc_upgrade 进程允许调用的关键系统命令，例如 /bin/tar。

方案二：验证系统 tar 命令的可执行性

在进行升级前，手动验证 tar 命令在目标环境中是否正常工作。

  1. 通过SSH登录BMC。
  2. 检查 /bin/tar 是否存在且有执行权限：
    ls -l /bin/tar
  3. 尝试执行一个简单的 tar 命令，看是否会触发相同的“mismatched command”错误：
    /bin/tar --version
    如果此命令也失败，则确认是执行层面的问题。

方案三：检查 bmc_upgrade 服务的权限和上下文

  1. 服务权限：确保 bmc_upgrade 服务以一个能够访问和执行系统二进制文件的用户身份运行（通常是 root）。
  2. 脚本完整性：升级包中的 decompress_img.lua 脚本可能硬编码了 /bin/tar 路径。虽然概率较低，但也应确认该路径在您的系统中是正确的（例如，系统中 tar 是否位于 /usr/bin/tar？）。如果是，则需要修改脚本或创建软链接。

方案四：临时禁用安全策略进行测试（仅用于诊断）

为确认问题是否由安全策略引起，可临时禁用相关模块。

# 临时禁用SELinux（如果启用）
setenforce 0

# 然后尝试重新升级
# (测试后，请根据结果决定是否 `setenforce 1` 重新启用)

如果禁用后升级成功，则强烈证明您的自定义签名校验引入了不兼容的安全策略。

总结

升级失败是由于 自定义添加的签名校验机制 与系统执行环境之间产生了冲突，导致 bmc_upgrade 服务在关键的解包步骤中无法执行 /bin/tar 命令。这不是一个固件包本身的问题，而是一个安全策略与系统服务执行权限的兼容性问题。

建议优先检查并修正由签名校验引入的安全模型，确保升级过程所需的核心系统命令能够在 bmc_upgrade 进程的上下文中被正常调用。 成功解决此兼容性问题后，升级应能顺利完成。

相关链接：

  1. 25.09版本真机升级失败求助
  2. 我按照“从零开始，玩转openUBMC”搭建环境没有hpm_signer工具，怎么制作和生成过度包？
  3. 升级白牌包到5%失败
  4. OpenUBMC2506 BMC固件升级失败
  5. BIOS实现和带内交互介绍 | 文档中心 | openUBMC
3

请问环境上BMC是什么时候的包呢？