[rtems-test] riscv32/rv32im: RTEMS_POSIX_API: Passed:556 Failed:10 Timeout:1 Test-Too-long:11 Invalid:0 Wrong:0

joel at rtems.org joel at rtems.org
Sun Jan 10 12:09:14 UTC 2021


Testing time     : 0:28:37.997042
Average test time: 0:00:02.926741

Host
====
Linux-3.10.0-1127.19.1.el7.x86_64-x86_64-with-centos-7.9.2009-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.7696533b97a2d162978b22f371e0c8fab2b65fb9
Build  : RTEMS_POSIX_API
Tools  : 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)

Summary
=======

Passed:        556
Failed:         10
User Input:      6
Expected Fail:   0
Indeterminate:   0
Benchmark:       3
Timeout:         1
Test too long:  11
Invalid:         0
Wrong Version:   0
Wrong Build:     0
Wrong Tools:     0
------------------
Total:         587

Failures:
 mrfs_fssymlink.exe
 minimum.exe
 dl08.exe
 dl02.exe
 dl09.exe
 dl06.exe
 dl05.exe
 psxfenv01.exe
 dl07.exe
 spsysinit01.exe
User Input:
 top.exe
 dl10.exe
 monitor.exe
 capture.exe
 fileio.exe
 termios.exe
Benchmark:
 whetstone.exe
 linpack.exe
 dhrystone.exe
Timeouts:
 spcache01.exe
Test too long:
 crypt01.exe
 spedfsched01.exe
 sp12.exe
 sp20.exe
 sp04.exe
 block08.exe
 malloctest.exe
 sp01.exe
 sp30.exe
 spcbssched01.exe
 psxtimer01.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=rv32im_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, Nov 16 2020, 22:23:17) [GCC 4.8.5 20150623 (Red Hat 4.8.5-44)]
Host: Linux-3.10.0-1127.19.1.el7.x86_64-x86_64-with-centos-7.9.2009-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/587] p:0   f:0   u:0   e:0   I:0   B:0   t:0   L:0   i:0   W:0   | riscv32/rv32im: dhrystone.exe
 <<skipping passes>>
[ 58/587] p:48  f:0   u:0   e:0   I:0   B:2   t:0   L:0   i:0   W:0   | riscv32/rv32im: mrfs_fssymlink.exe
Result: failed     Time: 0:00:00.510873 mrfs_fssymlink.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/fstests/mrfs_fssymlink.exe
] 
] 
=>  test start: FSSYMLINK RFS
] *** BEGIN OF TEST FSSYMLINK RFS ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] Initializing filesystem RFS
] Create a file named file
] Create a symlink named symlink to file
] call readlink 
] file
] Unlink the file
] call readlink 
] Create a dir named file
] Create a symlink named symlink to file
] call readlink 
] terminate called after throwing an instance of 'trap_load_access_fault'
[ 59/587] p:49  f:0   u:0   e:0   I:0   B:2   t:0   L:0   i:0   W:0   | riscv32/rv32im: mrfs_fstime.exe
 <<skipping passes>>
[ 67/587] p:56  f:1   u:0   e:0   I:0   B:2   t:0   L:0   i:0   W:0   | riscv32/rv32im: block08.exe
Result: test-too-long Time: 0:05:00.470519 block08.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/libtests/block08.exe
] 
] 
=>  test start: BLOCK 8
] *** BEGIN OF TEST BLOCK 8 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] TEST DISK - OK
] Test 1.1 - STARTED
] Thread #1 started
] DISK_DRV: R [0] 
] Test 1.1: Step 2 - OK
] DISK_DRV: R [10] 
] Test 1.1: Step 4 - OK
] DISK_DRV: R [10] 
] Test 1.1: Step 6 - OK
] TEST PASSED
] Test 1.2 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [20] 
] Test 1.2: Step 3 - OK
] DISK_DRV: R [20] 
] Test 1.2: Step 5 - OK
] TEST PASSED
] Test 1.3 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [30] 
] Test 1.3: Step 3 - OK
] DISK_DRV: R [30] 
] Test 1.3: Step 5 - OK
] TEST PASSED
] Test 1.4 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [40] 
] Test 1.4: Step 3 - OK
] Test 1.4: Step 5 - OK
] TEST PASSED
] Test 1.5 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [50] 
] Test 1.5: Step 1 - OK
] Test 1.5: Step 4 - OK
] TEST PASSED
] Test 2.1 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [60] 
] Test 2.1: Step 3 - OK
] Test 2.1: Step 5 - OK
] DISK_DRV: W [60] 
] TEST PASSED
] Test 2.2 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [70] 
] Test 2.2: Step 3 - OK
] Test 2.2: Step 5 - OK
] DISK_DRV: W [70] 
] TEST PASSED
] Test 3.1 - STARTED
] Thread #1 started
] Thread #2 started
] Test 3.1: Step 3 - OK
] DISK_DRV: W [80] 
] Test 3.1: Step 5 - OK
] DISK_DRV: W [85] 
] TEST PASSED
] Test 3.2 - STARTED
] Thread #1 started
] Thread #2 started
] Thread #3 started
] DISK_DRV: R [90] 
] Thread #2 DEBLOCK
] Test 3.2: Step 5 - OK
] DISK_DRV: W [92] 
] Thread #3 DEBLOCK
] DISK_DRV: W [94] 
] TEST PASSED
] Test 3.3 - STARTED
] Thread #1 started
] Thread #2 started
] Thread #3 started
] DISK_DRV: R [96] 
] DISK_DRV: R [98] 
] Thread #2 DEBLOCK
] Test 3.3: Step 5 - OK
] DISK_DRV: R [100] 
] Thread #3 DEBLOCK
] TEST PASSED
] Test 4.1 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [112] 
] Test 4.1: Step 2 - OK
] DISK_DRV: W [112] 
] Test 4.1: Step 5 - OK
] TEST PASSED
] Test 4.2 - STARTED
] Thread #1 started
] Thread #2 started
] DISK_DRV: R [114] 
] DISK_DRV: W [114] 
] Test 4.2: Step 3 - OK
] *** TEST TOO LONG
[ 68/587] p:56  f:1   u:0   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32im: block09.exe
 <<skipping passes>>
[ 81/587] p:69  f:1   u:0   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32im: crypt01.exe
Result: test-too-long Time: 0:05:00.455906 crypt01.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/libtests/crypt01.exe
] 
] 
=>  test start: CRYPT 1
] *** BEGIN OF TEST CRYPT 1 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] test crypt_md5_r()
] test crypt_sha256_r()
] input:    Hello world!
] salt:     $5$saltstring
] expected: $5$saltstring$5B8vYYiY.CVt1RlTTf8KbXBH3hsxY/GNooZaBBGWEc5
] actual:   $5$saltstring$5B8vYYiY.CVt1RlTTf8KbXBH3hsxY/GNooZaBBGWEc5
] input:    Hello world!
] salt:     $5$rounds=10000$saltstringsaltstring
] expected: $5$rounds=10000$saltstringsaltst$3xv.VbSHBb41AL9AvLeujZkZRBAwqFMz2.opqey6IcA
] actual:   $5$rounds=10000$saltstringsaltst$3xv.VbSHBb41AL9AvLeujZkZRBAwqFMz2.opqey6IcA
] input:    This is just a test
] salt:     $5$rounds=5000$toolongsaltstring
] expected: $5$rounds=5000$toolongsaltstrin$Un/5jzAHMgOGZ5.mWJpuVolil07guHPvOW8mGRcvxa5
] actual:   $5$rounds=5000$toolongsaltstrin$Un/5jzAHMgOGZ5.mWJpuVolil07guHPvOW8mGRcvxa5
] input:    a very much longer text to encrypt.  This one even stretches over morethan one line.
] salt:     $5$rounds=1400$anotherlongsaltstring
] expected: $5$rounds=1400$anotherlongsalts$Rx.j8H.h8HjEDGomFU8bDkXm3XIUnzyxf12oP84Bnq1
] actual:   $5$rounds=1400$anotherlongsalts$Rx.j8H.h8HjEDGomFU8bDkXm3XIUnzyxf12oP84Bnq1
] input:    we have a short salt string but not a short password
] salt:     $5$rounds=77777$short
] expected: $5$rounds=77777$short$JiO1O3ZpDAxGJeaDIuqCoEFysAe1mZNJRs3pw0KQRd/
] actual:   $5$rounds=77777$short$JiO1O3ZpDAxGJeaDIuqCoEFysAe1mZNJRs3pw0KQRd/
] input:    a short string
] salt:     $5$rounds=123456$asaltof16chars..
] expected: $5$rounds=123456$asaltof16chars..$gP3VQ/6X7UUEW3HkBn2w1/Ptq2jxPyzV/cZKmF/wJvD
] actual:   $5$rounds=123456$asaltof16chars..$gP3VQ/6X7UUEW3HkBn2w1/Ptq2jxPyzV/cZKmF/wJvD
] input:    the minimum number is still observed
] salt:     $5$rounds=10$roundstoolow
] expected: $5$rounds=1000$roundstoolow$yfvwcWrQ8l/K0DAWyuPMDNHpIVlTQebY9l/gL972bIC
] actual:   $5$rounds=1000$roundstoolow$yfvwcWrQ8l/K0DAWyuPMDNHpIVlTQebY9l/gL972bIC
] test crypt_sha512_r()
] input:    Hello world!
] salt:     $6$saltstring
] expected: $6$saltstring$svn8UoSVapNtMuq1ukKS4tPQd8iKwSMHWjl/O817G3uBnIFNjnQJuesI68u4OTLiBFdcbYEdFCoEOfaS35inz1
] actual:   $6$saltstring$svn8UoSVapNtMuq1ukKS4tPQd8iKwSMHWjl/O817G3uBnIFNjnQJuesI68u4OTLiBFdcbYEdFCoEOfaS35inz1
] input:    Hello world!
] salt:     $6$rounds=10000$saltstringsaltstring
] expected: $6$rounds=10000$saltstringsaltst$OW1/O6BYHV6BcXZu8QVeXbDWra3Oeqh0sbHbbMCVNSnCM/UrjmM0Dp8vOuZeHBy/YTBmSK6H9qs/y3RnOaw5v.
] actual:   $6$rounds=10000$saltstringsaltst$OW1/O6BYHV6BcXZu8QVeXbDWra3Oeqh0sbHbbMCVNSnCM/UrjmM0Dp8vOuZeHBy/YTBmSK6H9qs/y3RnOaw5v.
] input:    This is just a test
] salt:     $6$rounds=5000$toolongsaltstring
] expected: $6$rounds=5000$toolongsaltstrin$lQ8jolhgVRVhY4b5pZKaysCLi0QBxGoNeKQzQ3glMhwllF7oGDZxUhx1yxdYcz/e1JSbq3y6JMxxl8audkUEm0
] actual:   $6$rounds=5000$toolongsaltstrin$lQ8jolhgVRVhY4b5pZKaysCLi0QBxGoNeKQzQ3glMhwllF7oGDZxUhx1yxdYcz/e1JSbq3y6JMxxl8audkUEm0
] input:    a very much longer text to encrypt.  This one even stretches over morethan one line.
] salt:     $6$rounds=1400$anotherlongsaltstring
] expected: $6$rounds=1400$anotherlongsalts$POfYwTEok97VWcjxIiSOjiykti.o/pQs.wPvMxQ6Fm7I6IoYN3CmLs66x9t0oSwbtEW7o7UmJEiDwGqd8p4ur1
] actual:   $6$rounds=1400$anotherlongsalts$POfYwTEok97VWcjxIiSOjiykti.o/pQs.wPvMxQ6Fm7I6IoYN3CmLs66x9t0oSwbtEW7o7UmJEiDwGqd8p4ur1
] input:    we have a short salt string but not a short password
] salt:     $6$rounds=77777$short
] expected: $6$rounds=77777$short$WuQyW2YR.hBNpjjRhpYD/ifIw05xdfeEyQoMxIXbkvr0gge1a1x3yRULJ5CCaUeOxFmtlcGZelFl5CxtgfiAc0
] actual:   $6$rounds=77777$short$WuQyW2YR.hBNpjjRhpYD/ifIw05xdfeEyQoMxIXbkvr0gge1a1x3yRULJ5CCaUeOxFmtlcGZelFl5CxtgfiAc0
] *** TEST TOO LONG
[ 82/587] p:70  f:1   u:0   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32im: defaultconfig01.exe
 <<skipping passes>>
[109/587] p:90  f:7   u:1   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32im: malloctest.exe
Result: test-too-long Time: 0:05:00.515029 malloctest.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/libtests/malloctest.exe
] 
] 
=>  test start: MALLOCTEST
] *** BEGIN OF TEST MALLOCTEST ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] run tests for _Heap_Initialize()
] run tests for _Heap_Block_allocate()
] 	allocate block at the beginning
] 	allocate block full space
] 	allocate block in the middle
] 	allocate block at the end
] 	allocate block at the beginning
] 	allocate block full space
] 	allocate block in the middle
] 	allocate block at the end
] 	allocate block at the beginning
] 	allocate block full space
] 	allocate block in the middle
] 	allocate block at the end
] 	allocate block at the beginning
] 	allocate block full space
] 	allocate block in the middle
] 	allocate block at the end
] run tests for _Heap_Allocate_aligned_with_boundary()
] 	check if NULL will be returned if size causes integer overflow
] 	try to allocate more space than the one which fits in the boundary
] 	check if alignment will be set to page size if only a boundary is given
] 	create a block which is bigger then the first free space
] 	set boundary before allocation begin
] 	set boundary between allocation begin and end
] 	set boundary after allocation end
] 	set boundary on allocation end
] 	align the allocation to different positions in the block header
] 	allocate last block with different boundarys
] 	break the boundaries and aligns more than one time
] 	different combinations, so that there is no valid block at the end
] 	try to create a block, which is not possible because of the alignment and boundary
] run tests for _Heap_Resize_Block()
] 	give a block outside the heap to the function
] 	increase size
] 		let the next block be used alredy and try to get a size bigger than the actual block
] 		next block not used and try to set the new allocation size between the page-alignments
] 		let the block after the next be used and try to allocate more then one pagesize more
] 	try to resize to the same size
] 	decrease size
] 		decrease a block with two pages to one page
] 		resize the block to the size 0
] realloc - failed grow in place: 0x80046020 != realloc(0x800460c0,45)
] realloc - failed non-in place: realloc(0x800460c0,64) = 0x800460c0
] malloc_walk - normal path
] malloc_walk - in critical section path
] Heap Initialized
] heap extend - link below
] heap extend - merge below overlap
] heap extend - merge below
] heap extend - merge above overlap
] heap extend - merge above
] heap extend - link above
] heap extend - area too small
] heap extend - invalid area
] heap extend - merge below with align up
] malloc_free_space - check malloc space drops after malloc
] malloc_free_space - verify free space returns to previous value
] malloc_info - called with NULL
] 
] malloc_info - check free space drops after malloc
] malloc_info - verify free space returns to previous value
] _Heap_Size_with_overhead
] _Protected_heap_Get_information - NULL heap
] _Protected_heap_Get_information - NULL info
] posix_memalign - NULL return pointer -- EINVAL
] posix_memalign - alignment of 0 -- EINVAL
] posix_memalign - alignment  of 2-- EINVAL
] posix_memalign - alignment of 16 -- OK
] posix_memalign - alignment of 32 -- OK
] posix_memalign - alignment of 64 -- OK
] posix_memalign - alignment of 128 -- OK
] posix_memalign - alignment of 256 -- OK
] posix_memalign - alignment of 512 -- OK
] posix_memalign - alignment of 1024 -- OK
] posix_memalign - alignment of 2048 -- OK
] posix_memalign - alignment of 4096 -- OK
] posix_memalign - alignment of 8192 -- OK
] posix_memalign - alignment of 16384 -- OK
] posix_memalign - alignment of 32768 -- OK
] posix_memalign - alignment of 65536 -- OK
] posix_memalign - alignment of 131072 -- OK
] posix_memalign - alignment of 262144 -- OK
] posix_memalign - alignment of 524288 -- OK
] posix_memalign - alignment of 1048576 -- OK
] posix_memalign - alignment of 2097152 -- OK
] posix_memalign - alignment of 4194304 -- OK
] posix_memalign - alignment of 8388608 -- OK
] posix_memalign - alignment of 16777216 -- OK
] posix_memalign - alignment of 33554432 -- OK
] posix_memalign - alignment of 67108864 -- OK
] posix_memalign - ran out of memory trying 67108864
] posix_memalign - alignment of 67108864 -- SKIPPED
] posix_memalign - alignment of 134217728 -- SKIPPED
] posix_memalign - alignment of 268435456 -- SKIPPED
] posix_memalign - alignment of 536870912 -- SKIPPED
] posix_memalign - alignment of 1073741824 -- SKIPPED
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 690 bytes
] TA2  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 690 bytes
] TA3  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 690 bytes
] TA4  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 690 bytes
] TA5  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 690 bytes
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 505 bytes
] TA2  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 505 bytes
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 591 bytes
] TA3  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 505 bytes
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] mallocing 554 bytes
] TA4  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 505 bytes
] TA2  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 591 bytes
] TA1  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 378 bytes
] TA5  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 505 bytes
] TA1  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 257 bytes
] TA3  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 591 bytes
] TA2  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 554 bytes
] TA1  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 207 bytes
] TA1  - rtems_clock_get_tod - 09:00:01   12/31/1988
] mallocing 626 bytes
] TA4  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 591 bytes
] TA2  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 378 bytes
] TA1  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 340 bytes
] TA3  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 554 bytes
] TA1  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 843 bytes
] TA5  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 591 bytes
] TA2  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 257 bytes
] TA1  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 68 bytes
] TA1  - rtems_clock_get_tod - 09:00:02   12/31/1988
] mallocing 409 bytes
] TA4  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 554 bytes
] TA3  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 378 bytes
] TA2  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 207 bytes
] TA1  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 879 bytes
] TA1  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 319 bytes
] TA2  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 626 bytes
] TA1  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 980 bytes
] TA5  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 554 bytes
] TA3  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 257 bytes
] TA1  - rtems_clock_get_tod - 09:00:03   12/31/1988
] mallocing 85 bytes
] TA4  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 378 bytes
] TA2  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 340 bytes
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 907 bytes
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 102 bytes
] TA3  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 207 bytes
] TA2  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 843 bytes
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 921 bytes
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] mallocing 507 bytes
] TA5  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 378 bytes
] TA4  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 257 bytes
] TA2  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 68 bytes
] TA1  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 872 bytes
] TA3  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 626 bytes
] TA1  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 333 bytes
] TA2  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 409 bytes
] TA1  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 692 bytes
] TA1  - rtems_clock_get_tod - 09:00:05   12/31/1988
] mallocing 556 bytes
] TA4  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 207 bytes
] TA3  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 340 bytes
] TA2  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 879 bytes
] TA1  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 361 bytes
] TA5  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 257 bytes
] TA1  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 31 bytes
] TA2  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 319 bytes
] TA1  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 858 bytes
] TA3  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 843 bytes
] TA1  - rtems_clock_get_tod - 09:00:06   12/31/1988
] mallocing 98 bytes
] TA4  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 626 bytes
] TA2  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 980 bytes
] TA1  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 877 bytes
] TA1  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 449 bytes
] TA5  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 207 bytes
] TA3  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 68 bytes
] TA2  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 85 bytes
] TA1  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 432 bytes
] TA1  - rtems_clock_get_tod - 09:00:07   12/31/1988
] mallocing 606 bytes
] TA4  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 340 bytes
] TA2  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 907 bytes
] TA1  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 927 bytes
] TA3  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 409 bytes
] TA1  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 664 bytes
] TA2  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 102 bytes
] TA1  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 395 bytes
] TA5  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 626 bytes
] TA1  - rtems_clock_get_tod - 09:00:08   12/31/1988
] mallocing 438 bytes
] TA4  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 843 bytes
] TA3  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 879 bytes
] TA2  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 921 bytes
] TA1  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 652 bytes
] TA1  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 928 bytes
] TA2  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 507 bytes
] TA1  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 949 bytes
] TA3  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 319 bytes
] TA1  - rtems_clock_get_tod - 09:00:09   12/31/1988
] mallocing 307 bytes
] TA5  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 340 bytes
] TA4  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 68 bytes
] TA2  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 872 bytes
] TA1  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 596 bytes
] TA1  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 783 bytes
] TA3  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 980 bytes
] TA2  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 333 bytes
] TA1  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 338 bytes
] TA1  - rtems_clock_get_tod - 09:00:10   12/31/1988
] mallocing 805 bytes
] TA4  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 409 bytes
] TA2  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 692 bytes
] TA1  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 942 bytes
] TA5  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 843 bytes
] TA3  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 85 bytes
] TA1  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 66 bytes
] TA2  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 556 bytes
] TA1  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 857 bytes
] TA1  - rtems_clock_get_tod - 09:00:11   12/31/1988
] mallocing 977 bytes
] TA4  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 879 bytes
] TA3  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 907 bytes
] TA2  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 361 bytes
] TA1  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 889 bytes
] TA1  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 545 bytes
] TA5  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 68 bytes
] TA2  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 31 bytes
] TA1  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 864 bytes
] TA3  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 102 bytes
] TA1  - rtems_clock_get_tod - 09:00:12   12/31/1988
] mallocing 457 bytes
] TA4  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 319 bytes
] TA2  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 858 bytes
] TA1  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 800 bytes
] TA1  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 873 bytes
] TA3  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 921 bytes
] TA2  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 98 bytes
] TA1  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 821 bytes
] TA5  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 409 bytes
] TA1  - rtems_clock_get_tod - 09:00:13   12/31/1988
] mallocing 185 bytes
] TA4  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 980 bytes
] TA2  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 877 bytes
] TA1  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 86 bytes
] TA3  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 507 bytes
] TA1  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 638 bytes
] TA2  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 449 bytes
] TA1  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 233 bytes
] TA1  - rtems_clock_get_tod - 09:00:14   12/31/1988
] mallocing 462 bytes
] TA5  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 879 bytes
] TA4  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 85 bytes
] TA3  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 872 bytes
] TA2  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 432 bytes
] TA1  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 7 bytes
] TA1  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 635 bytes
] TA2  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 606 bytes
] TA1  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 421 bytes
] TA3  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 333 bytes
] TA1  - rtems_clock_get_tod - 09:00:15   12/31/1988
] mallocing 953 bytes
] TA4  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 907 bytes
] TA2  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 927 bytes
] TA1  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 210 bytes
] TA5  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 319 bytes
] TA1  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 970 bytes
] TA3  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 692 bytes
] TA2  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 664 bytes
] TA1  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 261 bytes
] TA1  - rtems_clock_get_tod - 09:00:16   12/31/1988
] mallocing 857 bytes
] TA4  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 102 bytes
] TA2  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 395 bytes
] TA1  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 581 bytes
] TA3  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 556 bytes
] TA1  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 707 bytes
] TA5  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 980 bytes
] TA2  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 438 bytes
] TA1  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 285 bytes
] TA1  - rtems_clock_get_tod - 09:00:17   12/31/1988
] mallocing 318 bytes
] TA4  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 921 bytes
] TA3  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 361 bytes
] TA2  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 652 bytes
] TA1  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 643 bytes
] TA1  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 858 bytes
] TA2  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 928 bytes
] TA1  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 668 bytes
] TA5  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 85 bytes
] TA3  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 31 bytes
] TA1  - rtems_clock_get_tod - 09:00:18   12/31/1988
] mallocing 443 bytes
] TA4  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 507 bytes
] TA2  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 949 bytes
] TA1  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 55 bytes
] TA1  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 777 bytes
] TA3  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 858 bytes
] TA2  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 307 bytes
] TA1  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 594 bytes
] TA1  - rtems_clock_get_tod - 09:00:19   12/31/1988
] mallocing 102 bytes
] TA5  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 907 bytes
] TA4  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 872 bytes
] TA2  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 596 bytes
] TA1  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 299 bytes
] TA3  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 98 bytes
] TA1  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 950 bytes
] TA2  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 783 bytes
] TA1  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 665 bytes
] TA1  - rtems_clock_get_tod - 09:00:20   12/31/1988
] mallocing 947 bytes
] TA4  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 333 bytes
] TA3  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 877 bytes
] TA2  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 338 bytes
] TA1  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 879 bytes
] TA5  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 102 bytes
] TA1  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 700 bytes
] TA2  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 805 bytes
] TA1  - rtems_clock_get_tod - 09:00:21   12/31/1988
] mallocing 375 bytes
] *** TEST TOO LONG
[110/587] p:91  f:7   u:1   e:0   I:0   B:3   t:0   L:0   i:0   W:0   | riscv32/rv32im: math.exe
 <<skipping passes>>
[255/587] p:229 f:8   u:4   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: psxtimer01.exe
Result: test-too-long Time: 0:05:00.462448 psxtimer01.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/psxtests/psxtimer01.exe
] 
] 
=>  test start: PSXTIMER 1
] *** BEGIN OF TEST PSXTIMER 1 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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
] *** TEST TOO LONG
[256/587] p:230 f:8   u:4   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: psxtimer02.exe
 <<skipping passes>>
[333/587] p:304 f:9   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: sp01.exe
Result: test-too-long Time: 0:05:00.405807 sp01.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/sp01.exe
] 
] 
=>  test start: SP 1
] *** BEGIN OF TEST SP 1 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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/587] p:305 f:9   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: sp02.exe
 <<skipping passes>>
[336/587] p:307 f:9   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: sp04.exe
Result: test-too-long Time: 0:05:00.424288 sp04.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/sp04.exe
] 
] 
=>  test start: SP 4
] *** BEGIN OF TEST SP 4 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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/587] p:308 f:9   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: sp05.exe
 <<skipping passes>>
[342/587] p:313 f:9   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: sp12.exe
Result: test-too-long Time: 0:05:00.425984 sp12.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/sp12.exe
] 
] 
=>  test start: SP 12
] *** BEGIN OF TEST SP 12 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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
] *** TEST TOO LONG
[343/587] p:314 f:9   u:6   e:0   I:0   B:3   t:0   L:3   i:0   W:0   | riscv32/rv32im: sp13.exe
 <<skipping passes>>
[350/587] p:320 f:9   u:6   e:0   I:0   B:3   t:0   L:4   i:0   W:0   | riscv32/rv32im: sp20.exe
Result: test-too-long Time: 0:05:00.423361 sp20.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/sp20.exe
] 
] 
=>  test start: SP 20
] *** BEGIN OF TEST SP 20 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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/587] p:321 f:9   u:6   e:0   I:0   B:3   t:0   L:4   i:0   W:0   | riscv32/rv32im: sp2038.exe
 <<skipping passes>>
[361/587] p:329 f:9   u:6   e:0   I:0   B:3   t:0   L:6   i:0   W:0   | riscv32/rv32im: sp30.exe
Result: test-too-long Time: 0:05:00.528693 sp30.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/sp30.exe
] 
] 
=>  test start: SP 30
] *** BEGIN OF TEST SP 30 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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
] *** TEST TOO LONG
[362/587] p:330 f:9   u:6   e:0   I:0   B:3   t:0   L:6   i:0   W:0   | riscv32/rv32im: sp31.exe
 <<skipping passes>>
[408/587] p:376 f:9   u:6   e:0   I:0   B:3   t:0   L:7   i:0   W:0   | riscv32/rv32im: spcache01.exe
Result: timeout    Time: 0:03:00.288376 spcache01.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/spcache01.exe
] *** TIMEOUT TIMEOUT
[409/587] p:376 f:9   u:6   e:0   I:0   B:3   t:0   L:7   i:0   W:0   | riscv32/rv32im: spcbssched01.exe
Result: test-too-long Time: 0:05:00.390781 spcbssched01.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/spcbssched01.exe
] 
] 
=>  test start: SPCBSSCHED 1
] *** BEGIN OF TEST SPCBSSCHED 1 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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
] TA1  - rtems_clock_get_tod - 09:00:34   12/31/1988
] *** TEST TOO LONG
[410/587] p:377 f:9   u:6   e:0   I:0   B:3   t:0   L:7   i:0   W:0   | riscv32/rv32im: spcbssched02.exe
 <<skipping passes>>
[424/587] p:390 f:9   u:6   e:0   I:0   B:3   t:0   L:8   i:0   W:0   | riscv32/rv32im: spedfsched01.exe
Result: test-too-long Time: 0:05:00.542832 spedfsched01.exe
=>  exe: spike --isa=RV32IM ./riscv-rtems6/c/rv32im/testsuites/sptests/spedfsched01.exe
] 
] 
=>  test start: SPEDFSCHED 1
] *** BEGIN OF TEST SPEDFSCHED 1 ***
] *** TEST VERSION: 6.0.0.7696533b97a2d162978b22f371e0c8fab2b65fb9
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 10.2.1 20210104 (RTEMS 6, RSB dd54653080bc3ab1f70032d8080059e617cc75a4, Newlib 415fdd4)
] 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
] TA1  - rtems_clock_get_tod - 09:00:34   12/31/1988
] *** TEST TOO LONG
[425/587] p:391 f:9   u:6   e:0   I:0   B:3   t:0   L:8   i:0   W:0   | riscv32/rv32im: spedfsched02.exe
 <<skipping passes>>
[587/587] p:549 f:10  u:6   e:0   I:0   B:3   t:1   L:10  i:0   W:0   | riscv32/rv32im: tmtimer01.exe

Passed:        556
Failed:         10
User Input:      6
Expected Fail:   0
Indeterminate:   0
Benchmark:       3
Timeout:         1
Test too long:  11
Invalid:         0
Wrong Version:   0
Wrong Build:     0
Wrong Tools:     0
------------------
Total:         587
Failures:
 mrfs_fssymlink.exe
 minimum.exe
 dl08.exe
 dl02.exe
 dl09.exe
 dl06.exe
 dl05.exe
 psxfenv01.exe
 dl07.exe
 spsysinit01.exe
User Input:
 top.exe
 dl10.exe
 monitor.exe
 capture.exe
 fileio.exe
 termios.exe
Benchmark:
 whetstone.exe
 linpack.exe
 dhrystone.exe
Timeouts:
 spcache01.exe
Test too long:
 crypt01.exe
 spedfsched01.exe
 sp12.exe
 sp20.exe
 sp04.exe
 block08.exe
 malloctest.exe
 sp01.exe
 sp30.exe
 spcbssched01.exe
 psxtimer01.exe
Average test time: 0:00:02.926741
Testing time     : 0:28:37.997042


More information about the build mailing list