[rtems-test] riscv32/rv32imafd: RTEMS_POSIX_API: Passed:567 Failed:7 Timeout:0 Test-Too-long:6 Invalid:0 Wrong:0

Sat Nov 7 01:13:35 UTC 2020

Testing time     : 0:25:31.699179
Average test time: 0:00:02.600507

Host
====
Linux-3.10.0-1127.19.1.el7.x86_64-x86_64-with-centos-7.8.2003-Core (Linux devel.oarcorp.com 3.10.0-1127.19.1.el7.x86_64 #1 SMP Tue Aug 25 17:23:54 UTC 2020 x86_64 x86_64)

Configuration
=============
Version: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
Build  : RTEMS_POSIX_API
Tools  : 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)

Summary
=======

Passed:        567
Failed:          7
User Input:      6
Expected Fail:   0
Indeterminate:   0
Benchmark:       3
Timeout:         0
Test too long:   6
Invalid:         0
Wrong Version:   0
Wrong Build:     0
Wrong Tools:     0
------------------
Total:         589

Failures:
User Input:
 capture.exe
 top.exe
 monitor.exe
 termios.exe
 dl10.exe
 fileio.exe
Benchmark:
 dhrystone.exe
 whetstone.exe
 linpack.exe
Test too long:
 sp30.exe
 sp01.exe
 sp04.exe
 psxtimer01.exe
 sp12.exe
 sp20.exe
Log
===

RTEMS Testing - Tester, 6.0.not_released
 Command Line: /home/joel/rtems-cron-6/tools/6/bin/rtems-test --rtems-tools=/home/joel/rtems-cron-6/tools/6 --rtems-bsp=rv32imafd_spike --log=run.log --mail --mail-from=joel at rtems.org --mail-to=build at rtems.org .
 Host: Linux devel.oarcorp.com 3.10.0-1127.19.1.el7.x86_64 #1 SMP Tue Aug 25 17:23:54 UTC 2020 x86_64
 Python: 2.7.5 (default, Apr  2 2020, 13:16:51) [GCC 4.8.5 20150623 (Red Hat 4.8.5-39)]
Host: Linux-3.10.0-1127.19.1.el7.x86_64-x86_64-with-centos-7.8.2003-Core (Linux devel.oarcorp.com 3.10.0-1127.19.1.el7.x86_64 #1 SMP Tue Aug 25 17:23:54 UTC 2020 x86_64 x86_64)
[  1/589] p:0   f:0   u:0   e:0   I:0   B:0   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: dhrystone.exe
 <<skipping passes>>
[255/589] p:235 f:5   u:4   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: psxtimer01.exe
Result: test-too-long Time: 0:05:00.520567 psxtimer01.exe
=>  exe: spike --isa=RV32IMAFD ./riscv-rtems6/c/rv32imafd/testsuites/psxtests/psxtimer01.exe
] 
] 
=>  test start: PSXTIMER 1
] *** BEGIN OF TEST PSXTIMER 1 ***
] *** TEST VERSION: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)
] task A: timer_settime - value=0:0 interval=0:0
] Executing task B with count = 10 Fri Jan  1 00:00:02 1988
] Executing task A with count = 20 Fri Jan  1 00:00:02 1988
] Executing task A with count = 19 Fri Jan  1 00:00:03 1988
] Executing task B with count =  9 Fri Jan  1 00:00:04 1988
] Executing task A with count = 18 Fri Jan  1 00:00:04 1988
] Executing task A with count = 17 Fri Jan  1 00:00:05 1988
] Executing task B with count =  8 Fri Jan  1 00:00:06 1988
] Executing task C with count =  6 Fri Jan  1 00:00:06 1988
] Executing task A with count = 16 Fri Jan  1 00:00:06 1988
] Executing task A with count = 15 Fri Jan  1 00:00:07 1988
] Executing task B with count =  7 Fri Jan  1 00:00:08 1988
] Executing task A with count = 14 Fri Jan  1 00:00:08 1988
] Executing task C with count =  5 Fri Jan  1 00:00:09 1988
] Executing task A with count = 13 Fri Jan  1 00:00:09 1988
] Executing task B with count =  6 Fri Jan  1 00:00:10 1988
] Executing task A with count = 12 Fri Jan  1 00:00:10 1988
] Executing task A with count = 11 Fri Jan  1 00:00:11 1988
] Executing task B with count =  5 Fri Jan  1 00:00:12 1988
] Executing task C with count =  4 Fri Jan  1 00:00:12 1988
] Executing task A with count = 10 Fri Jan  1 00:00:12 1988
] Executing task A with count =  9 Fri Jan  1 00:00:13 1988
] Executing task B with count =  4 Fri Jan  1 00:00:14 1988
] Executing task A with count =  8 Fri Jan  1 00:00:14 1988
] Executing task C with count =  3 Fri Jan  1 00:00:15 1988
] Executing task A with count =  7 Fri Jan  1 00:00:15 1988
] Executing task B with count =  3 Fri Jan  1 00:00:16 1988
] Executing task A with count =  6 Fri Jan  1 00:00:16 1988
] Executing task A with count =  5 Fri Jan  1 00:00:17 1988
] Executing task B with count =  2 Fri Jan  1 00:00:18 1988
] Executing task C with count =  2 Fri Jan  1 00:00:18 1988
] Executing task A with count =  4 Fri Jan  1 00:00:18 1988
] Executing task A with count =  3 Fri Jan  1 00:00:19 1988
] Executing task B with count =  1 Fri Jan  1 00:00:20 1988
] Executing task A with count =  2 Fri Jan  1 00:00:20 1988
] Executing task C with count =  1 Fri Jan  1 00:00:21 1988
] Executing task A with count =  1 Fri Jan  1 00:00:21 1988
] task C: timer_getoverrun - overruns=0
] task C: timer_gettime - 1:990000000 remaining from 3:0
] starting C again with 0.5 second periodicity
] Executing task C with count =  6 Fri Jan  1 00:00:25 1988
] Executing task C with count =  5 Fri Jan  1 00:00:26 1988
] *** TEST TOO LONG
[256/589] p:236 f:5   u:4   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: psxtimer02.exe
 <<skipping passes>>
[333/589] p:310 f:6   u:6   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: sp01.exe
Result: test-too-long Time: 0:05:00.365240 sp01.exe
=>  exe: spike --isa=RV32IMAFD ./riscv-rtems6/c/rv32imafd/testsuites/sptests/sp01.exe
] 
] 
=>  test start: SP 1
] *** BEGIN OF TEST SP 1 ***
] *** TEST VERSION: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA3  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:09   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:09   12/31/1988
] TA3  - rtems_clock_get_tod - 09:00:14   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:14   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:19   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:19   12/31/1988
] *** TEST TOO LONG
[334/589] p:311 f:6   u:6   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: sp02.exe
 <<skipping passes>>
[336/589] p:313 f:6   u:6   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: sp04.exe
Result: test-too-long Time: 0:05:00.435981 sp04.exe
=>  exe: spike --isa=RV32IMAFD ./riscv-rtems6/c/rv32imafd/testsuites/sptests/sp04.exe
] 
] 
=>  test start: SP 4
] *** BEGIN OF TEST SP 4 ***
] *** TEST VERSION: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)
] TA1 - rtems_task_suspend - on Task 2
] TA1 - rtems_task_suspend - on Task 3
] TA1 - killing time
] TA1 - rtems_task_resume - on Task 2
] TA1 - rtems_task_resume - on Task 3
] TA1 - rtems_task_mode - change mode to NO RTEMS_PREEMPT
] TA1 - 09:15:09   12/31/1988
] TA1 - 09:15:10   12/31/1988
] TA1 - 09:15:11   12/31/1988
] TA1 - 09:15:12   12/31/1988
] TA1 - 09:15:13   12/31/1988
] TA1 - 09:15:14   12/31/1988
] TA1 - rtems_task_mode - change mode to RTEMS_PREEMPT
] TA1 - 09:15:00   12/31/1988
] TA2 - 09:15:03   12/31/1988
] TA3 - 09:15:04   12/31/1988
] TA1 - 09:15:05   12/31/1988
] TA2 - 09:15:06   12/31/1988
] TA3 - 09:15:07   12/31/1988
] TA1 - 09:15:08   12/31/1988
] TA2 - 09:15:14   12/31/1988
] TA3 - 09:15:15   12/31/1988
] TA1 - 09:15:16   12/31/1988
] TA1 - rtems_extension_delete - successful
] *** TEST TOO LONG
[337/589] p:314 f:6   u:6   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: sp05.exe
 <<skipping passes>>
[342/589] p:319 f:6   u:6   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: sp12.exe
Result: test-too-long Time: 0:05:00.435769 sp12.exe
=>  exe: spike --isa=RV32IMAFD ./riscv-rtems6/c/rv32imafd/testsuites/sptests/sp12.exe
] 
] 
=>  test start: SP 12
] *** BEGIN OF TEST SP 12 ***
] *** TEST VERSION: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)
] INIT - Forward priority queue test
] PRI1 - rtems_semaphore_obtain - wait forever on SM2
] PRI2 - rtems_semaphore_obtain - wait forever on SM2
] PRI3 - rtems_semaphore_obtain - wait forever on SM2
] PRI4 - rtems_semaphore_obtain - wait forever on SM2
] PRI5 - rtems_semaphore_obtain - wait forever on SM2
] INIT - Backward priority queue test
] PRI5 - rtems_semaphore_obtain - wait forever on SM2
] PRI4 - rtems_semaphore_obtain - wait forever on SM2
] PRI3 - rtems_semaphore_obtain - wait forever on SM2
] PRI2 - rtems_semaphore_obtain - wait forever on SM2
] PRI1 - rtems_semaphore_obtain - wait forever on SM2
] INIT - Binary Semaphore and Priority Inheritance Test
] INIT - rtems_semaphore_create - allocated binary semaphore
] INIT - rtems_semaphore_release - allocated binary semaphore
] INIT - rtems_semaphore_delete - allocated binary semaphore
] PRI5 - rtems_semaphore_obtain - wait forever on SM2
] Set priority of self while holding resource
] PRI5 - rtems_task_suspend - until all priority tasks blocked
] PDRV - priority of PRI5 is 8
] PRI4 - rtems_semaphore_obtain - wait forever on SM2
] PDRV - change priority of PRI5 from 8 to 9
] PDRV - priority of PRI5 is 8
] PRI3 - rtems_semaphore_obtain - wait forever on SM2
] PDRV - priority of PRI5 is 8
] PRI2 - rtems_semaphore_obtain - wait forever on SM2
] PDRV - priority of PRI5 is 7
] PRI1 - rtems_semaphore_obtain - wait forever on SM2
] PDRV - priority of PRI5 is 6
] PDRV - rtems_task_resume - PRI5
] PRI5 - rtems_task_delete - all tasks waiting on SM2
] PRI5 - rtems_semaphore_obtain - nested
] PRI5 - rtems_semaphore_release - nested
] PRI5 - rtems_semaphore_release - restore priority
] PRI5 - priority of PRI5 is 9
] TA1 - rtems_semaphore_ident - smid => 1a010001
] TA1 - rtems_semaphore_obtain - wait forever on SM2
] TA1 - got SM2
] TA1 - rtems_semaphore_obtain - wait forever on SM3
] TA1 - got SM3
] TA1 - rtems_semaphore_obtain - get SM1 - RTEMS_NO_WAIT
] TA1 - got SM1
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA2 - rtems_semaphore_obtain - wait forever on SM1
] TA3 - rtems_semaphore_obtain - wait forever on SM2
] TA1 - rtems_semaphore_release - release SM1
] TA1 - rtems_semaphore_obtain - waiting for SM1 with 10 second timeout
] TA2 - got SM1
] TA2 - rtems_semaphore_release - release SM1
] TA2 - rtems_task_set_priority - make self highest priority task
] TA2 - rtems_semaphore_obtain - wait forever on SM2
] TA1 - got SM1
] TA1 - rtems_semaphore_release - release SM2
] TA2 - got SM2
] TA2 - rtems_semaphore_release - release SM2
] TA2 - rtems_task_exit
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA3 - got SM2
] TA3 - rtems_semaphore_release - release SM2
] TA3 - rtems_semaphore_obtain - wait forever on SM3
] TA1 - rtems_task_delete - delete TA3
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA4 - rtems_semaphore_obtain - wait forever on SM1
] TA5 - rtems_semaphore_obtain - wait forever on SM1
] TA1 - rtems_task_delete - delete TA4
] TA1 - rtems_semaphore_release - release SM1
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA5 - got SM1
] TA5 - rtems_semaphore_obtain - wait forever on SM1
] *** TEST TOO LONG
[343/589] p:320 f:6   u:6   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32imafd: sp13.exe
 <<skipping passes>>
[350/589] p:327 f:6   u:6   e:0   I:0   B:3   t:0   L:1   i:0   W:0   | riscv32/rv32imafd: sp20.exe
Result: test-too-long Time: 0:05:00.493877 sp20.exe
=>  exe: spike --isa=RV32IMAFD ./riscv-rtems6/c/rv32imafd/testsuites/sptests/sp20.exe
] 
] 
=>  test start: SP 20
] *** BEGIN OF TEST SP 20 ***
] *** TEST VERSION: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)
] TA1 - rtems_rate_monotonic_create id = 0x42010001
] TA1 - rtems_rate_monotonic_ident id = 0x42010001
] TA1 - (0x42010001) period 2
] TA2 - rtems_rate_monotonic_create id = 0x42010002
] TA2 - rtems_rate_monotonic_ident id = 0x42010002
] TA2 - (0x42010002) period 2
] TA3 - rtems_rate_monotonic_create id = 0x42010003
] TA3 - rtems_rate_monotonic_ident id = 0x42010003
] TA3 - (0x42010003) period 2
] TA4 - rtems_rate_monotonic_create id = 0x42010004
] TA4 - rtems_rate_monotonic_ident id = 0x42010004
] TA4 - (0x42010004) period 2
] TA5 - rtems_rate_monotonic_create id = 0x42010005
] TA5 - rtems_rate_monotonic_ident id = 0x42010005
] TA5 - (0x42010005) period 100
] TA6 - rtems_rate_monotonic_create id = 0x42010006
] TA6 - rtems_rate_monotonic_ident id = 0x42010006
] TA6 - (0x42010006) period 0
] TA5 - PERIODS CHECK OK (1)
] TA5 - PERIODS CHECK OK (2)
] *** TEST TOO LONG
[351/589] p:327 f:6   u:6   e:0   I:0   B:3   t:0   L:1   i:0   W:0   | riscv32/rv32imafd: sp2038.exe
 <<skipping passes>>
[361/589] p:335 f:6   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32imafd: sp30.exe
Result: test-too-long Time: 0:05:00.477509 sp30.exe
=>  exe: spike --isa=RV32IMAFD ./riscv-rtems6/c/rv32imafd/testsuites/sptests/sp30.exe
] 
] 
=>  test start: SP 30
] *** BEGIN OF TEST SP 30 ***
] *** TEST VERSION: 6.0.0.350bba7a04c7dd0c9e5fa0cd0aee33be564283a5
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20201030 (RTEMS 6, RSB 3d88829b937b8334698511a4a5aa728fe13304a1, Newlib 9517e5f)
] timer_initiate_server -- INVALID_PRIORITY
] timer_initiate_server -- OK
] timer_initiate_server -- already started
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA3  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:09   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:09   12/31/1988
] TA3  - rtems_clock_get_tod - 09:00:14   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:14   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:19   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:19   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:24   12/31/1988
] TA3  - rtems_clock_get_tod - 09:00:29   12/31/1988
] TA2  - rtems_clock_get_tod - 09:00:29   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:29   12/31/1988
] *** TEST TOO LONG
[362/589] p:336 f:6   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32imafd: sp31.exe
 <<skipping passes>>
[589/589] p:559 f:7   u:6   e:0   I:0   B:3   t:0   L:6   i:0   W:0   | riscv32/rv32imafd: tmtimer01.exe

Passed:        567
Failed:          7
User Input:      6
Expected Fail:   0
Indeterminate:   0
Benchmark:       3
Timeout:         0
Test too long:   6
Invalid:         0
Wrong Version:   0
Wrong Build:     0
Wrong Tools:     0
------------------
Total:         589
Failures:
User Input:
 capture.exe
 top.exe
 monitor.exe
 termios.exe
 dl10.exe
 fileio.exe
Benchmark:
 dhrystone.exe
 whetstone.exe
 linpack.exe
Test too long:
 sp30.exe
 sp01.exe
 sp04.exe
 psxtimer01.exe
 sp12.exe
 sp20.exe
Average test time: 0:00:02.600507
Testing time     : 0:25:31.699179