[BUG] chip-tool interactive log truncated

[rquidute]

Reproduction steps

Test Harness sends to the SKD container the following command and it is getting a truncated log:
Command: ./chip-tool interactive server --trace_file "/logs/trace_log_2024-07-08_17.13.18_0x5a750fef1273af84_CHIP_WEBSOCKET_SERVER.log" --trace_decode 1

Important: This behavior has been observed since this PR was merged: #32119
SDK SHA:686e73bbf4f22b4dcf62a751ac08eff2d1480028 from Jun 25th.

Screenshot

Additional information

Truncated Log, in the following log, only part of the timestamp is returned in the last line of this chunk e.g.: [1720458799

INFO | 2024-07-08 17:13:18.960 | test_collections.matter.sdk_tests.support.sdk_container:send_command:198 | Sending command to SDK container: ./chip-tool interactive server --trace_file "/logs/trace_log_2024-07-08_17.13.18_0x5a750fef1273af84_CHIP_WEBSOCKET_SERVER.log" --trace_decode 1
CHIPTOOL | 2024-07-08 17:13:24.237 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.030] [8:8] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs
CHIPTOOL | 2024-07-08 17:13:24.237 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.030] [8:8] [DL] writing settings to file (/tmp/chip_tool_kvs-wSEJNQ)
CHIPTOOL | 2024-07-08 17:13:24.237 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.030] [8:8] [DL] renamed tmp file to file (/tmp/chip_tool_kvs)
CHIPTOOL | 2024-07-08 17:13:24.238 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.030] [8:8] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
CHIPTOOL | 2024-07-08 17:13:24.238 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.030] [8:8] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs
CHIPTOOL | 2024-07-08 17:13:24.238 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
CHIPTOOL | 2024-07-08 17:13:24.238 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] writing settings to file (/tmp/chip_factory.ini-OaEXZH)
CHIPTOOL | 2024-07-08 17:13:24.239 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] renamed tmp file to file (/tmp/chip_factory.ini)
CHIPTOOL | 2024-07-08 17:13:24.239 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
CHIPTOOL | 2024-07-08 17:13:24.239 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] writing settings to file (/tmp/chip_config.ini-PvcrRQ)
CHIPTOOL | 2024-07-08 17:13:24.240 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] renamed tmp file to file (/tmp/chip_config.ini)
CHIPTOOL | 2024-07-08 17:13:24.240 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
CHIPTOOL | 2024-07-08 17:13:24.240 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] writing settings to file (/tmp/chip_counters.ini-XZTsTb)
CHIPTOOL | 2024-07-08 17:13:24.240 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] renamed tmp file to file (/tmp/chip_counters.ini)
CHIPTOOL | 2024-07-08 17:13:24.240 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] writing settings to file (/tmp/chip_factory.ini-9kWzWj)
CHIPTOOL | 2024-07-08 17:13:24.241 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] renamed tmp file to file (/tmp/chip_factory.ini)
CHIPTOOL | 2024-07-08 17:13:24.241 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] NVS set: chip-factory/unique-id = "2FEACE7AAC69DAB8"
CHIPTOOL | 2024-07-08 17:13:24.241 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.032] [8:8] [DL] writing settings to file (/tmp/chip_factory.ini-pDCLkT)
CHIPTOOL | 2024-07-08 17:13:24.241 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] renamed tmp file to file (/tmp/chip_factory.ini)
CHIPTOOL | 2024-07-08 17:13:24.242 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] NVS set: chip-factory/vendor-id = 65521 (0xFFF1)
CHIPTOOL | 2024-07-08 17:13:24.242 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] writing settings to file (/tmp/chip_factory.ini-jFiwAj)
CHIPTOOL | 2024-07-08 17:13:24.242 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] renamed tmp file to file (/tmp/chip_factory.ini)
CHIPTOOL | 2024-07-08 17:13:24.242 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] NVS set: chip-factory/product-id = 32769 (0x8001)
CHIPTOOL | 2024-07-08 17:13:24.243 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] writing settings to file (/tmp/chip_counters.ini-M9bWHb)
CHIPTOOL | 2024-07-08 17:13:24.243 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] renamed tmp file to file (/tmp/chip_counters.ini)
CHIPTOOL | 2024-07-08 17:13:24.243 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] NVS set: chip-counters/reboot-count = 1 (0x1)
CHIPTOOL | 2024-07-08 17:13:24.244 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] writing settings to file (/tmp/chip_counters.ini-t5Gda7)
CHIPTOOL | 2024-07-08 17:13:24.244 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] renamed tmp file to file (/tmp/chip_counters.ini)
CHIPTOOL | 2024-07-08 17:13:24.245 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0)
CHIPTOOL | 2024-07-08 17:13:24.245 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] writing settings to file (/tmp/chip_counters.ini-Hx3rDd)
CHIPTOOL | 2024-07-08 17:13:24.245 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] renamed tmp file to file (/tmp/chip_counters.ini)
CHIPTOOL | 2024-07-08 17:13:24.245 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait _for_server_start:102 | [1720458799.033] [8:8] [DL] NVS set: chip-counters/boot-reason = 0 (0x0)
CHIPTOOL | 2024-07-08 17:13:24.246 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] writing settings to file (/tmp/chip_config.ini-fGw6KF)
CHIPTOOL | 2024-07-08 17:13:24.246 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] renamed tmp file to file (/tmp/chip_config.ini)
CHIPTOOL | 2024-07-08 17:13:24.246 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] NVS set: chip-config/regulatory-location = 0 (0x0)
CHIPTOOL | 2024-07-08 17:13:24.246 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.033] [8:8] [DL] writing settings to file (/tmp/chip_config.ini-RBoh1Q)
CHIPTOOL | 2024-07-08 17:13:24.246 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [DL] renamed tmp file to file (/tmp/chip_config.ini)
CHIPTOOL | 2024-07-08 17:13:24.247 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [DL] NVS set: chip-config/location-capability = 2 (0x2)
CHIPTOOL | 2024-07-08 17:13:24.247 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [DL] Got Ethernet interface: enp0s1
CHIPTOOL | 2024-07-08 17:13:24.248 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [DL] Found the primary Ethernet interface:enp0s1
CHIPTOOL | 2024-07-08 17:13:24.249 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [DL] Failed to get WiFi interface
CHIPTOOL | 2024-07-08 17:13:24.249 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [DL] Failed to reset WiFi statistic counts
CHIPTOOL | 2024-07-08 17:13:24.249 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [IN] UDP::Init bind&listen port=0
CHIPTOOL | 2024-07-08 17:13:24.249 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [IN] UDP::Init bound to port=45145
CHIPTOOL | 2024-07-08 17:13:24.250 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799.034] [8:8] [IN] BLEBase::Init - setting/overriding transport
CHIPTOOL | 2024-07-08 17:13:24.250 | test_collections.matter.sdk_tests.support.chip.chip_server:__wait_for_server_start:102 | [1720458799

Bug prevalence

Always

GitHub hash of the SDK that was being used

6e31453

Platform

python

Platform Version(s)

N/A

Anything else?

No response