Uploaded image for project: 'RHEL Testing'
  1. RHEL Testing
  2. RHELTEST-237

LTP lite: read_all.c:311: TBROK: Worker timeout is too short; restarts take >1002506us

XMLWordPrintable

    • 2
    • False
    • Hide

      None

      Show
      None
    • False
    • CK-November-2024

      This issue was originally created at 2024-10-16T12:58:57.432Z as a Gitlab issue:
      https://gitlab.com/redhat/centos-stream/tests/kernel/kernel-tests/-/issues/2041

      1. Snippet of test failure

      >>> 1515:read_all_sys__with_dmesg_entry BROK <<<
      ###############################################################################

      1. Test Num : 1515 #
      2. Test Case : read_all_sys__with_dmesg_entry #
      3. Test Result : BROK #
        ###############################################################################

      1 <<<test_start>>>
      2 tag=read_all_sys__with_dmesg_entry stime=1728657921
      3 cmdline="dmesg -c 1>/dev/null 2>&1; read_all -d /sys -q -r 3; EXIT_CODE=$?; dmesg > /mnt/testarea/ltp/output/DMESG_DIR_RHELKT1LITE.FILTERED-dmesg-output-60691-01:40:34PM/read_all_sys.dmesg.log; exit $EXIT_CODE"
      4 contacts=""
      5 analysis=exit
      6 <<<test_output>>>
      7 tst_test.c:1733: TINFO: LTP version: 20240524
      8 tst_test.c:1617: TINFO: Timeout per run is 0h 02m 10s
      9 read_all.c:568: TINFO: Worker timeout set to 10% of max_runtime: 1000ms
      10 read_all.c:447: TINFO: Worker 297959 (0): Stuck for 1683288us, restarting it
      11 read_all.c:447: TINFO: Worker 297965 (6): Stuck for 1682696us, restarting it
      12 read_all.c:447: TINFO: Worker 297963 (4): Stuck for 1683925us, restarting it
      13 read_all.c:447: TINFO: Worker 297964 (5): Stuck for 1683820us, restarting it
      14 read_all.c:447: TINFO: Worker 297961 (2): Stuck for 1684269us, restarting it
      15 read_all.c:447: TINFO: Worker 297960 (1): Stuck for 1684515us, restarting it
      16 read_all.c:447: TINFO: Worker 297962 (3): Stuck for 1684812us, restarting it
      17 read_all.c:447: TINFO: Worker 297966 (7): Stuck for 1692398us, restarting it
      18 read_all.c:447: TINFO: Worker 297967 (8): Stuck for 1692282us, restarting it
      19 read_all.c:447: TINFO: Worker 297968 (9): Stuck for 1693150us, restarting it
      20 read_all.c:447: TINFO: Worker 297969 (10): Stuck for 1693109us, restarting it
      21 read_all.c:383: TINFO: Worker 297965 (6): Last popped '/sys/kernel/tracing/per_cpu/cpu9/trace'
      22 read_all.c:383: TINFO: Worker 297959 (0): Last popped '/sys/kernel/tracing/per_cpu/cpu9/stats'
      23 read_all.c:383: TINFO: Worker 297960 (1): Last popped '/sys/kernel/tracing/per_cpu/cpu9/trace_pipe_raw'
      24 read_all.c:383: TINFO: Worker 297961 (2): Last popped '/sys/kernel/tracing/per_cpu/cpu9/trace_pipe_raw'
      25 read_all.c:383: TINFO: Worker 297962 (3): Last popped '/sys/kernel/tracing/per_cpu/cpu9/snapshot'
      26 read_all.c:383: TINFO: Worker 297963 (4): Last popped '/sys/kernel/tracing/per_cpu/cpu9/trace'
      27 read_all.c:383: TINFO: Worker 297964 (5): Last popped '/sys/kernel/tracing/per_cpu/cpu9/trace'
      28 read_all.c:383: TINFO: Worker 297966 (7): Last popped '/sys/kernel/tracing/per_cpu/cpu9/buffer_size_kb'
      29 read_all.c:383: TINFO: Worker 297967 (8): Last popped '/sys/kernel/tracing/per_cpu/cpu9/buffer_size_kb'
      30 read_all.c:383: TINFO: Worker 297968 (9): Last popped '/sys/kernel/tracing/per_cpu/cpu9/stats'
      31 read_all.c:383: TINFO: Worker 297969 (10): Last popped '/sys/kernel/tracing/per_cpu/cpu9/stats'
      32 read_all.c:447: TINFO: Worker 297973 (0): Stuck for 72054832us, restarting it
      33 read_all.c:447: TINFO: Worker 297974 (1): Stuck for 72567825us, restarting it
      34 read_all.c:365: TINFO: Worker 297973 (0): Timeout waiting after kill
      35 read_all.c:383: TINFO: Worker 297973 (0): Last popped '/sys/devices/pnp0/00:05/tpm/tpm0/ppi/tcg_operations'
      36 read_all.c:365: TINFO: Worker 297974 (1): Timeout waiting after kill
      37 read_all.c:383: TINFO: Worker 297974 (1): Last popped '/sys/devices/pnp0/00:05/tpm/tpm0/ppi/tcg_operations'
      38 read_all.c:311: TBROK: Worker timeout is too short; restarts take >1002506us
      39 read_all.c:447: TINFO: Worker 297975 (2): Stuck for 76189512us, restarting it
      40 read_all.c:447: TINFO: Worker 297996 (0): Stuck for 1570769us, restarting it
      41 read_all.c:424: TINFO: Silencing timeout warnings; consider increasing LTP_RUNTIME_MUL or removing -q
      42 read_all.c:604: TINFO: Zombie workers detected; consider increasing LTP_RUNTIME_MUL
      43 read_all.c:687: TPASS: Finished reading files
      44
      45 Summary:
      46 passed 1
      47 failed 0
      48 broken 1
      49 skipped 0
      50 warnings 0
      51 <<<execution_status>>>
      52 initiation_status="ok"
      53 duration=86 termination_type=exited termination_id=2 corefile=no
      54 cutime=3162 cstime=12325
      55 <<<test_end>>>

      1. Test logs on DataWarehouse

      https://datawarehouse.cki-project.org/kcidb/tests/14747033

      1. DataWarehouse issue

      https://datawarehouse.cki-project.org/issue/3170

      **In case opening the links above result in a 404 page on DataWarehouse, please
      make sure you are [correctly logged into DataWarehouse via Red Hat SSO](
      https://cki-project.org/l/datawarehouse-404).**

              jstancek@redhat.com Jan Stancek
              bgoncalv@redhat.com Bruno Goncalves
              Li Wang, Ping Fang
              Votes:
              0 Vote for this issue
              Watchers:
              1 Start watching this issue

                Created:
                Updated:
                Resolved: