luxonis / rae-ros

Implementation of RAE ROS and gazebo stack
MIT License
30 stars 13 forks source link

VPU crash #48

Open sskorol opened 10 months ago

sskorol commented 10 months ago

Steps:

Expected: nodes are successfully started Actual: VPU has crashed

Randomly caught it several times in the past week. But in general, RAE can't easily recover from this state. Only hard shutdown helps to resurrect VPU again. Here's what I caught in logs when it happened:

ros2 launch rae_bringup robot.launch.py
[INFO] [launch]: All log files can be found below /root/.ros/log/2023-10-21-19-12-13-121861-rae-901
[INFO] [launch]: Default logging verbosity is set to INFO
[INFO] [component_container-1]: process started with pid [915]
[INFO] [robot_state_publisher-2]: process started with pid [917]
[INFO] [ekf_node-3]: process started with pid [919]
[INFO] [ros2_control_node-4]: process started with pid [921]
[INFO] [spawner-5]: process started with pid [923]
[INFO] [spawner-6]: process started with pid [925]
[INFO] [mic_node-7]: process started with pid [927]
[INFO] [speakers_node-8]: process started with pid [929]
[INFO] [battery_status.py-9]: process started with pid [931]
[component_container-1] [INFO] [1697915536.759803476] [rae_container]: Load Library: /underlay_ws/install/depthai_ros_driver/lib/libdepthai_ros_driver.so
[robot_state_publisher-2] [INFO] [1697915537.039387815] [robot_state_publisher]: got segment base_footprint
[robot_state_publisher-2] [INFO] [1697915537.046802611] [robot_state_publisher]: got segment base_link
[robot_state_publisher-2] [INFO] [1697915537.046892902] [robot_state_publisher]: got segment base_link_back
[robot_state_publisher-2] [INFO] [1697915537.046931986] [robot_state_publisher]: got segment chassis
[robot_state_publisher-2] [INFO] [1697915537.046968277] [robot_state_publisher]: got segment left_caster_wheel
[robot_state_publisher-2] [INFO] [1697915537.047004403] [robot_state_publisher]: got segment left_wheel
[robot_state_publisher-2] [INFO] [1697915537.047040319] [robot_state_publisher]: got segment rae_imu_frame
[robot_state_publisher-2] [INFO] [1697915537.047076486] [robot_state_publisher]: got segment rae_left_back_camera_frame
[robot_state_publisher-2] [INFO] [1697915537.047113361] [robot_state_publisher]: got segment rae_left_back_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915537.047152111] [robot_state_publisher]: got segment rae_left_camera_frame
[robot_state_publisher-2] [INFO] [1697915537.047189069] [robot_state_publisher]: got segment rae_left_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915537.047226486] [robot_state_publisher]: got segment rae_rgb_camera_frame
[robot_state_publisher-2] [INFO] [1697915537.047473194] [robot_state_publisher]: got segment rae_rgb_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915537.054898324] [robot_state_publisher]: got segment rae_right_back_camera_frame
[robot_state_publisher-2] [INFO] [1697915537.054986074] [robot_state_publisher]: got segment rae_right_back_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915537.055050741] [robot_state_publisher]: got segment rae_right_camera_frame
[robot_state_publisher-2] [INFO] [1697915537.055092282] [robot_state_publisher]: got segment rae_right_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915537.055156282] [robot_state_publisher]: got segment right_caster_wheel
[robot_state_publisher-2] [INFO] [1697915537.055196574] [robot_state_publisher]: got segment right_wheel
[ros2_control_node-4] [INFO] [1697915537.299739972] [resource_manager]: Loading hardware 'RAE' 
[ekf_node-3] X acceleration is being measured from IMU; X velocity control input is disabled
[ekf_node-3] use_control is set to true, but control_config has only false values. No control term will be used.
[ros2_control_node-4] [INFO] [1697915537.981454801] [resource_manager]: Initialize hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915537.990737432] [resource_manager]: Successful initialization of hardware 'RAE'
[ros2_control_node-4] [INFO] [1697915537.991079349] [resource_manager]: 'configure' hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915538.000984146] [resource_manager]: Successful 'configure' of hardware 'RAE'
[ros2_control_node-4] [INFO] [1697915538.008744693] [resource_manager]: 'activate' hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915538.015841280] [resource_manager]: Successful 'activate' of hardware 'RAE'
[ros2_control_node-4] [INFO] [1697915538.132318475] [controller_manager]: update rate is 100 Hz
[ros2_control_node-4] [INFO] [1697915538.146974192] [controller_manager]: RT kernel is recommended for better performance
[component_container-1] [INFO] [1697915538.277393936] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<depthai_ros_driver::Camera>
[component_container-1] [INFO] [1697915538.277898311] [rae_container]: Instantiate class: rclcpp_components::NodeFactoryTemplate<depthai_ros_driver::Camera>
[mic_node-7] [INFO] [1697915538.366772781] [mic_node]: Mic node running!
[speakers_node-8] [INFO] [1697915538.377270120] [speakers_node]: Speakers node running!
[component_container-1] [INFO] [1697915538.409901557] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [2023-10-21 19:12:18.440] [depthai] [warning] USB protocol not available - If running in a container, make sure that the following is set: "-v /dev/bus/usb:/dev/bus/usb --device-cgroup-rule='c 189:* rmw'"
[ros2_control_node-4] [INFO] [1697915540.690126724] [controller_manager]: Loading controller 'joint_state_broadcaster'
[ros2_control_node-4] [INFO] [1697915540.934130615] [controller_manager]: Loading controller 'diff_controller'
[spawner-5] [INFO] [1697915541.254460005] [spawner_diff_controller]: Loaded diff_controller
[ros2_control_node-4] [INFO] [1697915541.274109266] [controller_manager]: Configuring controller 'diff_controller'
[spawner-6] [INFO] [1697915541.291942735] [spawner_joint_state_broadcaster]: Loaded joint_state_broadcaster
[ros2_control_node-4] [INFO] [1697915541.325846754] [controller_manager]: Configuring controller 'joint_state_broadcaster'
[ros2_control_node-4] [INFO] [1697915541.326277421] [joint_state_broadcaster]: 'joints' or 'interfaces' parameter is empty. All available state interfaces will be published
[spawner-5] [INFO] [1697915541.398116378] [spawner_diff_controller]: Configured and activated diff_controller
[spawner-6] [INFO] [1697915541.449936283] [spawner_joint_state_broadcaster]: Configured and activated joint_state_broadcaster
[INFO] [spawner-5]: process has finished cleanly [pid 923]
[INFO] [spawner-6]: process has finished cleanly [pid 925]
[component_container-1] [INFO] [1697915545.540972979] [rae]: Camera with MXID: xlinkserver and Name: 127.0.0.1 connected!
[component_container-1] [INFO] [1697915545.541175062] [rae]: PoE camera detected. Consider enabling low bandwidth for specific image topics (see readme).
[component_container-1] [INFO] [1697915545.573147329] [rae]: Device type: RAE
[component_container-1] [INFO] [1697915545.862981941] [rae]: Pipeline type: rae
[component_container-1] [INFO] [1697915546.948649755] [rae]: Finished setting up pipeline.
[component_container-1] [INFO] [1697915547.680929336] [rae]: Camera ready!
[INFO] [launch_ros.actions.load_composable_nodes]: Loaded node '/rae' in container '/rae_container'
[component_container-1] [INFO] [1697915547.686688089] [rae_container]: Load Library: /ws/install/rae_hw/lib/librae_hw.so
[component_container-1] [INFO] [1697915547.755684291] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::BatteryNode>
[component_container-1] [INFO] [1697915547.755879708] [rae_container]: Instantiate class: rclcpp_components::NodeFactoryTemplate<rae_hw::BatteryNode>
[INFO] [launch_ros.actions.load_composable_nodes]: Loaded node '/battery_node' in container 'rae_container'
[component_container-1] [INFO] [1697915547.825451743] [battery_node]: Battery node running!
[component_container-1] [INFO] [1697915547.841370918] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::BatteryNode>
[component_container-1] [INFO] [1697915547.841543918] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::LCDNode>
[component_container-1] [INFO] [1697915547.841583751] [rae_container]: Instantiate class: rclcpp_components::NodeFactoryTemplate<rae_hw::LCDNode>
[component_container-1] [ERROR] [1697915548.034614974] [rae_container]: Component constructor threw an exception: Statically typed parameter 'default_logo_path' must be initialized.
[ERROR] [launch_ros.actions.load_composable_nodes]: Failed to load node 'lcd_node' of type 'rae_hw::LCDNode' in container 'rae_container': Component constructor threw an exception: Statically typed parameter 'default_logo_path' must be initialized.
[component_container-1] [INFO] [1697915548.052709400] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::BatteryNode>
[component_container-1] [INFO] [1697915548.052865067] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::LCDNode>
[component_container-1] [INFO] [1697915548.053017484] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::LEDNode>
[component_container-1] [INFO] [1697915548.053061109] [rae_container]: Instantiate class: rclcpp_components::NodeFactoryTemplate<rae_hw::LEDNode>
[component_container-1] [INFO] [1697915548.164287664] [led_node]: LED node running!
[INFO] [launch_ros.actions.load_composable_nodes]: Loaded node '/led_node' in container 'rae_container'
[component_container-1] [INFO] [1697915548.329528997] [battery_node]: Power supply status changed to [Charging] after 0 h 0 min 0 secs.
[component_container-1] [4B928816838860C6] [127.0.0.1] [1697915548.551] [system] [critical] VPU crash dump found, saved to: /tmp/gate_fw_vje7sd3t/485e53f7b26b4c79c89d13afc817c7c279630434c15fb52103ffa5c792c86074/crashDump.json
[INFO] [launch.user]: Resetting PWM.
[INFO] [busybox devmem 0x20320180 32 0x00000000-10]: process started with pid [1045]
[INFO] [busybox devmem 0x20320180 32 0x00000000-10]: process has finished cleanly [pid 1045]
[component_container-1] [INFO] [1697915553.828914946] [battery_node]: Power supply status changed to [Discharging] after 0 h 0 min 5 secs.
[component_container-1] [4B928816838860C6] [127.0.0.1] [1697915558.548] [StereoDepth(12)] [error] Node threw exception CallVpu error: -1

Key takes:

[component_container-1] [4B928816838860C6] [127.0.0.1] [1697915548.551] [system] [critical] VPU crash dump found, saved to: /tmp/gate_fw_vje7sd3t/485e53f7b26b4c79c89d13afc817c7c279630434c15fb52103ffa5c792c86074/crashDump.json
[component_container-1] [4B928816838860C6] [127.0.0.1] [1697915558.548] [StereoDepth(12)] [error] Node threw exception CallVpu error: -1

Attaching the crash dump.

Note that I tried to reload the container and even restart RAE, but every further run caused the same error that there are no available devices:

root@rae:/ws# ros2 launch rae_bringup robot.launch.py
[INFO] [launch]: All log files can be found below /root/.ros/log/2023-10-21-19-18-37-837447-rae-1103
[INFO] [launch]: Default logging verbosity is set to INFO
[INFO] [component_container-1]: process started with pid [1117]
[INFO] [robot_state_publisher-2]: process started with pid [1119]
[INFO] [ekf_node-3]: process started with pid [1121]
[INFO] [ros2_control_node-4]: process started with pid [1123]
[INFO] [spawner-5]: process started with pid [1125]
[INFO] [spawner-6]: process started with pid [1127]
[INFO] [mic_node-7]: process started with pid [1129]
[INFO] [speakers_node-8]: process started with pid [1133]
[INFO] [battery_status.py-9]: process started with pid [1142]
[component_container-1] [INFO] [1697915920.665089864] [rae_container]: Load Library: /ws/install/rae_hw/lib/librae_hw.so
[robot_state_publisher-2] [INFO] [1697915920.780842500] [robot_state_publisher]: got segment base_footprint
[robot_state_publisher-2] [INFO] [1697915920.788166407] [robot_state_publisher]: got segment base_link
[robot_state_publisher-2] [INFO] [1697915920.795050689] [robot_state_publisher]: got segment base_link_back
[robot_state_publisher-2] [INFO] [1697915920.795129314] [robot_state_publisher]: got segment chassis
[robot_state_publisher-2] [INFO] [1697915920.795167981] [robot_state_publisher]: got segment left_caster_wheel
[robot_state_publisher-2] [INFO] [1697915920.799579600] [robot_state_publisher]: got segment left_wheel
[robot_state_publisher-2] [INFO] [1697915920.799822350] [robot_state_publisher]: got segment rae_imu_frame
[robot_state_publisher-2] [INFO] [1697915920.799864183] [robot_state_publisher]: got segment rae_left_back_camera_frame
[robot_state_publisher-2] [INFO] [1697915920.799902850] [robot_state_publisher]: got segment rae_left_back_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915920.799941724] [robot_state_publisher]: got segment rae_left_camera_frame
[robot_state_publisher-2] [INFO] [1697915920.799979599] [robot_state_publisher]: got segment rae_left_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915920.800016516] [robot_state_publisher]: got segment rae_rgb_camera_frame
[robot_state_publisher-2] [INFO] [1697915920.800811723] [robot_state_publisher]: got segment rae_rgb_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915920.800916598] [robot_state_publisher]: got segment rae_right_back_camera_frame
[robot_state_publisher-2] [INFO] [1697915920.800957223] [robot_state_publisher]: got segment rae_right_back_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915920.800995181] [robot_state_publisher]: got segment rae_right_camera_frame
[robot_state_publisher-2] [INFO] [1697915920.801032723] [robot_state_publisher]: got segment rae_right_camera_optical_frame
[robot_state_publisher-2] [INFO] [1697915920.801070265] [robot_state_publisher]: got segment right_caster_wheel
[robot_state_publisher-2] [INFO] [1697915920.801107348] [robot_state_publisher]: got segment right_wheel
[ekf_node-3] X acceleration is being measured from IMU; X velocity control input is disabled
[ekf_node-3] use_control is set to true, but control_config has only false values. No control term will be used.
[ros2_control_node-4] [INFO] [1697915920.987014140] [resource_manager]: Loading hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915921.551319424] [resource_manager]: Initialize hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915921.555050211] [resource_manager]: Successful initialization of hardware 'RAE'
[ros2_control_node-4] [INFO] [1697915921.555403461] [resource_manager]: 'configure' hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915921.564000699] [resource_manager]: Successful 'configure' of hardware 'RAE'
[ros2_control_node-4] [INFO] [1697915921.564155574] [resource_manager]: 'activate' hardware 'RAE' 
[ros2_control_node-4] [INFO] [1697915921.564192532] [resource_manager]: Successful 'activate' of hardware 'RAE'
[ros2_control_node-4] [INFO] [1697915921.665503563] [controller_manager]: update rate is 100 Hz
[ros2_control_node-4] [INFO] [1697915921.691775486] [controller_manager]: RT kernel is recommended for better performance
[component_container-1] [INFO] [1697915921.767732550] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<rae_hw::BatteryNode>
[component_container-1] [INFO] [1697915921.773583459] [rae_container]: Instantiate class: rclcpp_components::NodeFactoryTemplate<rae_hw::BatteryNode>
[mic_node-7] [INFO] [1697915921.790000562] [mic_node]: Mic node running!
[component_container-1] [INFO] [1697915921.941992483] [battery_node]: Battery node running!
[INFO] [launch_ros.actions.load_composable_nodes]: Loaded node '/battery_node' in container 'rae_container'
[component_container-1] [INFO] [1697915921.944774521] [rae_container]: Load Library: /underlay_ws/install/depthai_ros_driver/lib/libdepthai_ros_driver.so
[component_container-1] [INFO] [1697915922.228020682] [rae_container]: Found class: rclcpp_components::NodeFactoryTemplate<depthai_ros_driver::Camera>
[component_container-1] [INFO] [1697915922.229131097] [rae_container]: Instantiate class: rclcpp_components::NodeFactoryTemplate<depthai_ros_driver::Camera>
[speakers_node-8] [INFO] [1697915922.275798368] [speakers_node]: Speakers node running!
[component_container-1] [INFO] [1697915922.341636987] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [2023-10-21 19:18:42.353] [depthai] [warning] USB protocol not available - If running in a container, make sure that the following is set: "-v /dev/bus/usb:/dev/bus/usb --device-cgroup-rule='c 189:* rmw'"
[ros2_control_node-4] [INFO] [1697915923.732574240] [controller_manager]: Loading controller 'joint_state_broadcaster'
[spawner-6] [INFO] [1697915924.302596847] [spawner_joint_state_broadcaster]: Loaded joint_state_broadcaster
[ros2_control_node-4] [INFO] [1697915924.338333965] [controller_manager]: Configuring controller 'joint_state_broadcaster'
[ros2_control_node-4] [INFO] [1697915924.338870840] [joint_state_broadcaster]: 'joints' or 'interfaces' parameter is empty. All available state interfaces will be published
[spawner-6] [INFO] [1697915924.428230386] [spawner_joint_state_broadcaster]: Configured and activated joint_state_broadcaster
[INFO] [spawner-6]: process has finished cleanly [pid 1127]
[ros2_control_node-4] [INFO] [1697915925.100175812] [controller_manager]: Loading controller 'diff_controller'
[spawner-5] [INFO] [1697915925.216702405] [spawner_diff_controller]: Loaded diff_controller
[ros2_control_node-4] [INFO] [1697915925.225400351] [controller_manager]: Configuring controller 'diff_controller'
[spawner-5] [INFO] [1697915925.288347850] [spawner_diff_controller]: Configured and activated diff_controller
[INFO] [spawner-5]: process has finished cleanly [pid 1125]
[component_container-1] [ERROR] [1697915926.491951889] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915926.492175013] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915930.623728570] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915930.623943945] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915934.711731984] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915934.711957650] [rae]: No ip/mxid specified, connecting to the next available device.
[INFO] [launch.user]: Resetting PWM.
[INFO] [busybox devmem 0x20320180 32 0x00000000-10]: process started with pid [1232]
[INFO] [busybox devmem 0x20320180 32 0x00000000-10]: process has finished cleanly [pid 1232]
[component_container-1] [ERROR] [1697915938.803749733] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915938.803962149] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915942.931719689] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915942.931935689] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915947.019724000] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915947.019950500] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915951.143768853] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915951.144122394] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915955.219725240] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915955.219937240] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915959.327722800] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915959.327946675] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915963.459735336] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915963.459943961] [rae]: No ip/mxid specified, connecting to the next available device.
[component_container-1] [ERROR] [1697915967.575717488] [rae]: No available devices (3 connected, but in use)
[component_container-1] [INFO] [1697915967.575947988] [rae]: No ip/mxid specified, connecting to the next available device.
sskorol commented 10 months ago

Also, noticed the following errors in the system logs:

vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8

Not sure if it might be potentially related to overheating. But here's the full log after boot. There are even more errors.

[Oct23 15:35] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
[  +0.000000] Linux version 5.10.32-intel-standard (oe-user@oe-host) (aarch64-ese-linux-gcc (GCC) 9.2.1 20191025, GNU ld (GNU Binutils) 2.34.0.20200220) #1 SMP PREEMPT Thu Sep 28 14:27:16 UTC 2023
[  +0.000000] Machine model: rae
[  +0.000000] earlycon: uart8250 at MMIO32 0x0000000020180000 (options '')
[  +0.000000] printk: bootconsole [uart8250] enabled
[  +0.000000] efi: UEFI not found.
[  +0.000000] [Firmware Bug]: Kernel image misaligned at boot, please fix your bootloader!
[  +0.000000] Reserved memory: created DMA memory pool at 0x0000000084800000, size 256 MiB
[  +0.000000] OF: reserved mem: initialized node vpu_reserved@84800000, compatible id shared-dma-pool
[  +0.000000] Reserved memory: created DMA memory pool at 0x0000000094800000, size 2 MiB
[  +0.000000] OF: reserved mem: initialized node ipc_buffers@94800000, compatible id shared-dma-pool
[  +0.000000] Reserved memory: created DMA memory pool at 0x0000000094a00000, size 2 MiB
[  +0.000000] OF: reserved mem: initialized node ipc_buffers@94A00000, compatible id shared-dma-pool
[  +0.000000] Reserved memory: created DMA memory pool at 0x0000000094c00000, size 1 MiB
[  +0.000000] OF: reserved mem: initialized node xlink_buffers@94C00000, compatible id shared-dma-pool
[  +0.000000] Reserved memory: created DMA memory pool at 0x0000000094d00000, size 1 MiB
[  +0.000000] OF: reserved mem: initialized node xlink_buffers@94D00000, compatible id shared-dma-pool
[  +0.000000] Reserved memory: created CMA memory pool at 0x000000009d000000, size 1328 MiB
[  +0.000000] OF: reserved mem: initialized node pixel_data_cma@0x9d000000, compatible id shared-dma-pool
[  +0.000000] Reserved memory: created DMA memory pool at 0x0000000480000000, size 768 MiB
[  +0.000000] OF: reserved mem: initialized node codec_reserved@480000000, compatible id shared-dma-pool
[  +0.000000] cma: Reserved 32 MiB at 0x00000000fe000000
[  +0.000000] Zone ranges:
[  +0.000000]   DMA      [mem 0x0000000084800000-0x00000000ffffffff]
[  +0.000000]   DMA32    empty
[  +0.000000]   Normal   [mem 0x0000000100000000-0x00000004ffffffff]
[  +0.000000] Movable zone start for each node
[  +0.000000] Early memory node ranges
[  +0.000000]   node   0: [mem 0x0000000084800000-0x0000000094dfffff]
[  +0.000000]   node   0: [mem 0x0000000094e00000-0x00000000ffffffff]
[  +0.000000]   node   0: [mem 0x0000000480000000-0x00000004afffffff]
[  +0.000000]   node   0: [mem 0x00000004b0000000-0x00000004ffffffff]
[  +0.000000] Initmem setup node 0 [mem 0x0000000084800000-0x00000004ffffffff]
[  +0.000000] On node 0 totalpages: 1030144
[  +0.000000]   DMA zone: 7904 pages used for memmap
[  +0.000000]   DMA zone: 0 pages reserved
[  +0.000000]   DMA zone: 505856 pages, LIFO batch:63
[  +0.000000]   Normal zone: 8192 pages used for memmap
[  +0.000000]   Normal zone: 524288 pages, LIFO batch:63
[  +0.000000] psci: probing for conduit method from DT.
[  +0.000000] psci: PSCIv1.1 detected in firmware.
[  +0.000000] psci: Using standard PSCI v0.2 function IDs
[  +0.000000] psci: Trusted OS migration not required
[  +0.000000] psci: SMC Calling Convention v1.1
[  +0.000000] percpu: Embedded 23 pages/cpu s54744 r8192 d31272 u94208
[  +0.000000] pcpu-alloc: s54744 r8192 d31272 u94208 alloc=23*4096
[  +0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[  +0.000000] Detected VIPT I-cache on CPU0
[  +0.000000] CPU features: detected: ARM erratum 845719
[  +0.000000] CPU features: detected: GIC system register CPU interface
[  +0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1014048
[  +0.000000] Kernel command line: root=/dev/mmcblk1p8 rootwait rw mender.data=/dev/mmcblk1p11 earlycon=uart8250,mmio32,0x20180000 verity=0
[  +0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[  +0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[  +0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[  +0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)
[  +0.000000] Memory: 1437824K/4120576K available (11008K kernel code, 1258K rwdata, 4532K rodata, 73792K init, 431K bss, 1290112K reserved, 1392640K cma-reserved)
[  +0.000000] random: get_random_u64 called from __kmem_cache_create+0x2c/0x410 with crng_init=0
[  +0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[  +0.000000] rcu: Preemptible hierarchical RCU implementation.
[  +0.000000] rcu:  RCU event tracing is enabled.
[  +0.000000] rcu:  RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[  +0.000000]   Trampoline variant of Tasks RCU enabled.
[  +0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[  +0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[  +0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[  +0.000000] GICv3: 128 SPIs implemented
[  +0.000000] GICv3: 0 Extended SPIs implemented
[  +0.000000] GICv3: Distributor has no Range Selector support
[  +0.000000] GICv3: 16 PPIs implemented
[  +0.000000] GICv3: CPU0: found redistributor 0 region 0:0x0000000020580000
[  +0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt).
[  +0.000000] arch_timer: Keembay applied clocksource workaround
[  +0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns
[  +0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns
[  +0.008150] Failed to initialize '/soc/counter@203300e8': -6
[  +0.005865] Console: colour dummy device 80x25
[  +0.004441] printk: console [tty0] enabled
[  +0.004096] printk: bootconsole [uart8250] disabled
[  +0.004898] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000)
[  +0.000019] pid_max: default: 32768 minimum: 301
[  +0.000108] LSM: Security Framework initializing
[  +0.000090] AppArmor: AppArmor initialized
[  +0.000053] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[  +0.000022] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[  +0.001268] rcu: Hierarchical SRCU implementation.
[  +0.000995] EFI services will not be available.
[  +0.000150] smp: Bringing up secondary CPUs ...
[  +0.000359] Detected VIPT I-cache on CPU1
[  +0.000026] GICv3: CPU1: found redistributor 1 region 0:0x00000000205a0000
[  +0.000036] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
[  +0.000385] Detected VIPT I-cache on CPU2
[  +0.000013] GICv3: CPU2: found redistributor 2 region 0:0x00000000205c0000
[  +0.000016] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
[  +0.000334] Detected VIPT I-cache on CPU3
[  +0.000012] GICv3: CPU3: found redistributor 3 region 0:0x00000000205e0000
[  +0.000016] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
[  +0.000048] smp: Brought up 1 node, 4 CPUs
[  +0.000092] SMP: Total of 4 processors activated.
[  +0.000013] CPU features: detected: 32-bit EL0 Support
[  +0.000011] CPU features: detected: CRC32 instructions
[  +0.000010] CPU features: detected: 32-bit EL1 Support
[  +0.000061] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching
[  +0.009740] CPU: All CPU(s) started at EL1
[  +0.000026] alternatives: patching kernel code
[  +0.000820] devtmpfs: initialized
[  +0.004531] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[  +0.000025] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[  +0.036242] pinctrl core: initialized pinctrl subsystem
[  +0.000499] DMI not present or invalid.
[  +0.000464] NET: Registered protocol family 16
[  +0.001106] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
[  +0.000096] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[  +0.000184] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[  +0.000037] audit: initializing netlink subsys (disabled)
[  +0.000166] audit: type=2000 audit(0.072:1): state=initialized audit_enabled=0 res=1
[  +0.000304] thermal_sys: Registered thermal governor 'fair_share'
[  +0.000004] thermal_sys: Registered thermal governor 'step_wise'
[  +0.000011] thermal_sys: Registered thermal governor 'user_space'
[  +0.000595] cpuidle: using governor ladder
[  +0.000094] cpuidle: using governor menu
[  +0.000128] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[  +0.000064] ASID allocator initialised with 65536 entries
[  +0.007881] OF: /soc/gpio@20320000: could not find phandle
[  +0.002026] OF: /soc/gpio@20320000: could not find phandle
[  +0.006325] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[  +0.000014] HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
[  +0.000012] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[  +0.000011] HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
[  +0.000937] cryptd: max_cpu_qlen set to 1000
[  +0.001925] iommu: Default domain type: Translated 
[  +0.000147] vgaarb: loaded
[  +0.000230] SCSI subsystem initialized
[  +0.000161] usbcore: registered new interface driver usbfs
[  +0.000037] usbcore: registered new interface driver hub
[  +0.000028] usbcore: registered new device driver usb
[  +0.000517] pps_core: LinuxPPS API ver. 1 registered
[  +0.000011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[  +0.000020] PTP clock support registered
[  +0.000101] EDAC MC: Ver: 3.0.0
[  +0.000369] arm-scmi firmware:scmi: SCMI Notifications - Core Enabled.
[  +0.000050] arm-scmi firmware:scmi: SCMI Protocol v1.0 'INTEL:KMB' Firmware version 0x1
[  +0.000051] arm-scmi firmware:scmi: SCMI protocol 17 not implemented
[  +0.000353] Advanced Linux Sound Architecture Driver Initialized.
[  +0.000367] NetLabel: Initializing
[  +0.000009] NetLabel:  domain hash size = 128
[  +0.000008] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[  +0.000065] NetLabel:  unlabeled traffic allowed by default
[  +0.000235] clocksource: Switched to clocksource arch_sys_counter
[  +0.000132] VFS: Disk quotas dquot_6.6.0
[  +0.000048] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[  +0.000443] AppArmor: AppArmor Filesystem Enabled
[  +0.004280] NET: Registered protocol family 2
[  +0.000448] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[  +0.000050] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[  +0.000190] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[  +0.000541] TCP: Hash tables configured (established 32768 bind 32768)
[  +0.000095] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[  +0.000071] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[  +0.000164] NET: Registered protocol family 1
[  +0.000029] PCI: CLS 0 bytes, default 64
[  +0.133850] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available
[  +0.000354] kvm [1]: HYP mode not available
[  +0.001094] Initialise system trusted keyrings
[  +0.000095] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[  +0.004918] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[  +0.031401] NET: Registered protocol family 38
[  +0.000017] Key type asymmetric registered
[  +0.000011] Asymmetric key parser 'x509' registered
[  +0.000030] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[  +0.000089] io scheduler mq-deadline registered
[  +0.000011] io scheduler kyber registered
[  +0.000838] debugfs: Directory '20400000.usb_phy' with parent 'regmap' already present!
[  +0.002543] gpiochip_find_base: found new base at 432
[  +0.000040] gpio gpiochip0: (20320000.gpio): created GPIO range 0->79 ==> 20320000.gpio PIN 0->79
[  +0.000130] gpio gpiochip0: (20320000.gpio): added GPIO chardev (254:0)
[  +0.000046] gpio gpiochip0: registered GPIOs 432 to 511 on 20320000.gpio
[  +0.000952] keembay-pcie 37000000.pcie: GPIO lookup for consumer reset
[  +0.000005] keembay-pcie 37000000.pcie: using device tree for GPIO lookup
[  +0.000016] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/soc/pcie@37000000[0]'
[  +0.000019] of_get_named_gpiod_flags: parsed 'reset-gpio' property of node '/soc/pcie@37000000[0]' - status (0)
[  +0.003836] dw_axi_dmac_platform 28000000.dma: DesignWare AXI DMA Controller, 8 channels
[  +0.000925]  203300f0.ipc:local_rsvd_mem: assigned reserved memory node ipc_buffers@94A00000
[  +0.000344]  203300f0.ipc:remote_rsvd_mem: assigned reserved memory node ipc_buffers@94800000
[  +0.000290] keembay-ipc 203300f0.ipc: Local vaddr 0x(____ptrval____) vpu_addr 0x0000000094a00000 size 0x200000
[  +0.000016] keembay-ipc 203300f0.ipc: Remote vaddr 0x(____ptrval____) vpu_addr 0x0000000094800000 size 0x200000
[  +0.000276] keembay-ipc 203300f0.ipc: IPC Buffer Pool size: 32768
[  +0.003972] keembay-ipc 203300f0.ipc: Register base for css_fifo: vaddr (____ptrval____) paddr: 0x00000000203300f0
[  +0.000053] keembay-ipc 203300f0.ipc: Register base for mss_fifo: vaddr (____ptrval____) paddr: 0x00000000208200f0
[  +0.000047] keembay-ipc 203300f0.ipc: Registering handler for IRQ 35
[  +0.002821] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[  +0.000989] dw-apb-uart 20150000.serial: GPIO lookup for consumer rs485-term
[  +0.000005] dw-apb-uart 20150000.serial: using device tree for GPIO lookup
[  +0.000018] of_get_named_gpiod_flags: can't parse 'rs485-term-gpios' property of node '/soc/serial@20150000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'rs485-term-gpio' property of node '/soc/serial@20150000[0]'
[  +0.000009] dw-apb-uart 20150000.serial: using lookup tables for GPIO lookup
[  +0.000006] dw-apb-uart 20150000.serial: No GPIO consumer rs485-term found
[  +0.000024] 20150000.serial: ttyS0 at MMIO 0x20150000 (irq = 15, base_baud = 2048) is a 16550A
[  +0.000377] dw-apb-uart 20160000.serial: GPIO lookup for consumer rs485-term
[  +0.000005] dw-apb-uart 20160000.serial: using device tree for GPIO lookup
[  +0.000013] of_get_named_gpiod_flags: can't parse 'rs485-term-gpios' property of node '/soc/serial@20160000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'rs485-term-gpio' property of node '/soc/serial@20160000[0]'
[  +0.000006] dw-apb-uart 20160000.serial: using lookup tables for GPIO lookup
[  +0.000005] dw-apb-uart 20160000.serial: No GPIO consumer rs485-term found
[  +0.000016] 20160000.serial: ttyS1 at MMIO 0x20160000 (irq = 16, base_baud = 2048) is a 16550A
[  +0.000349] dw-apb-uart 20170000.serial: GPIO lookup for consumer rs485-term
[  +0.000005] dw-apb-uart 20170000.serial: using device tree for GPIO lookup
[  +0.000013] of_get_named_gpiod_flags: can't parse 'rs485-term-gpios' property of node '/soc/serial@20170000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'rs485-term-gpio' property of node '/soc/serial@20170000[0]'
[  +0.000006] dw-apb-uart 20170000.serial: using lookup tables for GPIO lookup
[  +0.000004] dw-apb-uart 20170000.serial: No GPIO consumer rs485-term found
[  +0.000017] 20170000.serial: ttyS2 at MMIO 0x20170000 (irq = 17, base_baud = 2048) is a 16550A
[  +0.000332] dw-apb-uart 20180000.serial: GPIO lookup for consumer rs485-term
[  +0.000005] dw-apb-uart 20180000.serial: using device tree for GPIO lookup
[  +0.000012] of_get_named_gpiod_flags: can't parse 'rs485-term-gpios' property of node '/soc/serial@20180000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'rs485-term-gpio' property of node '/soc/serial@20180000[0]'
[  +0.000006] dw-apb-uart 20180000.serial: using lookup tables for GPIO lookup
[  +0.000005] dw-apb-uart 20180000.serial: No GPIO consumer rs485-term found
[  +0.000017] 20180000.serial: ttyS3 at MMIO 0x20180000 (irq = 18, base_baud = 1500000) is a 16550A
[  +0.000025] dw8250_set_termios_keembay: baud= 115200 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +1.135131] printk: console [ttyS3] enabled
[  +0.005281] [drm] Initialized vgem 1.0.0 20120112 for vgem on minor 0
[  +0.006559] cacheinfo: Unable to detect cache hierarchy for CPU 0
[  +0.011301] loop: module loaded
[  +0.003377] xlink-driver soc:xlink: KeemBay xlink v0.1.3:a
[  +0.031494] xlink-driver soc:xlink: Major = 245 Minor = 0
[  +0.005638] xlink-driver soc:xlink: Device Driver Insert...Done!!!
[  +0.006205] xlink-event kobject added
[  +0.003991] keembay-xlink-ipc soc:xlink-ipc: Keem Bay xlink IPC driver probed.
[  +0.007241] keembay-xlink-ipc soc:xlink-ipc:  dev_name soc:xlink-ipc, name xlink_local_reserved
[  +0.008766]  soc:xlink-ipc:xlink_local_reserved: assigned reserved memory node xlink_buffers@94D00000
[  +0.009243] keembay-xlink-ipc soc:xlink-ipc:  dev_name soc:xlink-ipc, name xlink_remote_reserved
[  +0.008823]  soc:xlink-ipc:xlink_remote_reserved: assigned reserved memory node xlink_buffers@94C00000
[  +0.009584] keembay-xlink-ipc soc:xlink-ipc: Local vaddr 0x(____ptrval____) paddr 0x0x0000000094d00000 size 0x100000
[  +0.010519] keembay-xlink-ipc soc:xlink-ipc: Remote vaddr 0x(____ptrval____) paddr 0x0x0000000094c00000 size 0x100000
[  +0.010737] keembay-xlink-ipc soc:xlink-ipc: xlink Buffer Pool size: 2000
[  +0.006798] keembay-xlink-ipc soc:xlink-ipc: Using IPC device: 3f00209c.vpu-ipc
[  +0.007311] keembay-xlink-ipc soc:xlink-ipc: Device id=0 sw_device_id=0x0 name=vpu-slice-0 probe complete.
[  +0.010050] thermal thermal_zone0: failed to read out thermal zone (-22)
[  +0.006737] thermal thermal_zone1: failed to read out thermal zone (-22)
[  +0.006727] thermal thermal_zone2: failed to read out thermal zone (-22)
[  +0.006728] thermal thermal_zone3: failed to read out thermal zone (-22)
[  +0.006871] intel,keembay_thermal intel,keembay_thermal.0: Thermal driver loaded for intel,keembay_thermal
[  +0.009797] vpu_cooling_drv soc:vpu-cooling-device: Get max supported states function called
[  +0.008473] vpu_cooling_drv soc:vpu-cooling-device: Get max supported states function called
[  +0.008450] vpu_cooling_drv soc:vpu-cooling-device: Get max supported states function called
[  +0.008445] vpu_cooling_drv soc:vpu-cooling-device: Get max supported states function called
[  +0.008446] vpu_cooling_drv soc:vpu-cooling-device: Get max supported states function called
[  +0.008453] vpu_cooling_drv soc:vpu-cooling-device: VPU not started. xlink_get_device_status() returned 0 and xlink_device_status=4294967274
[  +0.012603] vpu_cooling_drv soc:vpu-cooling-device: VPU not started. xlink_get_device_status() returned 0 and xlink_device_status=4294967274
[  +0.012609] vpu_cooling_drv soc:vpu-cooling-device: VPU cooling device probe success
[  +0.007885] intel_hddl_client 20320000.hddl_device: Board type is kmb-dm3370-r5m2
[  +0.007492] intel_hddl_client 20320000.hddl_device: This platform does not support soc-reset
[  +0.008637] intel_hddl_client 20320000.hddl_device: HDDL:Device name: 0 vpu-slice-0
[  +0.000795] dw_spi_mmio 201c2000.spi: Unbalanced pm_runtime_enable!
[  +0.013596] dw_spi_mmio 201c4000.spi: Unbalanced pm_runtime_enable!
[  +0.006299] spi_master spi2: GPIO lookup for consumer cs
[  +0.000005] spi_master spi2: using device tree for GPIO lookup
[  +0.000025] of_get_named_gpiod_flags: parsed 'cs-gpios' property of node '/soc/spi@201c4000[0]' - status (0)
[  +0.000258] panel-t0d96bp spi2.0: GPIO lookup for consumer reset
[  +0.000005] panel-t0d96bp spi2.0: using device tree for GPIO lookup
[  +0.000021] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/soc/spi@201c4000/lcd@0[0]' - status (0)
[  +0.000011] panel-t0d96bp spi2.0: GPIO lookup for consumer dc
[  +0.000005] panel-t0d96bp spi2.0: using device tree for GPIO lookup
[  +0.000015] of_get_named_gpiod_flags: parsed 'dc-gpios' property of node '/soc/spi@201c4000/lcd@0[0]' - status (0)
[  +0.000011] panel-t0d96bp spi2.0: GPIO lookup for consumer led
[  +0.000004] panel-t0d96bp spi2.0: using device tree for GPIO lookup
[  +0.000016] of_get_named_gpiod_flags: parsed 'led-gpios' property of node '/soc/spi@201c4000/lcd@0[0]' - status (0)
[  +0.664504] random: fast init done
[  +0.135404] panel-t0d96bp spi2.0: device init completed
[  +0.005228] panel-t0d96bp spi2.0: frame buffer is allocated
[  +0.005610] panel-t0d96bp spi2.0: info.fix.smem_start=4126560256
              info.fix.smem_len=38400
              info.screen_size=38400
[  +0.013124] Console: switching to colour frame buffer device 20x10
[  +0.006207] panel-t0d96bp spi2.0: frame buffer is registered
[  +0.005665] panel-t0d96bp spi2.0: spi driver probed
[  +0.005097] dw_spi_mmio 201c6000.spi: Unbalanced pm_runtime_enable!
[  +0.007172] mdio_bus fixed-0: GPIO lookup for consumer reset
[  +0.000005] mdio_bus fixed-0: using lookup tables for GPIO lookup
[  +0.000005] mdio_bus fixed-0: No GPIO consumer reset found
[  +0.000021] libphy: Fixed MDIO Bus: probed
[  +0.004425] tun: Universal TUN/TAP device driver, 1.6
[  +0.005282] usbcore: registered new interface driver asix
[  +0.005436] usbcore: registered new interface driver ax88179_178a
[  +0.006120] usbcore: registered new interface driver cdc_ether
[  +0.005858] usbcore: registered new interface driver net1080
[  +0.005681] usbcore: registered new interface driver cdc_subset
[  +0.005949] usbcore: registered new interface driver zaurus
[  +0.005607] usbcore: registered new interface driver cdc_ncm
[  +0.005683] usbcore: registered new interface driver qmi_wwan
[  +0.005830] VFIO - User Level meta-driver version: 0.3
[  +0.066790] OF: graph: no port node found in /soc/usb_phy@20480000
[  +0.007986] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[  +0.006564] ehci-pci: EHCI PCI platform driver
[  +0.004470] ehci-platform: EHCI generic platform driver
[  +0.005332] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[  +0.006192] ohci-pci: OHCI PCI platform driver
[  +0.004469] ohci-platform: OHCI generic platform driver
[  +0.005523] usbcore: registered new interface driver cdc_wdm
[  +0.005695] usbcore: registered new interface driver usb-storage
[  +0.006063] usbcore: registered new interface driver option
[  +0.005600] usbserial: USB Serial support registered for GSM modem (1-port)
[  +0.008064] i2c /dev entries driver
[  +0.004513] device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com
[  +0.009833] sdhci: Secure Digital Host Controller Interface driver
[  +0.006189] sdhci: Copyright(c) Pierre Ossman
[  +0.004438] Synopsys Designware Multimedia Card Interface Driver
[  +0.006261] sdhci-pltfm: SDHCI platform and OF driver helper
[  +0.006230] sdhci-arasan 33000000.mmc: GPIO lookup for consumer wp
[  +0.000006] sdhci-arasan 33000000.mmc: using device tree for GPIO lookup
[  +0.000017] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/soc/mmc@33000000[0]'
[  +0.000013] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/soc/mmc@33000000[0]'
[  +0.000009] sdhci-arasan 33000000.mmc: using lookup tables for GPIO lookup
[  +0.000005] sdhci-arasan 33000000.mmc: No GPIO consumer wp found
[  +0.000164] mmc1: CQHCI version 5.10
[  +0.003616] ledtrig-cpu: registered to indicate activity on CPUs
[  +0.006231] keembay-vpu-ipc 3f00209c.vpu-ipc: Keem Bay VPU IPC probing.
[  +0.006836] hid: raw HID events driver (C) Jiri Kosina
[  +0.005525] usbcore: registered new interface driver usbhid
[  +0.005580] usbhid: USB HID core driver
[  +0.001321] mmc1: SDHCI controller on 33000000.mmc [33000000.mmc] using ADMA
[  +0.002993] optee: probing for conduit method.
[  +0.011038] optee: revision 3.14 (d21befa5)
[  +0.000142] optee: dynamic shared memory is enabled
[  +0.009375] optee: initialized driver
[  +0.005077] IPVS: Registered protocols (TCP, UDP)
[  +0.004812] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[  +0.007444] IPVS: ipvs loaded.
[  +0.003064] IPVS: [rr] scheduler registered.
[  +0.004380] Initializing XFRM netlink socket
[  +0.004385] NET: Registered protocol family 17
[  +0.004525] 9pnet: Installing 9P2000 support
[  +0.004318] Key type dns_resolver registered
[  +0.004514] registered taskstats version 1
[  +0.004109] Loading compiled-in X.509 certificates
[  +0.004945] AppArmor: AppArmor sha1 policy hashing enabled
[  +0.005700] mmc1: Command Queue Engine enabled
[  +0.004486] mmc1: new HS400 Enhanced strobe MMC card at address 0001
[  +0.001716] i2c_designware 20200000.i2c: GPIO lookup for consumer scl
[  +0.004654] i2c_designware 20200000.i2c: using device tree for GPIO lookup
[  +0.000028] of_get_named_gpiod_flags: can't parse 'scl-gpios' property of node '/soc/i2c@20200000[0]'
[  +0.000013] of_get_named_gpiod_flags: can't parse 'scl-gpio' property of node '/soc/i2c@20200000[0]'
[  +0.000009] i2c_designware 20200000.i2c: using lookup tables for GPIO lookup
[  +0.000006] i2c_designware 20200000.i2c: No GPIO consumer scl found
[  +0.000426] at24 0-0051: supply vcc not found, using dummy regulator
[  +0.000030] mmcblk1: mmc1:0001 DF4032 29.1 GiB 
[  +0.006446] at24 0-0051: GPIO lookup for consumer wp
[  +0.004442] at24 0-0051: using device tree for GPIO lookup
[  +0.000016] of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/soc/i2c@20200000/eeprom@51[0]'
[  +0.000013] of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/soc/i2c@20200000/eeprom@51[0]'
[  +0.000006] at24 0-0051: using lookup tables for GPIO lookup
[  +0.000005] at24 0-0051: No GPIO consumer wp found
[  +0.000082] mmcblk1boot0: mmc1:0001 DF4032 partition 1 4.00 MiB
[  +0.000140] at24 0-0051: 4096 byte 24c32 EEPROM, writable, 1 bytes/write
[  +0.005918] mmcblk1boot1: mmc1:0001 DF4032 partition 2 4.00 MiB
[  +0.006837] i2c_designware 20210000.i2c: GPIO lookup for consumer scl
[  +0.005664] i2c_designware 20210000.i2c: using device tree for GPIO lookup
[  +0.000014] of_get_named_gpiod_flags: can't parse 'scl-gpios' property of node '/soc/i2c@20210000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'scl-gpio' property of node '/soc/i2c@20210000[0]'
[  +0.000006] i2c_designware 20210000.i2c: using lookup tables for GPIO lookup
[  +0.000005] i2c_designware 20210000.i2c: No GPIO consumer scl found
[  +0.000044] mmcblk1rpmb: mmc1:0001 DF4032 partition 3 4.00 MiB, chardev (242:0)
[  +0.000266] i2c_designware 20220000.i2c: GPIO lookup for consumer scl
[  +0.007066] i2c_designware 20220000.i2c: using device tree for GPIO lookup
[  +0.000013] of_get_named_gpiod_flags: can't parse 'scl-gpios' property of node '/soc/i2c@20220000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'scl-gpio' property of node '/soc/i2c@20220000[0]'
[  +0.000006] i2c_designware 20220000.i2c: using lookup tables for GPIO lookup
[  +0.000005] i2c_designware 20220000.i2c: No GPIO consumer scl found
[  +0.000309] i2c_designware 20230000.i2c: GPIO lookup for consumer scl
[  +0.000005] i2c_designware 20230000.i2c: using device tree for GPIO lookup
[  +0.000014] of_get_named_gpiod_flags: can't parse 'scl-gpios' property of node '/soc/i2c@20230000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'scl-gpio' property of node '/soc/i2c@20230000[0]'
[  +0.000006] i2c_designware 20230000.i2c: using lookup tables for GPIO lookup
[  +0.000005] i2c_designware 20230000.i2c: No GPIO consumer scl found
[  +0.000537] i2c_designware 20240000.i2c: GPIO lookup for consumer scl
[  +0.000005] i2c_designware 20240000.i2c: using device tree for GPIO lookup
[  +0.000013] of_get_named_gpiod_flags: can't parse 'scl-gpios' property of node '/soc/i2c@20240000[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'scl-gpio' property of node '/soc/i2c@20240000[0]'
[  +0.000006] i2c_designware 20240000.i2c: using lookup tables for GPIO lookup
[  +0.000005] i2c_designware 20240000.i2c: No GPIO consumer scl found
[  +0.000412] keembay-pcie 37000000.pcie: GPIO lookup for consumer reset
[  +0.000005] keembay-pcie 37000000.pcie: using device tree for GPIO lookup
[  +0.000013] of_get_named_gpiod_flags: can't parse 'reset-gpios' property of node '/soc/pcie@37000000[0]'
[  +0.000023] of_get_named_gpiod_flags: parsed 'reset-gpio' property of node '/soc/pcie@37000000[0]' - status (0)
[  +0.007085]  mmcblk1: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11
[  +0.099211] keembay-pcie 37000000.pcie: host bridge /soc/pcie@37000000 ranges:
[  +0.007247] keembay-pcie 37000000.pcie:   No bus range found for /soc/pcie@37000000, using [bus 00-ff]
[  +0.009330] keembay-pcie 37000000.pcie:      MEM 0x0036000000..0x0036dfffff -> 0x0036000000
[  +0.008411] keembay-pcie 37000000.pcie: invalid resource
[  +0.105345] keembay-pcie 37000000.pcie: Link up
[  +0.004619] keembay-pcie 37000000.pcie: PCI host bridge to bus 0000:00
[  +0.006536] pci_bus 0000:00: root bus resource [bus 00-ff]
[  +0.005493] pci_bus 0000:00: root bus resource [mem 0x36000000-0x36dfffff]
[  +0.006906] pci 0000:00:00.0: [8086:6240] type 01 class 0x060400
[  +0.006074] pci 0000:00:00.0: supports D1
[  +0.004017] pci 0000:00:00.0: PME# supported from D0 D1 D3hot D3cold
[  +0.007995] pci 0000:01:00.0: [8086:2526] type 00 class 0x028000
[  +0.006064] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00003fff 64bit]
[  +0.007054] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
[  +0.018589] pci 0000:00:00.0: BAR 14: assigned [mem 0x36000000-0x360fffff]
[  +0.006890] pci 0000:01:00.0: BAR 0: assigned [mem 0x36000000-0x36003fff 64bit]
[  +0.007338] pci 0000:00:00.0: PCI bridge to [bus 01-ff]
[  +0.005233] pci 0000:00:00.0:   bridge window [mem 0x36000000-0x360fffff]
[  +0.007375] keembay-vpu-ipc 3f00209c.vpu-ipc: Keem Bay VPU IPC probing.
[  +0.009680] keembay-vpu-ipc 3f00209c.vpu-ipc: Using IPC device: 203300f0.ipc
[  +0.007106] keembay-vpu-ipc 3f00209c.vpu-ipc: Memory region (X509) paddr 0x0x0000000084100000 size 0x100000
[  +0.009755] keembay-vpu-ipc 3f00209c.vpu-ipc: Memory region (MSS IPC) vpu_addr 0x0000000094800000 paddr 0x0000000094800000 size 0x200000
[  +0.012300] keembay-vpu-ipc 3f00209c.vpu-ipc: assigned reserved memory node vpu_reserved@84800000
[  +0.042506] keembay-vpu-ipc 3f00209c.vpu-ipc: Memory region (firmware): vpu_addr 0x0x0000000084800000 size 0x10000000
[  +0.010625] keembay-vpu-ipc 3f00209c.vpu-ipc: Register base for nce_wdt: 0x0x000000003f00209c
[  +0.008542] keembay-vpu-ipc 3f00209c.vpu-ipc: Register base for nce_tim_cfg: 0x0x000000003f003008
[  +0.008888] keembay-vpu-ipc 3f00209c.vpu-ipc: Register base for mss_wdt: 0x0x000000002082009c
[  +0.008536] keembay-vpu-ipc 3f00209c.vpu-ipc: Register base for mss_tim_cfg: 0x0x0000000020821008
[  +0.008950] keembay-vpu-ipc 3f00209c.vpu-ipc: Keem Bay VPU IPC probed.
[  +0.007637] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/keembay_keys/power-button[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/keembay_keys/power-button[0]'
[  +0.000048] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/keembay_keys/usb-interrupt[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/keembay_keys/usb-interrupt[0]'
[  +0.000029] of_get_named_gpiod_flags: can't parse 'gpios' property of node '/keembay_keys/reset-button[0]'
[  +0.000012] of_get_named_gpiod_flags: can't parse 'gpio' property of node '/keembay_keys/reset-button[0]'
[  +0.000117] input: keembay_keys as /devices/platform/keembay_keys/input/input0
[  +0.007885] ALSA device list:
[  +0.002978]   No soundcards found.
[  +0.003612] dw-apb-uart 20180000.serial: forbid DMA for kernel console
[  +0.006542] dw8250_set_termios_keembay: baud= 115200 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +0.036950] Freeing unused kernel memory: 73792K
[  +0.045346] Checked W+X mappings: passed, no W+X pages found
[  +0.005731] Run /init as init process
[  +0.003677]   with arguments:
[  +0.000004]     /init
[  +0.000003]   with environment:
[  +0.000004]     HOME=/
[  +0.000003]     TERM=linux
[  +0.000003]     verity=0
[  +0.457654] EXT4-fs (mmcblk1p8): mounted filesystem with ordered data mode. Opts: (null)
[  +0.184451] [initrd] waiting for /dev/mmcblk1p11
[  +0.155420] EXT4-fs (mmcblk1p11): recovery complete
[  +0.006967] EXT4-fs (mmcblk1p11): mounted filesystem with ordered data mode. Opts: (null)
[  +0.023077] [initrd] data done
[  +0.018784] [initrd] rbind done
[  +0.008659] EXT4-fs (mmcblk1p10): mounted filesystem with ordered data mode. Opts: (null)
[  +0.034202] [initrd] rbind persistent done
[  +0.015108] [initrd] rbind apparmor done
[  +0.305911] [initrd] rbind vpux firmware done
[  +0.247773] systemd[1]: System time before build time, advancing clock.
[  +0.028896] NET: Registered protocol family 10
[  +0.005173] Segment Routing with IPv6
[  +0.016719] systemd[1]: systemd 244.5+ running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[  +0.022026] systemd[1]: Detected architecture arm64.
[  +0.042088] systemd[1]: Set hostname to <rae>.
[  +0.216966] systemd[1]: /lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock \xe2\x86\x92 /run/docker.sock; please update the unit file accordingly.
[  +0.025052] systemd[1]: /lib/systemd/system/deviceservice.service:9: Ignoring unknown escape sequences: "/bin/echo $VPU_FIRMWARE_FILE > /sys/devices/platform/soc/soc\:vpusmm/fwname"
[  +0.109102] systemd[1]: Condition check resulted in System Slice being skipped.
[  +0.009638] systemd[1]: Unnecessary job for /dev/mmcblk1p10 was removed.
[  +0.006755] systemd[1]: Unnecessary job for /dev/mmcblk1p11 was removed.
[  +0.008092] random: systemd: uninitialized urandom read (16 bytes read)
[  +0.006826] systemd[1]: system-getty.slice: unit configures an IP firewall, but the local system does not support BPF/cgroup firewalling.
[  +0.012354] systemd[1]: (This warning is only shown for the first unit using IP firewalling.)
[  +0.011227] systemd[1]: Created slice system-getty.slice.
[  +0.021630] random: systemd: uninitialized urandom read (16 bytes read)
[  +0.007733] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  +0.020131] random: systemd: uninitialized urandom read (16 bytes read)
[  +0.007617] systemd[1]: Created slice User and Session Slice.
[  +0.020588] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  +0.023925] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  +0.023950] systemd[1]: Reached target Local Encrypted Volumes.
[  +0.020014] systemd[1]: Reached target Paths.
[  +0.015925] systemd[1]: Reached target Remote Encrypted Volumes.
[  +0.019983] systemd[1]: Reached target Remote File Systems.
[  +0.019999] systemd[1]: Reached target Slices.
[  +0.016032] systemd[1]: Reached target Swap.
[  +0.016473] systemd[1]: Listening on Syslog Socket.
[  +0.015725] systemd[1]: Listening on initctl Compatibility Named Pipe.
[  +0.024227] systemd[1]: Listening on Journal Audit Socket.
[  +0.019745] systemd[1]: Listening on Journal Socket (/dev/log).
[  +0.020074] systemd[1]: Listening on Journal Socket.
[  +0.020089] systemd[1]: Listening on Network Service Netlink Socket.
[  +0.023893] systemd[1]: Listening on udev Control Socket.
[  +0.019916] systemd[1]: Listening on udev Kernel Socket.
[  +0.023459] systemd[1]: Mounting Huge Pages File System...
[  +0.024050] systemd[1]: Mounting POSIX Message Queue File System...
[  +0.028014] systemd[1]: Mounting Kernel Debug File System...
[  +0.026237] systemd[1]: Mounting Temporary Directory (/tmp)...
[  +0.021523] systemd[1]: Started RAE charger setup.
[  +0.020538] systemd[1]: Started Event watch.
[  +0.021499] systemd[1]: Starting Create list of static device nodes for the current kernel...
[  +0.025969] systemd[1]: Started LCD logo.
[  +0.016513] systemd[1]: Starting Mender service to grow data partition size...
[  +0.029264] systemd[1]: Starting Journal Service...
[  +0.019223] systemd[1]: Starting Remount Root and Kernel File Systems...
[  +0.028102] systemd[1]: Starting udev Coldplug all Devices...
[  +0.022612] systemd[1]: Started TEE Supplicant.
[  +0.005199] EXT4-fs (mmcblk1p8): re-mounted. Opts: (null)
[  +0.015836] systemd[1]: Started Secure WatchDog Timer.
[  +0.027298] systemd[1]: Condition check resulted in Load Kernel Modules being skipped.
[  +0.008628] systemd[1]: Condition check resulted in FUSE Control File System being skipped.
[  +0.012555] systemd[1]: Mounting Kernel Configuration File System...
[  +0.027211] systemd[1]: Starting Apply Kernel Variables...
[  +0.024381] systemd[1]: Started Journal Service.
[  +0.224354] EXT4-fs (mmcblk1p11): resizing filesystem from 23357423 to 23357423 blocks
[  +0.027707] systemd-journald[369]: Received client request to flush runtime journal.
[  +0.419372] mc: Linux media interface: v0.10
[  +0.018521] videodev: Linux video capture interface: v2.00
[  +0.015154] kmb-imx214-sensor color: IMX214 rotate180 enabled
[  +0.006505] kmb-ov9282-sensor left: read rotation variable: 180
[  +0.000236] kmb-imx214-sensor color: pre detect gpios
[  +0.012818] kmb-ov9282-sensor left: read sensor-type variable: color
[  +0.000109] random: crng init done
[  +0.010823] random: 7 urandom warning(s) missed due to ratelimiting
[  +0.002081] kmb-ov9282-sensor left: read aux-force variable: 0
[  +0.001748] kmb-imx214-sensor color: detected gpio
[  +0.000020] kmb-imx214-sensor color: got inclk
[  +0.000044] kmb-imx214-sensor color: set inclk to 24000000 Hz
[  +0.000007] kmb-imx214-sensor color: GPIO lookup for consumer reset
[  +0.000003] kmb-imx214-sensor color: using device tree for GPIO lookup
[  +0.000025] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/color[0]' - status (0)
[  +0.023423] kmb-ov9282-sensor left: OV9282 color mode selected by default
[  +0.007691] kmb-ov9282-sensor left: GPIO lookup for consumer reset
[  +0.000008] kmb-ov9282-sensor left: using device tree for GPIO lookup
[  +0.000030] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/left[0]' - status (0)
[  +0.002786] kmb-imx214-sensor color: powered on the sensor
[  +0.016924] keembay-vpu-mgr soc:vpusmm: assigned reserved memory node pixel_data_cma@0x9d000000
[  +0.008910] dev 0x0 connected - dev_type 1 - nmb_connected_links 1
[  +0.007720] keembay-vpu-mgr soc:vpusmm: vcm_vpu_link_init: rc = 0
[  +0.002842] kmb-imx214-sensor color: Detected on i2c address 10
[  +0.005895] kmb-ov9282-sensor left: read chip id, ret: 0
[  +0.001474] of_get_named_gpiod_flags: can't parse 'simple-audio-card,hp-det-gpio' property of node '/sound[0]'
[  +0.000015] of_get_named_gpiod_flags: can't parse 'simple-audio-card,mic-det-gpio' property of node '/sound[0]'
[  +0.006772] kmb-imx214-sensor color: got i2c client
[  +0.001854] kmb-ov9282-sensor left: found expected chip id: 9281
[  +0.004187] kmb-imx214-sensor color: about to read chip id... reg: 16
[  +0.007480] kmb-ov9282-sensor left: Probe success!
[  +0.004823] kmb-imx214-sensor color: read chip id, ret: 0
[  +0.009095] kmb-ov9282-sensor right: read rotation variable: 0
[  +0.001015] kmb-imx214-sensor color: found expected chip id: 214
[  +0.006923] kmb-ov9282-sensor right: read sensor-type variable: color
[  +0.004928] kmb-imx214-sensor color: detected imx214 successfully
[  +0.007822] kmb-ov9282-sensor right: read aux-force variable: 0
[  +0.004736] kmb-imx214-sensor color: detected imx214 successfully
[  +0.007458] kmb-ov9282-sensor right: OV9282 color mode selected by default
[  +0.004635] kmb-imx214-sensor color: initialized controls
[  +0.008196] kmb-ov9282-sensor right: GPIO lookup for consumer reset
[  +0.005462] kmb-ov9282-sensor right: using device tree for GPIO lookup
[  +0.000046] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/right[0]' - status (0)
[  +0.000213] kmb-imx214-sensor color: Probe success!
[  +0.021155] kmb-ov9282-sensor right: read chip id, ret: 0
[  +0.010389] kmb-ov9282-sensor right: found expected chip id: 9281
[  +0.010644] kmb-ov9282-sensor right: Probe success!
[  +0.014540] kmb-ov9282-sensor left_back: read rotation variable: 180
[  +0.007254] kmb-ov9282-sensor left_back: read sensor-type variable: color
[  +0.007759] kmb-ov9282-sensor left_back: read aux-force variable: 0
[  +0.007466] kmb-ov9282-sensor left_back: OV9282 color mode selected by default
[  +0.008606] kmb-ov9282-sensor left_back: GPIO lookup for consumer reset
[  +0.000010] kmb-ov9282-sensor left_back: using device tree for GPIO lookup
[  +0.000039] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/left_back[0]' - status (0)
[  +0.020665] kmb-ov9282-sensor left_back: read chip id, ret: 0
[  +0.007322] kmb-ov9282-sensor left_back: found expected chip id: 9281
[  +0.012655] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[  +0.021166] kmb-ov9282-sensor left_back: Probe success!
[  +0.010796] kmb-ov9282-sensor right_back: read rotation variable: 0
[  +0.031722] kmb-ov9282-sensor right_back: read sensor-type variable: color
[  +0.009930] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[  +0.007049] EXT4-fs (mmcblk1p4): recovery complete
[  +0.004874] EXT4-fs (mmcblk1p4): mounted filesystem with ordered data mode. Opts: (null)
[  +0.012659] kmb-ov9282-sensor right_back: read aux-force variable: 0
[  +0.006472] kmb-ov9282-sensor right_back: OV9282 color mode selected by default
[  +0.007546] kmb-ov9282-sensor right_back: GPIO lookup for consumer reset
[  +0.000007] kmb-ov9282-sensor right_back: using device tree for GPIO lookup
[  +0.000063] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/right_back[0]' - status (0)
[  +0.004308] Intel(R) Wireless WiFi driver for Linux
[  +0.005507] iwlwifi 0000:01:00.0: enabling device (0000 -> 0002)
[  +0.011967] kmb-ov9282-sensor right_back: read chip id, ret: 0
[  +0.005702] iwlwifi 0000:01:00.0: WRT: Overriding region id 0
[  +0.000226] kmb-ov9282-sensor right_back: found expected chip id: 9281
[  +0.005763] iwlwifi 0000:01:00.0: WRT: Overriding region id 1
[  +0.006693] kmb-ov9282-sensor right_back: Probe success!
[  +0.006397] iwlwifi 0000:01:00.0: WRT: Overriding region id 2
[  +0.011738] iwlwifi 0000:01:00.0: WRT: Overriding region id 3
[  +0.010342] iwlwifi 0000:01:00.0: WRT: Overriding region id 4
[  +0.006028] iwlwifi 0000:01:00.0: WRT: Overriding region id 6
[  +0.005864] iwlwifi 0000:01:00.0: WRT: Overriding region id 8
[  +0.005759] iwlwifi 0000:01:00.0: WRT: Overriding region id 9
[  +0.005792] iwlwifi 0000:01:00.0: WRT: Overriding region id 10
[  +0.005957] iwlwifi 0000:01:00.0: WRT: Overriding region id 11
[  +0.010971] iwlwifi 0000:01:00.0: WRT: Overriding region id 15
[  +0.005915] iwlwifi 0000:01:00.0: WRT: Overriding region id 16
[  +0.005913] iwlwifi 0000:01:00.0: WRT: Overriding region id 18
[  +0.005898] iwlwifi 0000:01:00.0: WRT: Overriding region id 19
[  +0.006848] iwlwifi 0000:01:00.0: WRT: Overriding region id 20
[  +0.010511] iwlwifi 0000:01:00.0: WRT: Overriding region id 21
[  +0.006627] iwlwifi 0000:01:00.0: WRT: Overriding region id 28
[  +0.008787] iwlwifi 0000:01:00.0: loaded firmware version 46.6b541b68.0 9260-th-b0-jf-b0-46.ucode op_mode iwlmvm
[  +0.039124] iwlwifi 0000:01:00.0: Detected Intel(R) Wireless-AC 9260 160MHz, REV=0x324
[  +0.011346] audit: type=1400 audit(1689777520.664:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lsb_release" pid=453 comm="apparmor_parser"
[  +0.015236] audit: type=1400 audit(1689777520.664:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="klogd" pid=455 comm="apparmor_parser"
[  +0.015689] audit: type=1400 audit(1689777520.676:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=454 comm="apparmor_parser"
[  +0.020476] audit: type=1400 audit(1689777520.676:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=454 comm="apparmor_parser"
[  +0.019213] iwlwifi 0000:01:00.0: base HW address: 8c:1d:96:e2:50:0e
[  +0.005634] audit: type=1400 audit(1689777520.680:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="ping" pid=452 comm="apparmor_parser"
[  +0.021801] audit: type=1400 audit(1689777520.692:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/inbm-node" pid=465 comm="apparmor_parser"
[  +0.024016] audit: type=1400 audit(1689777520.708:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="syslogd" pid=464 comm="apparmor_parser"
[  +0.024009] audit: type=1400 audit(1689777520.708:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/node" pid=466 comm="apparmor_parser"
[  +0.019964] audit: type=1400 audit(1689777520.692:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="syslog-ng" pid=463 comm="apparmor_parser"
[  +0.015606] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[  +0.001236] thermal thermal_zone5: failed to read out thermal zone (-61)
[  +0.007467] iwlwifi 0000:01:00.0 wlp1s0: renamed from wlan0
[  +0.007818] audit: type=1400 audit(1689777520.732:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/dovecot/anvil" pid=469 comm="apparmor_parser"
[  +0.681337] hantro_drm_probe: dev soc:media_kmb_b0@20884000 probe
[  +0.010858] hantro soc:media_kmb_b0@20884000: assigned reserved memory node pixel_data_cma@0x9d000000
[  +0.019256] hantroenc: HW at base <0x20884000> with ID 0x8000
[  +0.006268] hx280enc: module inserted. Major <0>
[  +0.014348] hantroenc: HW at base <0x208a0000> with ID 0x8000
[  +0.012093] hx280enc: module inserted. Major <0>
[  +0.011155] hantrodec: HW at base <0x20888000> with ID 0x8001
[  +0.011250] hantrodec: HW at base <0x20888800> with ID 0x8001
[  +0.013554] hantrocache: HW at base <0x20889a00> with ID 0x3000
[  +0.006717] hantrocache: HW at base <0x20889820> with ID 0x3000
[  +0.035198] hantrocache: HW at base <0x20889c20> with ID 0x3000
[  +0.014069]  soc:media_kmb_b0@20884000:codec_reserved: assigned reserved memory node codec_reserved@480000000
[  +0.024262] hantro soc:media_kmb_b0@20884000: Success: Codec reserved memory found at idx = 1, ret=0
[  +0.023224] hantro_drm_probe: dev hantro probe
[  +0.013432] [drm] Initialized hantro 1.0.0 20210304 for hantro on minor 1
[  +0.010158] hantro device created
[  +0.001269] using random self ethernet address
[  +0.006629] using random host ethernet address
[  +0.031387] using random self ethernet address
[  +0.004524] using random host ethernet address
[  +0.022493] usb0: HOST MAC 96:33:cf:d6:30:d2
[  +0.020428] usb0: MAC d6:21:24:e0:a2:2e
[  +0.011713] usb1: HOST MAC 46:f8:f8:ed:ce:5d
[  +0.018388] usb1: MAC 1e:2e:94:4c:15:89
[  +0.945209] IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready
[  +0.221741] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[  +0.041939] br0: port 1(usb0) entered blocking state
[  +0.011026] br0: port 1(usb0) entered disabled state
[  +0.005976] device usb0 entered promiscuous mode
[  +0.006940] br0: port 1(usb0) entered blocking state
[  +0.005004] br0: port 1(usb0) entered forwarding state
[  +0.016467] br0: port 2(usb1) entered blocking state
[  +0.005058] br0: port 2(usb1) entered disabled state
[  +0.013430] device usb1 entered promiscuous mode
[  +3.385058] dw8250_set_termios_keembay: baud= 115200 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +0.010888] dw-apb-uart 20150000.serial: failed to request DMA
[  +0.007340] dw8250_set_termios_keembay: baud= 9600 rate= 32768, new_rate= 24000000, obtained rate= 24000000
[  +0.016183] dw8250_set_termios_keembay: baud= 9600 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +0.016277] dw8250_set_termios_keembay: baud= 9600 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +0.015025] dw8250_set_termios_keembay: baud= 9600 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +0.012650] dw8250_set_termios_keembay: baud= 115200 rate= 24000000, new_rate= 24000000, obtained rate= 24000000
[  +2.251241] Bridge firewalling registered
[  +0.038239] kauditd_printk_skb: 43 callbacks suppressed
[  +0.000008] audit: type=1325 audit(1698075208.976:45): table=nat family=2 entries=0 op=xt_register pid=701 subj==unconfined comm="modprobe"
[  +0.037569] audit: type=1325 audit(1698075209.012:46): table=filter family=2 entries=0 op=xt_register pid=703 subj==unconfined comm="modprobe"
[  +0.102825] audit: type=1325 audit(1698075209.116:47): table=nat family=2 entries=5 op=xt_replace pid=723 subj==unconfined comm="iptables"
[  +0.012642] audit: type=1325 audit(1698075209.120:48): table=filter family=2 entries=4 op=xt_replace pid=725 subj==unconfined comm="iptables"
[  +0.017145] audit: type=1325 audit(1698075209.128:49): table=filter family=2 entries=6 op=xt_replace pid=727 subj==unconfined comm="iptables"
[  +0.012912] audit: type=1325 audit(1698075209.132:50): table=filter family=2 entries=8 op=xt_replace pid=729 subj==unconfined comm="iptables"
[  +0.015087] audit: type=1325 audit(1698075209.140:51): table=filter family=2 entries=10 op=xt_replace pid=731 subj==unconfined comm="iptables"
[  +0.015998] audit: type=1325 audit(1698075209.148:52): table=filter family=2 entries=11 op=xt_replace pid=733 subj==unconfined comm="iptables"
[  +0.015995] audit: type=1325 audit(1698075209.152:53): table=filter family=2 entries=12 op=xt_replace pid=734 subj==unconfined comm="iptables"
[  +0.012997] audit: type=1325 audit(1698075209.196:54): table=nat family=2 entries=7 op=xt_replace pid=742 subj==unconfined comm="iptables"
[  +0.442702] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[  +0.013099] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[  +0.016288] Intel(R) Wireless WiFi driver for Linux
[  +0.041711] iwlwifi 0000:01:00.0: WRT: Overriding region id 0
[  +0.005900] iwlwifi 0000:01:00.0: WRT: Overriding region id 1
[  +0.011318] iwlwifi 0000:01:00.0: WRT: Overriding region id 2
[  +0.005918] iwlwifi 0000:01:00.0: WRT: Overriding region id 3
[  +0.005935] iwlwifi 0000:01:00.0: WRT: Overriding region id 4
[  +0.012143] iwlwifi 0000:01:00.0: WRT: Overriding region id 6
[  +0.005964] iwlwifi 0000:01:00.0: WRT: Overriding region id 8
[  +0.010067] iwlwifi 0000:01:00.0: WRT: Overriding region id 9
[  +0.005992] iwlwifi 0000:01:00.0: WRT: Overriding region id 10
[  +0.005915] iwlwifi 0000:01:00.0: WRT: Overriding region id 11
[  +0.012040] iwlwifi 0000:01:00.0: WRT: Overriding region id 15
[  +0.005983] iwlwifi 0000:01:00.0: WRT: Overriding region id 16
[  +0.006003] iwlwifi 0000:01:00.0: WRT: Overriding region id 18
[  +0.005964] iwlwifi 0000:01:00.0: WRT: Overriding region id 19
[  +0.005972] iwlwifi 0000:01:00.0: WRT: Overriding region id 20
[  +0.005930] iwlwifi 0000:01:00.0: WRT: Overriding region id 21
[  +0.005940] iwlwifi 0000:01:00.0: WRT: Overriding region id 28
[  +0.013998] iwlwifi 0000:01:00.0: loaded firmware version 46.6b541b68.0 9260-th-b0-jf-b0-46.ucode op_mode iwlmvm
[  +0.032079] iwlwifi 0000:01:00.0: Detected Intel(R) Wireless-AC 9260 160MHz, REV=0x324
[  +0.074044] iwlwifi 0000:01:00.0: base HW address: 8c:1d:96:e2:50:0e
[  +0.080545] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[  +0.001085] thermal thermal_zone5: failed to read out thermal zone (-61)
[  +0.006520] iwlwifi 0000:01:00.0 wlp1s0: renamed from wlan0
[  +0.601609] process 'docker/tmp/qemu-check733773572/check' started with executable stack
[  +6.848015] wlp1s0: authenticate with 30:de:4b:07:f6:5f
[  +0.007541] wlp1s0: send auth to 30:de:4b:07:f6:5f (try 1/3)
[  +0.045118] wlp1s0: authenticated
[  +0.005619] wlp1s0: associate with 30:de:4b:07:f6:5f (try 1/3)
[  +0.009064] wlp1s0: RX AssocResp from 30:de:4b:07:f6:5f (capab=0x1911 status=0 aid=5)
[  +0.012691] wlp1s0: associated
[  +0.003323] IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready
[  +0.006087] iwlwifi 0000:01:00.0: Unhandled alg: 0xc0400707
[  +0.006177] iwlwifi 0000:01:00.0: Unhandled alg: 0xc0400707
[  +0.005697] iwlwifi 0000:01:00.0: Unhandled alg: 0xc0400707
[  +0.005660] iwlwifi 0000:01:00.0: Unhandled alg: 0xc0400707
[  +0.005653] iwlwifi 0000:01:00.0: Unhandled alg: 0xc0400707
[  +0.005650] iwlwifi 0000:01:00.0: Unhandled alg: 0xc0400707
[  +0.013595] wlp1s0: Limiting TX power to 35 (35 - 0) dBm as advertised by 30:de:4b:07:f6:5f
[  +2.728007] keembay_wdt 2033009c.watchdog: Initial timeout 5 sec.
[  +6.418411] sd-volt-rail: disabling
[ +10.898093] kauditd_printk_skb: 31 callbacks suppressed
[  +0.000008] audit: type=1006 audit(1698075345.882:86): pid=893 uid=0 subj==unconfined old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=2 res=1
[  +0.424304] audit: type=1006 audit(1698075346.306:87): pid=890 uid=0 subj==unconfined old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=3 res=1
[  +5.156246] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation
[Oct23 15:36] audit: type=1006 audit(1698075417.282:88): pid=1112 uid=0 subj==unconfined old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=4 res=1
[Oct23 15:37] audit: type=1006 audit(1698075437.694:89): pid=4137 uid=0 subj==unconfined old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=5 res=1
[ +29.894608] gpio gpiochip0: registered chardev handle for line 41
[  +0.000031] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000023] gpio gpiochip0: registered chardev handle for line 42
[  +0.000010] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000020] gpio gpiochip0: registered chardev handle for line 43
[  +0.000011] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.001167] gpio gpiochip0: registered chardev handle for line 45
[  +0.000021] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000020] gpio gpiochip0: registered chardev handle for line 46
[  +0.000011] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000019] gpio gpiochip0: registered chardev handle for line 47
[  +0.000010] gpio gpiochip0: registered chardev handle for 1 lines
[  +3.904059] kmb-camera keembay_camera: assigned reserved memory node pixel_data_cma@0x9d000000
[  +0.017256] dev 0x0 ALREADY connected - dev_type 1
[  +0.883203] keembay-vpu-ipc 3f00209c.vpu-ipc: Can't handle event 2 in state 0
[  +0.021308] keembay-vpu-ipc 3f00209c.vpu-ipc: Can't stop in this state.
[  +0.015786] Failed to stop VPU: -22
[  +0.005182] 
              Start VPU 0x0 - luxonis_vpu.bin
[  +0.008119] keembay-vpu-ipc 3f00209c.vpu-ipc: Stop SMC successful.
[  +0.011094] keembay-vpu-ipc 3f00209c.vpu-ipc: Keem Bay VPU IPC start with luxonis_vpu.bin.
[  +0.029852] keembay-vpu-ipc 3f00209c.vpu-ipc: Boot SMC successful.
[  +0.008889] Successfully started VPU!
[  +0.047018] keembay-vpu-ipc 3f00209c.vpu-ipc: VPU ready message received successfully!
[  +0.008160] Successfully synchronised state with VPU!
[  +0.006663] VPU was ready.
[  +0.203773] dev 0x0 ALREADY connected - dev_type 1
[  +5.291453] gpio gpiochip0: registered chardev handle for line 65
[  +0.000030] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.001590] gpio gpiochip0: registered chardev handle for line 58
[  +0.000019] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000036] gpio gpiochip0: registered chardev handle for line 52
[  +0.000011] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000026] gpio gpiochip0: registered chardev handle for line 55
[  +0.000009] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000026] gpio gpiochip0: registered chardev handle for line 65
[  +0.000026] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000245] gpio gpiochip0: registered chardev handle for line 53
[  +0.000013] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000059] spidev spi1.0: attempting to unlock already unlocked bus by: spi1.0 
[  +0.027743] spidev spi1.0: attempting to unlock already unlocked bus by: spi1.0 
[  +1.118871] gpio gpiochip0: registered chardev handle for line 58
[  +0.000025] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000032] gpio gpiochip0: registered chardev handle for line 52
[  +0.000011] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000025] gpio gpiochip0: registered chardev handle for line 55
[  +0.000010] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000026] gpio gpiochip0: registered chardev handle for line 65
[  +0.000011] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.001805] gpio gpiochip0: registered chardev handle for line 53
[  +0.000025] gpio gpiochip0: registered chardev handle for 1 lines
[  +0.000088] spidev spi1.0: attempting to unlock already unlocked bus by: spi1.0 
[  +0.028977] spidev spi1.0: attempting to unlock already unlocked bus by: spi1.0 
[  +0.100302] kmb-ov9282-sensor right_back: GPIO lookup for consumer reset
[  +0.000030] kmb-ov9282-sensor right_back: using device tree for GPIO lookup
[  +0.000050] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/right_back[0]' - status (0)
[  +0.021857] kmb-ov9282-sensor right_back: Unexpected transfer OK before power-on, aborting address change!
[  +0.010756] kmb-ov9282-sensor right_back: I2C bus 1 UNLOCKING
[  +0.005955] kmb-ov9282-sensor right_back: INFO kmb_ov9282_start_streaming: flipH,V:0,0, sync:0, aux_force:0
[  +0.024129] kmb-ov9282-sensor right_back: V4L2_CID_HFLIP: 0 or V4L2_CID_VFLIP: 0
[  +0.008304] kmb-ov9282-sensor right_back: V4L2_CID_HFLIP: 0 or V4L2_CID_VFLIP: 0
[  +0.009123] kmb-ov9282-sensor right_back: Sent start streaming command
[  +0.199012] kmb-ov9282-sensor left_back: GPIO lookup for consumer reset
[  +0.000031] kmb-ov9282-sensor left_back: using device tree for GPIO lookup
[  +0.000099] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/left_back[0]' - status (0)
[  +0.019333] kmb-ov9282-sensor left_back: Unexpected transfer OK before power-on, aborting address change!
[  +0.012199] kmb-ov9282-sensor left_back: I2C bus 3 UNLOCKING
[  +0.014359] kmb-ov9282-sensor left_back: INFO kmb_ov9282_start_streaming: flipH,V:1,1, sync:0, aux_force:0
[  +0.030991] kmb-ov9282-sensor left_back: V4L2_CID_HFLIP: 1 or V4L2_CID_VFLIP: 1
[  +0.009253] kmb-ov9282-sensor left_back: V4L2_CID_HFLIP: 1 or V4L2_CID_VFLIP: 1
[  +0.010592] kmb-ov9282-sensor left_back: Sent start streaming command
[  +0.250256] kmb-ov9282-sensor right: GPIO lookup for consumer reset
[  +0.000015] kmb-ov9282-sensor right: using device tree for GPIO lookup
[  +0.000054] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/right[0]' - status (0)
[  +0.021361] kmb-ov9282-sensor right: Unexpected transfer OK before power-on, aborting address change!
[  +0.016324] kmb-ov9282-sensor right: I2C bus 0 UNLOCKING
[  +0.006737] kmb-ov9282-sensor right: INFO kmb_ov9282_start_streaming: flipH,V:0,0, sync:0, aux_force:0
[  +0.063015] kmb-ov9282-sensor right: V4L2_CID_HFLIP: 0 or V4L2_CID_VFLIP: 0
[  +0.009564] kmb-ov9282-sensor right: V4L2_CID_HFLIP: 0 or V4L2_CID_VFLIP: 0
[  +0.011147] kmb-ov9282-sensor right: Sent start streaming command
[Oct23 15:38] kmb-ov9282-sensor left: GPIO lookup for consumer reset
[  +0.000015] kmb-ov9282-sensor left: using device tree for GPIO lookup
[  +0.000051] of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/left[0]' - status (0)
[  +0.020327] kmb-ov9282-sensor left: Unexpected transfer OK before power-on, aborting address change!
[  +0.026539] kmb-ov9282-sensor left: I2C bus 4 UNLOCKING
[  +0.025911] kmb-ov9282-sensor left: INFO kmb_ov9282_start_streaming: flipH,V:1,1, sync:0, aux_force:0
[  +0.068930] kmb-ov9282-sensor left: V4L2_CID_HFLIP: 1 or V4L2_CID_VFLIP: 1
[  +0.021232] kmb-ov9282-sensor left: V4L2_CID_HFLIP: 1 or V4L2_CID_VFLIP: 1
[  +0.018345] kmb-ov9282-sensor left: Sent start streaming command
[  +0.031845] alloc_contig_range: [a5980, a59de) PFNs busy
[  +2.204181] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.152865] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.593210] alloc_contig_range: [a6400, a643f) PFNs busy
[  +1.724266] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.019524] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.179431] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.378762] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.563271] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.375121] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.841897] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.198139] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.275323] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.563719] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.040104] alloc_contig_range: [a6400, a643f) PFNs busy
[  +1.366786] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.372876] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.663195] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.590481] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.257118] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.602661] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.288927] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.114657] alloc_contig_range: [a6500, a655e) PFNs busy
[  +0.225437] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.185852] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.259738] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.774615] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.152132] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.034092] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.233970] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.242780] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.722997] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.314411] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.206488] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.212228] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.342328] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.635532] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.848019] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.965911] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.023832] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.553842] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.014307] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.225450] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.062462] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.292258] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.191256] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.401917] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.023210] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.858933] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.353386] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.131967] alloc_contig_range: 7 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.296454] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.491137] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.335801] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.319907] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.715352] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.942790] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.216244] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.286723] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.361398] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.545917] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.223593] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.204261] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.304195] alloc_contig_range: [a6480, a64de) PFNs busy
[  +0.083113] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.188621] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.632592] alloc_contig_range: 1 callbacks suppressed
[  +0.000012] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.832685] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.123854] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.415101] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.465020] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.138339] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.694905] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.372058] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.448380] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.034090] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.034067] alloc_contig_range: 3 callbacks suppressed
[  +0.000012] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.256768] alloc_contig_range: [a6480, a64de) PFNs busy
[  +0.175156] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.665349] alloc_contig_range: [a6580, a65de) PFNs busy
[  +0.038206] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.165796] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.551884] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.568419] alloc_contig_range: [a6580, a65de) PFNs busy
[  +0.066847] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.048052] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.652272] alloc_contig_range: 6 callbacks suppressed
[  +0.000009] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.901841] alloc_contig_range: [a6500, a655e) PFNs busy
[  +0.022657] alloc_contig_range: [a6600, a665e) PFNs busy
[  +0.593466] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.712063] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.080342] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.512746] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.216335] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.409916] alloc_contig_range: [a6480, a64de) PFNs busy
[  +0.084520] alloc_contig_range: [a5880, a58bf) PFNs busy
[Oct23 15:39] alloc_contig_range: 7 callbacks suppressed
[  +0.000299] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.335172] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.112426] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.430441] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.560282] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.009840] alloc_contig_range: [a6500, a655e) PFNs busy
[  +0.803640] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.009211] alloc_contig_range: [a6500, a655e) PFNs busy
[  +0.309421] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005504] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.006178] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.048191] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.168978] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.230207] alloc_contig_range: 12 callbacks suppressed
[  +0.000010] alloc_contig_range: [a6580, a65de) PFNs busy
[  +0.241915] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.115430] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.755964] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.934636] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.044927] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.229094] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.282043] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.264609] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.344080] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +3.476069] alloc_contig_range: 6 callbacks suppressed
[  +0.000010] alloc_contig_range: [a6480, a64de) PFNs busy
[  +0.064524] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.965379] alloc_contig_range: [a6400, a645e) PFNs busy
[  +1.275631] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.005930] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.616614] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.040801] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.797559] alloc_contig_range: [a6500, a655e) PFNs busy
[  +1.204658] alloc_contig_range: [a6280, a62bf) PFNs busy
[  +0.271558] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.015439] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.015016] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.031521] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.676937] alloc_contig_range: [a6400, a643f) PFNs busy
[  +0.067520] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.237574] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.008786] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.007895] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.507803] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.132946] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.276820] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.129660] alloc_contig_range: 12 callbacks suppressed
[  +0.000015] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +0.176524] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.151058] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.026403] alloc_contig_range: [a6300, a635e) PFNs busy
[  +1.027653] alloc_contig_range: [a6300, a635e) PFNs busy
[  +1.725795] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.415100] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.074360] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.378213] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.180461] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.145603] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.750473] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.070162] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.530493] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.283930] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.019125] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.244096] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.038726] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.411132] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.750355] alloc_contig_range: 5 callbacks suppressed
[  +0.000022] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.105510] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.158713] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.676551] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.346814] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.017146] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.322501] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.121611] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.261971] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.331637] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.059512] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.828002] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.837543] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.901153] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.294526] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.045404] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.806281] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.242900] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.025889] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.062244] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.675879] alloc_contig_range: 1 callbacks suppressed
[  +0.000010] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.482899] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.182126] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.042030] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.107983] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.760266] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.854333] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.308855] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.499705] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.076649] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.388445] alloc_contig_range: 2 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.674077] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.333781] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.461473] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.344116] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.048971] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.191731] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.021553] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.332796] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.506485] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.857909] alloc_contig_range: 5 callbacks suppressed
[  +0.000010] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.025014] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.487338] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.346605] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.039438] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.133435] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.515809] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.357247] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.023696] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.844168] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[Oct23 15:40] alloc_contig_range: 9 callbacks suppressed
[  +0.000010] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.519333] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.750038] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.970508] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.262433] alloc_contig_range: [a6300, a635e) PFNs busy
[  +1.345720] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.079548] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.083557] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.460279] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.812423] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.156743] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.109862] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.735968] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.265220] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.473033] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.339415] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.621188] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.526961] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.537844] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.267380] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.338207] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.470721] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.520717] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.122309] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.195472] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.355101] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.841349] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.695995] alloc_contig_range: 2 callbacks suppressed
[  +0.000010] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.115831] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.007560] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.463892] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.190385] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.151605] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.418693] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.101891] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.318030] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.044911] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.273168] alloc_contig_range: 1 callbacks suppressed
[  +0.000008] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.942273] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.293465] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.959523] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.646758] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.014489] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.200585] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.065376] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.131800] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.540973] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.438833] alloc_contig_range: 6 callbacks suppressed
[  +0.000015] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.308315] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.062687] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.214238] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.236878] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.579823] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.050367] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.832016] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.224032] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.387812] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.717854] alloc_contig_range: 3 callbacks suppressed
[  +0.000011] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.786072] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.307619] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.517970] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.010847] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.352895] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.490364] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.010445] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.313444] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.197729] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.347161] alloc_contig_range: 5 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.428019] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.038449] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.103228] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.570448] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.485698] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.256221] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.094501] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.683594] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.336737] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.462685] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.204805] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.018583] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.127633] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.166337] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.624725] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.096129] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.021366] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.854407] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005801] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.006299] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.108693] alloc_contig_range: 7 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.440921] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.060101] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.068315] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.338012] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.471664] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.057843] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.110669] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.825776] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.435788] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.731055] alloc_contig_range: 3 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.536021] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.654627] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.363941] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.129095] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.275377] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[Oct23 15:41] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.268633] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.112932] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.009543] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.634013] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.452251] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.019283] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.025455] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +0.525033] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.816858] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.013006] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.521594] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.062559] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.760744] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.598950] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.067145] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.361936] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.665215] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.206295] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.342718] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.097363] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.045538] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.021208] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.141735] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.366314] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.019489] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.098377] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.299570] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.659710] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.335053] alloc_contig_range: 2 callbacks suppressed
[  +0.000081] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.342898] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.066141] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.216739] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.060005] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.015878] alloc_contig_range: [a6300, a633f) PFNs busy
[  +0.226857] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.320509] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.219509] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.047311] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +3.610071] alloc_contig_range: 7 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.664776] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.524663] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.618339] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.225600] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.804922] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.389982] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.347426] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.335234] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.127657] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.383244] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.725344] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.279241] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.114437] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.044500] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.015898] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.677431] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.234177] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.053406] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.643838] alloc_contig_range: 7 callbacks suppressed
[  +0.000008] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.456032] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.053893] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.302819] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.082170] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.193893] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.748730] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +0.659000] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.795876] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.450508] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.872418] alloc_contig_range: 1 callbacks suppressed
[  +0.000010] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.163472] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.404348] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.495676] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.822583] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.652865] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.658436] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.249629] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.009526] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.328131] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.652861] alloc_contig_range: 1 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.110976] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.550484] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.526136] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.035195] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.057039] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.625403] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.510915] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.509598] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.008829] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.232167] alloc_contig_range: 4 callbacks suppressed
[  +0.000020] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.673568] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.294625] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.009490] alloc_contig_range: [a6380, a63bf) PFNs busy
[  +0.287946] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.935323] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.695069] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.760414] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.805534] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.011296] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.533782] alloc_contig_range: 2 callbacks suppressed
[  +0.000010] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.282406] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.640740] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.400583] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.009720] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.022981] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.877062] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.533358] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.593533] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.543991] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.338019] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.095934] alloc_contig_range: [a5880, a58bf) PFNs busy
[Oct23 15:42] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.253586] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.053034] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.562015] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.236331] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.051030] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.295401] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.243117] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.863822] alloc_contig_range: 3 callbacks suppressed
[  +0.000022] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.582642] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.411346] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.249643] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.349291] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.275733] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.538747] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.040320] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.085473] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.121379] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.514333] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.066891] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.234221] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.787853] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.011541] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.112271] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.260449] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.340465] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.607556] alloc_contig_range: 3 callbacks suppressed
[  +0.000014] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.125340] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.950024] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.102912] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.183111] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.139495] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.789441] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.471559] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.300565] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.322253] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.874690] alloc_contig_range: 2 callbacks suppressed
[  +0.000009] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.576188] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.119295] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.253492] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.173263] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.502959] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.303761] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.048074] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.963861] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.680402] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.177181] alloc_contig_range: [a6380, a63de) PFNs busy
[  +1.705252] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.280620] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.068759] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.302246] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.201962] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.047744] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.529186] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.570387] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.615942] alloc_contig_range: 2 callbacks suppressed
[  +0.000008] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.035481] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.715159] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.400684] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.088491] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.766641] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.060083] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.156943] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.337232] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.326520] alloc_contig_range: [a6280, a62de) PFNs busy
[  +2.379378] alloc_contig_range: 4 callbacks suppressed
[  +0.000009] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.439218] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.294987] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.072698] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.344997] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.194617] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.020304] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.234546] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.332600] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.634375] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.452912] alloc_contig_range: 4 callbacks suppressed
[  +0.000012] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.886024] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.102245] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.530038] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.760248] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.235386] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.052429] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.243539] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.249652] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.131972] alloc_contig_range: [a6280, a62de) PFNs busy
[  +2.505072] alloc_contig_range: 2 callbacks suppressed
[  +0.000010] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.838704] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005486] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.008717] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[Oct23 15:43] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.007574] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.007797] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +2.140688] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.964935] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.260262] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +3.640926] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.266397] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.220734] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.397183] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.742705] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.711804] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.007407] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.072452] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.011286] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.899186] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.287816] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.818746] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.929543] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.118550] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.251086] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.280312] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.293263] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.012221] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.130923] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.428819] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +0.023063] alloc_contig_range: [a56c0, a56ff) PFNs busy
[  +0.419617] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.422412] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.129147] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.092320] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.844082] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.011994] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.010413] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.065155] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.609318] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.207043] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.126510] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.539107] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.277806] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.123857] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.282725] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.726029] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.008700] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.223816] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.069303] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.493864] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.044303] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.914927] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.178483] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.559132] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.274489] alloc_contig_range: 3 callbacks suppressed
[  +0.000013] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.256306] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.666640] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.487240] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.216944] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.396513] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.779365] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.009567] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.189048] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.046593] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.066747] alloc_contig_range: 1 callbacks suppressed
[  +0.000009] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.559237] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.260957] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.281539] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.039323] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.068073] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.026471] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.192858] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.196658] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.941803] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.689025] alloc_contig_range: 2 callbacks suppressed
[  +0.000009] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.602919] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +3.320770] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.422046] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +4.960960] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.883363] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.555972] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.054409] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.007042] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.007376] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.233614] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.372149] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.029759] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.221646] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.042538] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[Oct23 15:44] alloc_contig_range: 9 callbacks suppressed
[  +0.000010] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.169999] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.010119] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.406428] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.142917] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.406233] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.876904] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.314065] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.541784] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.034120] alloc_contig_range: [a6300, a635e) PFNs busy
[  +2.861036] alloc_contig_range: 9 callbacks suppressed
[  +0.000024] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.241701] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.014501] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.201927] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.692491] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.175026] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.678243] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.035664] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.034146] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.889728] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.010309] alloc_contig_range: 6 callbacks suppressed
[  +0.000012] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.496310] alloc_contig_range: [a5680, a56de) PFNs busy
[ +23.480880] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.248261] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.017641] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.207893] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.146795] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.068429] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +3.378929] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.331321] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.286140] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.379269] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.124974] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.034650] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.705030] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.554163] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.325697] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +3.967405] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.242018] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +0.556360] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.586386] alloc_contig_range: [a6300, a635e) PFNs busy
[  +0.503763] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.862533] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.875742] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.239600] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.277248] alloc_contig_range: [a6280, a62de) PFNs busy
[Oct23 15:45] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.284316] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.344030] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.167896] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.087002] alloc_contig_range: [a6400, a645e) PFNs busy
[  +0.064225] alloc_contig_range: [a5880, a58bf) PFNs busy
[ +41.860825] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005611] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.011689] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[ +12.303444] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.007907] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.010365] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.878293] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.795148] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.765163] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[Oct23 15:46] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +5.349416] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[ +17.945447] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.014689] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.012340] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[ +10.131608] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.081789] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.480522] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +8.807256] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.007251] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.012094] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +5.639693] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.381570] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.201962] alloc_contig_range: [a5880, a58bf) PFNs busy
[Oct23 15:47] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.835334] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005730] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.009116] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +3.641478] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.461107] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.892821] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[ +18.122324] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.288246] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.377153] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.009892] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.715979] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.872130] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.216207] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.367410] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.322536] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.549015] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.211329] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.402962] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.277383] alloc_contig_range: [a5680, a56de) PFNs busy
[  +9.623046] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.629975] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.143661] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.559234] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.262904] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.837598] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.194408] alloc_contig_range: [a5a80, a5ade) PFNs busy
[Oct23 15:48] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.217982] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.351986] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.527982] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.249798] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.618470] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.524066] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.097925] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.022152] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.914053] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.487952] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.505655] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.186513] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +3.147223] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.070688] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.862544] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.048184] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.509215] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.517629] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.549532] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.125921] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.222181] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.314827] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.351227] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.272093] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.298706] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.310136] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.766686] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.007432] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.008279] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.044189] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.509268] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.373879] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.854720] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.052055] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.914598] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.319788] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.229595] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.040455] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +1.894341] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005496] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.011193] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.588531] alloc_contig_range: [a6300, a635e) PFNs busy
[ +12.002540] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[Oct23 15:49] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.481834] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.835949] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.493450] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.068768] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.316927] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.976264] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.415724] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.458041] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.560764] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.343015] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.137732] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.676661] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.036682] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.116044] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.585314] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.496363] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.103292] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.536816] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.408871] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.158588] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.729693] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.353990] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.305074] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.224590] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.334889] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.994270] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.201282] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.242758] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.570147] alloc_contig_range: [a5880, a58bf) PFNs busy
[ +18.757155] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.159589] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.047745] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.563431] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005562] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.010448] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.217402] alloc_contig_range: [a5680, a56bf) PFNs busy
[  +0.287899] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.564595] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.060883] alloc_contig_range: [a6480, a64de) PFNs busy
[  +2.469528] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.843038] alloc_contig_range: [a6380, a63de) PFNs busy
[  +0.602914] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.386249] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.490513] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.631371] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.977752] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.593763] alloc_contig_range: [a6280, a62de) PFNs busy
[  +1.491435] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.150899] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.793162] alloc_contig_range: [a5880, a58bf) PFNs busy
[Oct23 15:50] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.591065] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.013845] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.913403] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.417310] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.239865] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.321537] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.050409] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.677542] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.947693] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.500412] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.311103] alloc_contig_range: [a5880, a58bf) PFNs busy
[ +19.860368] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +6.985285] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.796038] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.344392] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.650185] alloc_contig_range: [a5680, a56de) PFNs busy
[  +4.119321] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.647241] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +5.774281] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.724556] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.588824] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +4.308194] alloc_contig_range: [a5680, a56de) PFNs busy
[Oct23 15:51] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.414817] alloc_contig_range: [a5680, a56de) PFNs busy
[  +3.182524] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.497511] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.282686] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.623334] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.998410] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.797443] alloc_contig_range: [a5680, a56de) PFNs busy
[  +3.013495] alloc_contig_range: [a5680, a56de) PFNs busy
[  +3.530657] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.688163] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.011077] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.810924] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.282739] alloc_contig_range: [a5680, a56de) PFNs busy
[ +20.592179] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.855136] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.405315] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.334617] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +3.420653] alloc_contig_range: [a5680, a56de) PFNs busy
[  +7.383315] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.207349] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +2.963431] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.959866] alloc_contig_range: [a5a80, a5ade) PFNs busy
[Oct23 15:52] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.020286] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.009144] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.007521] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.011024] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[ +15.661052] audit: type=1006 audit(1698076379.847:90): pid=104285 uid=0 subj==unconfined old-auid=4294967295 auid=0 tty=(none) old-ses=4294967295 ses=6 res=1
[Oct23 15:53] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.026128] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +0.000004] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.000011] keembay-ipc 203300f0.ipc: Channel was already closed
[  +4.514709] alloc_contig_range: [a5880, a58bf) PFNs busy
[Oct23 15:54] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +3.669943] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.099600] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.164080] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[ +19.799581] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.245882] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.189809] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.254940] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.007813] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.008058] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +3.533460] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.626528] alloc_contig_range: [a5880, a58bf) PFNs busy
[ +11.062160] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.368302] alloc_contig_range: [a5680, a56de) PFNs busy
[  +3.286987] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.857263] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.088504] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +6.085727] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.280377] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.460765] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.034614] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005890] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.015344] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +1.473600] alloc_contig_range: [a5680, a56de) PFNs busy
[Oct23 15:55] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +5.708817] alloc_contig_range: [a5680, a56de) PFNs busy
[  +0.053347] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +2.444033] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.597516] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.512892] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.052764] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.682400] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.031399] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +4.339444] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.010826] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.012099] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[Oct23 15:56] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.008316] alloc_contig_range: [a6300, a635e) PFNs busy
[  +1.426666] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.091854] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.623933] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.015459] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +0.366919] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +1.667676] alloc_contig_range: [a5a80, a5ade) PFNs busy
[  +0.740865] alloc_contig_range: [a6280, a62de) PFNs busy
[  +0.909983] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.541861] alloc_contig_range: [a5680, a56de) PFNs busy
[  +1.368737] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.043910] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.714321] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005853] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.007797] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[Oct23 15:57] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.006166] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.006740] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[Oct23 15:58] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.806830] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.326785] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +2.709268] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +6.481106] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[ +12.142613] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +1.498821] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.141916] alloc_contig_range: [a5680, a56de) PFNs busy
[  +6.504738] alloc_contig_range: [a5680, a56de) PFNs busy
[  +2.911517] alloc_contig_range: [a5880, a58bf) PFNs busy
[  +0.769629] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.448418] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.008271] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.013289] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +4.139229] xlink_ipc_get_device_mode:Error IPC write -2
[  +0.005499] keembay-ipc 203300f0.ipc: Channel was already closed
[  +0.008840] vpu_cooling_drv soc:vpu-cooling-device: xlink_get_device_mode() failed with error 8
[  +2.056310] alloc_contig_range: [a5680, a56de) PFNs busy
[  +3.265902] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +1.988197] alloc_contig_range: [a5680, a56de) PFNs busy
[Oct23 15:59] alloc_contig_range: [a5d00, a5d5e) PFNs busy
[  +3.265475] alloc_contig_range: [a5a80, a5ade) PFNs busy
danilo-pejovic commented 10 months ago

Hey, sorry for late response here. This seems to be bit deeper FW issue. Is there any way to reproduce this issue?

sskorol commented 10 months ago

I couldn't find any specific steps yet. Sometimes, it happens directly after the boot when I start ROS nodes in docker. But it also happened after periodic ROS nodes restarting. Boot errors are persistent, though. I see them on each boot.

danilo-pejovic commented 10 months ago

OK, I will try to reproduce them tomorrow - thanks for reporting.