[rtems-test] riscv32/rv32i: RTEMS_POSIX_API: Passed:530 Failed:19 Timeout:31 Invalid:2 Wrong:0

joel at rtems.org joel at rtems.org
Fri Apr 17 06:10:16 UTC 2020


Testing time     : 0:49:19.122823
Average test time: 0:00:05.006976

Host
====
FreeBSD-12.1-RELEASE-p3-amd64-64bit-ELF (FreeBSD rtbf64b 12.1-RELEASE-p3 FreeBSD 12.1-RELEASE-p3 GENERIC amd64 amd64)

Configuration
=============
Version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
Build  : RTEMS_POSIX_API
Tools  : 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)

Summary
=======

Passed:        530
Failed:         19
User Input:      6
Expected Fail:   0
Indeterminate:   0
Benchmark:       3
Timeout:        31
Invalid:         2
Wrong Version:   0
Wrong Build:     0
Wrong Tools:     0
------------------
Total:         591

Failures:
 dl02.exe
 dl05.exe
 dl06.exe
 dl07.exe
 dl08.exe
 dl09.exe
 psxfenv01.exe
 sp42.exe
 spintrcritical01.exe
 spintrcritical02.exe
 spintrcritical03.exe
 spintrcritical04.exe
 spintrcritical05.exe
 spintrcritical10.exe
 spintrcritical16.exe
 spintrcritical21.exe
 spintrcritical22.exe
 spintrcritical23.exe
 spsysinit01.exe
User Input:
 dl10.exe
 monitor.exe
 termios.exe
 top.exe
 capture.exe
 fileio.exe
Benchmark:
 whetstone.exe
 linpack.exe
 dhrystone.exe
Timeouts:
 block08.exe
 cpuuse.exe
 crypt01.exe
 flashdisk01.exe
 malloctest.exe
 psxintrcritical01.exe
 psxsignal01.exe
 psxstat.exe
 psxtime.exe
 psxtimer01.exe
 ticker.exe
 sp01.exe
 sp04.exe
 sp05.exe
 sp12.exe
 sp19.exe
 sp20.exe
 sp24.exe
 sp30.exe
 spcbssched01.exe
 spcbssched03.exe
 spcontext01.exe
 spedfsched01.exe
 spedfsched03.exe
 spintrcritical07.exe
 spintrcritical15.exe
 spintrcritical18.exe
 spintrcritical24.exe
 spqreslib.exe
 spsimplesched01.exe
 tmfine01.exe
Invalid:
 rhtaskpreempt.exe
 rhtaskswitch.exe
Log
===

RTEMS Testing - Tester, 5 (ea86d967ec0e)
 Command Line: /home/joel/rtems-cron-5/rtems-tools//tester/rtems-test --rtems-tools=/home/joel/rtems-cron-5/tools/5 --rtems-bsp=rv32i_spike --log=run.log --mail --mail-from=joel at rtems.org --mail-to=build at rtems.org .
 Host: FreeBSD rtbf64b 12.1-RELEASE-p3 FreeBSD 12.1-RELEASE-p3 GENERIC amd64
 Python: 3.6.9 (default, Oct 24 2019, 01:18:01) [GCC 4.2.1 Compatible FreeBSD Clang 6.0.1 (tags/RELEASE_601/final 335540)]
Host: FreeBSD-12.1-RELEASE-p3-amd64-64bit-ELF (FreeBSD rtbf64b 12.1-RELEASE-p3 FreeBSD 12.1-RELEASE-p3 GENERIC amd64 amd64)
[  1/591] p:0   f:0   u:0   e:0   I:0   B:0   t:0   i:0   W:0   | riscv32/rv32i: dhrystone.exe
 <<skipping passes>>
[ 67/591] p:60  f:0   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: block08.exe
Result: timeout    Time: 0:03:00.396272 block08.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/block08.exe
] 
] 
=>  test start: BLOCK 8
] *** BEGIN OF TEST BLOCK 8 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[ 68/591] p:61  f:0   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: block09.exe
 <<skipping passes>>
[ 80/591] p:73  f:0   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: cpuuse.exe
Result: timeout    Time: 0:03:00.415061 cpuuse.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/cpuuse.exe
] 
] 
=>  test start: CPUUSE
] *** BEGIN OF TEST CPUUSE ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[ 81/591] p:74  f:0   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: crypt01.exe
Result: timeout    Time: 0:03:00.468821 crypt01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/crypt01.exe
] 
] 
=>  test start: CRYPT 1
] *** BEGIN OF TEST CRYPT 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[ 82/591] p:75  f:0   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: defaultconfig01.exe
 <<skipping passes>>
[ 88/591] p:81  f:0   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl02.exe
Result: failed     Time: 0:00:01.008967 dl02.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/dl02.exe
] 
] 
=>  test start: libdl (RTL) 2
] *** BEGIN OF TEST libdl (RTL) 2 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] load: /dl02-o1.o
] handle: 0x80064700 has unresolved externals
] load: /dl02-o2.o
] handle: 0x80064eb0 has unresolved externals
] Loaded module: argc:4 [../../../../../../rtems/c/src/../../testsuites/libtests/dl02/dl02-o1.c]
]   0: 1
]   1: 2
]   2: 3
]   3: 4
] Loaded module: argc:4 [../../../../../../rtems/c/src/../../testsuites/libtests/dl02/dl02-o2.c]
]   0: 1
]   1: 2
]   2: 3
]   3: 4
] dl_o2_func2: d1=2147483647.00 d2=0.0 ret=0.00
] rtems_main: dl_o2_func2 returned: 0.00
] rtems_main: dl_o2_func2 returned a bad
] dlsym call failed: ret value bad
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[ 89/591] p:81  f:1   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl03.exe
 <<skipping passes>>
[ 91/591] p:83  f:1   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl05.exe
Result: failed     Time: 0:00:01.008934 dl05.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/dl05.exe
] 
] 
=>  test start: libdl (RTL) 5
] *** BEGIN OF TEST libdl (RTL) 5 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 
] *** FATAL ***
] fatal source: 9 (RTEMS_FATAL_SOURCE_EXCEPTION)
] mstatus 0x00001880
] mcause  0x00000004
] mepc    0x80019228
] ra      0x8001793c
] sp      0x800774e0
] gp      0x8006b800
] tp      0x00000000
] t0      0xff7fffff
] t1      0x80017850
] t2      0xffffffff
] s0      0x80086e87
] s1      0x80085210
] a0      0x00000023
] a1      0x8005d2f4
] a2      0x800854c0
] a3      0x00000003
] a4      0x80019228
] a5      0x80086bd0
] a6      0xfffffd49
] a7      0x80086bd0
] s2      0x80086bd0
] s3      0x800775a0
] s4      0x00000001
] s5      0x8007eb44
] s6      0x800854c0
] s7      0x80086448
] s8      0x00000000
] s9      0x80085c40
] s10     0x80085c90
] s11     0x80077550
] t3      0x2e003738
] t4      0x31465341
] t5      0x4c2e0036
] t6      0x38314653
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[ 92/591] p:83  f:2   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl06.exe
Result: failed     Time: 0:00:01.008847 dl06.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/dl06.exe
] 
] 
=>  test start: libdl (RTL) 6
] *** BEGIN OF TEST libdl (RTL) 6 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 
] load: /dl06.rap
] dlopen failed: (null)
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[ 93/591] p:83  f:3   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl07.exe
Result: failed     Time: 0:00:01.008589 dl07.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/dl07.exe
] 
] 
=>  test start: libdl (RTL) 7
] *** BEGIN OF TEST libdl (RTL) 7 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] --------------------------------------------------
]  Run: 0
] Test source (link in strstr): testsuites/libtests/dl07/dl-load.c
] load: /dl07-o1.o
] ../../../../../../rtems/c/src/../../testsuites/libtests/dl07/dl-load.c: 70 unresolved == 0
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[ 94/591] p:83  f:4   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl08.exe
Result: failed     Time: 0:00:01.008953 dl08.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/dl08.exe
] 
] 
=>  test start: libdl (RTL) 8
] *** BEGIN OF TEST libdl (RTL) 8 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] --------------------------------------------------
]  Run: 0
] Test source (link in strstr): testsuites/libtests/dl08/dl-load.c
] load: /dl08-o1.o
] dlo6: CONSTRUCTOR: ../../../../../../rtems/c/src/../../testsuites/libtests/dl08/dl08-o6-123456789-123456789.c
] dlo6:   dl06_unresolv_1:    8: 0x80084b20: 0
] dlo6:   dl06_unresolv_2:    2: 0x80084b28: 0
] dlo6:   dl06_unresolv_3:    4: 0x80084b2c: 0
] dlo6:   dl06_unresolv_4:    1: 0x80084b30: 0
] dlo6:   dl06_unresolv_5:    8: 0x80084b38: 0
] ../../../../../../rtems/c/src/../../testsuites/libtests/dl08/dl-load.c: 71 unresolved == 0
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[ 95/591] p:83  f:5   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl09.exe
Result: failed     Time: 0:00:01.008587 dl09.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/dl09.exe
] 
] 
=>  test start: libdl (RTL) 9
] *** BEGIN OF TEST libdl (RTL) 9 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] --------------------------------------------------
]  Run: 0
] Test source (link in strstr): testsuites/libtests/dl09/dl-load.c
] load: /dl09-o1.o
] handel: 0x80076850: unresolved externals
] handle: 0x80076850 loaded
] space alloc: /dl09-o1.o: 33554432: 0x80077e20
] load: /dl09-o2.o
] handel: 0x800770f0: unresolved externals
] handle: 0x800770f0 loaded
] space alloc: /dl09-o2.o: 33554432: 0
] ../../../../../../rtems/c/src/../../testsuites/libtests/dl09/dl-load.c: 156 o->space != NULL
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[ 96/591] p:83  f:6   u:0   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: dl10.exe
 <<skipping passes>>
[100/591] p:86  f:6   u:1   e:0   I:0   B:3   t:0   i:0   W:0   | riscv32/rv32i: flashdisk01.exe
Result: timeout    Time: 0:03:00.562369 flashdisk01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/flashdisk01.exe
] 
] 
=>  test start: FLASHDISK 1
] *** BEGIN OF TEST FLASHDISK 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] [00]: start
] [00]: mount: /dev/fdda -> /mnt
] [00]: init root: /mnt
] [00]: create dir: 1804928587
] [00]: create dir: 959030623
] [00]: create dir: 1903590565
] [00]: open file: file
] [00]: sleep: 700 ms
] [00]: close file
] [00]: sleep: 200 ms
] [00]: open dir: 959030623
] [00]: create dir: 838545539
] [00]: create file: 1594243340
] [00]: create file: 162216788
] [00]: create file: 1841205112
] [00]: sleep: 700 ms
] [00]: remove dir: 838545539
] [00]: create dir: 1143741253
] [00]: remove file: 1594243340
] [00]: sleep: 400 ms
] [00]: close dir
] [00]: sleep: 400 ms
] [00]: create file: 832100416
] [00]: open dir: 1903590565
] [00]: create file: 1401208270
] [00]: create dir: 2032315143
] [00]: create dir: 2026989069
] [00]: close dir
] [00]: open dir: 1804928587
] [00]: close dir
] [00]: open dir: 1804928587
] [00]: sleep: 100 ms
] [00]: create dir: 650320721
] [00]: sleep: 500 ms
] [00]: create dir: 1857409239
] [00]: create file: 2060801678
] [00]: create dir: 490998763
] [00]: create dir: 644928527
] [00]: create file: 1758287264
] [00]: close dir
] [00]: sleep: 500 ms
] [00]: open file: file
] [00]: read from file
] [00]: read from file
] [00]: read from file
] [00]: sleep: 700 ms
] [00]: read from file
] [00]: sleep: 200 ms
] [00]: sleep: 300 ms
] [00]: read from file
] [00]: append to file
] [00]: read from file
] [00]: close file
] [00]: remove dir: 1804928587
] [00]: create file: 2073894790
] [00]: sleep: 900 ms
] [00]: create dir: 472737403
] [00]: remove dir: 1903590565
] [00]: create dir: 689486081
] [00]: create dir: 373636079
] [00]: remove file: file
] [00]: create dir: 1506727461
] [00]: open dir: 959030623
] [00]: create dir: 1029668001
] [00]: remove file: 162216788
] [00]: close dir
] [00]: sleep: 400 ms
] [00]: open dir: 959030623
] [00]: remove file: 1841205112
] [00]: create file: 378843792
] [00]: sleep: 1000 ms
] [00]: create file: 1871951748
] [00]: open dir: 1143741253
] [00]: create file: 855915646
] [00]: sleep: 700 ms
] [00]: create file: 2008730550
] [00]: close dir
] [00]: create file: 1327563176
] [00]: remove dir: 1143741253
] [00]: create file: 1384249492
] [00]: close dir
] [00]: remove dir: 959030623
] [00]: open file: 832100416
] [00]: read from file
] [00]: sleep: 400 ms
] [00]: close file
] [00]: open file: 832100416
] [00]: close file
] [00]: create dir: 2025552021
] [00]: remove dir: 959030623
] [00]: create dir: 1178339711
] [00]: remove file: 832100416
] [00]: remove file: 2073894790
] [00]: open dir: 1506727461
] [00]: sleep: 700 ms
] [00]: close dir
] [00]: sleep: 300 ms
] [00]: open dir: 1506727461
] [00]: create dir: 1625748679
] [00]: close dir
] [00]: remove dir: 689486081
] [00]: remove dir: 959030623
] [00]: open dir: 959030623
] [00]: sleep: 1000 ms
] [00]: close dir
] [00]: sleep: 100 ms
] [00]: open dir: 959030623
] [00]: create dir: 1128727833
] [00]: create file: 1203746106
] [00]: open dir: 1128727833
] [00]: create dir: 58221889
] [00]: create file: 611814452
] [00]: create dir: 420219909
] [00]: close dir
] [00]: remove file: 1384249492
] [00]: close dir
] [00]: open dir: 959030623
] [00]: close dir
] [00]: sleep: 800 ms
] [00]: remove dir: 1506727461
] [00]: create file: 550820384
] [00]: create file: 833699694
] [00]: remove dir: 1506727461
] [00]: create dir: 861614531
] [00]: remove dir: 1506727461
] [00]: create dir: 846638047
] [00]: remove dir: 1506727461
] [00]: create dir: 1766470371
] [00]: remove dir: 959030623
] [00]: create file: 1969337196
] [00]: remove dir: 1506727461
] [00]: create file: 1937189238
] [00]: remove dir: 959030623
] [00]: create dir: 1615521955
] [00]: remove dir: 959030623
] [00]: sleep: 500 ms
] [00]: open dir: 1506727461
] [00]: create file: 926653184
] [00]: remove dir: 1625748679
] [00]: close dir
] [00]: create dir: 975747319
] [00]: open dir: 959030623
] [00]: open file: 1871951748
] [00]: sleep: 600 ms
] [00]: close file
] [00]: sleep: 800 ms
] [00]: remove file: 378843792
] [00]: open file: 1203746106
] [00]: read from file
] [00]: append to file
] [00]: sleep: 600 ms
] [00]: close file
] [00]: open file: 1871951748
] [00]: append to file
] [00]: close file
] [00]: remove file: 1871951748
] [00]: sleep: 400 ms
] [00]: create file: 1835979858
] [00]: open dir: 1128727833
] [00]: create dir: 862728993
] [00]: sleep: 700 ms
] [00]: remove dir: 862728993
] [00]: close dir
] [00]: close dir
] [00]: open dir: 975747319
] [00]: sleep: 400 ms
] [00]: create dir: 1840098529
] [00]: create file: 231405798
] [00]: remove dir: 1840098529
] [00]: close dir
] [00]: remove file: 550820384
] [00]: create file: 1020303340
] [00]: create dir: 895068705
] [00]: open dir: 975747319
] [00]: close dir
] [00]: open file: 1020303340
] [00]: read from file
] [00]: close file
] [00]: create dir: 1993404773
] [00]: remove file: 1020303340
] [00]: create dir: 2112395285
] [00]: create dir: 81202983
] [00]: remove dir: 975747319
] [00]: remove dir: 959030623
] [00]: create dir: 467069459
] [00]: open dir: 1506727461
] [00]: create file: 612609068
] [00]: create dir: 1793174441
] [00]: create file: 710808000
] [00]: sleep: 300 ms
] [00]: create file: 897370450
] [00]: create file: 1901152134
] [00]: sleep: 200 ms
] [00]: close dir
] [00]: open dir: 1506727461
] [00]: close dir
] [00]: sleep: 800 ms
] [00]: create file: 906254788
] [00]: remove dir: 467069459
] [00]: remove dir: 1506727461
] [00]: open dir: 895068705
] [00]: close dir
] [00]: create file: 1216543556
] [00]: create file: 37176518
] [00]: open dir: 2112395285
] [00]: close dir
] [00]: create dir: 659416143
] [00]: remove file: 37176518
] [00]: sleep: 500 ms
] [00]: remove dir: 895068705
] [00]: create dir: 1507289677
] [00]: open dir: 959030623
] [00]: create file: 1766335976
] [00]: close dir
] [00]: sleep: 400 ms
] [00]: open dir: 2112395285
] [00]: create dir: 1476817327
] [00]: close dir
] [00]: create dir: 1841667551
] [00]: remove dir: 2112395285
] [00]: open dir: 1507289677
] [00]: close dir
] [00]: create file: 1610238546
] [00]: create file: 220151308
] [00]: remove dir: 1507289677
] [00]: open file: 1216543556
] [00]: append to file
] [00]: append to file
] [00]: close file
] [00]: remove file: 1216543556
] [00]: open dir: 959030623
] [00]: sleep: 500 ms
] *** TIMEOUT TIMEOUT
[101/591] p:86  f:6   u:1   e:0   I:0   B:3   t:1   i:0   W:0   | riscv32/rv32i: getentropy01.exe
 <<skipping passes>>
[109/591] p:94  f:6   u:1   e:0   I:0   B:3   t:1   i:0   W:0   | riscv32/rv32i: malloctest.exe
Result: timeout    Time: 0:03:00.538910 malloctest.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/libtests/malloctest.exe
] 
] 
=>  test start: MALLOCTEST
] *** BEGIN OF TEST MALLOCTEST ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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: 0x80046fa0 != realloc(0x80047040,45)
] realloc - failed non-in place: realloc(0x80047040,64) = 0x80047040
] 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
] *** TIMEOUT TIMEOUT
[110/591] p:94  f:6   u:1   e:0   I:0   B:3   t:2   i:0   W:0   | riscv32/rv32i: math.exe
 <<skipping passes>>
[195/591] p:174 f:6   u:4   e:0   I:0   B:3   t:5   i:0   W:0   | riscv32/rv32i: psxfenv01.exe
Result: failed     Time: 0:00:01.018891 psxfenv01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/psxtests/psxfenv01.exe
] 
] 
=>  test start: PSXFENV 01
] *** BEGIN OF TEST PSXFENV 01 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] fesetenv(FE_DFL_ENV).
] feclearexcept(FE_ALL_EXCEPT).
] Divide by zero and confirm fetestexcept()
] ../../../../../../rtems/c/src/../../testsuites/psxtests/psxfenv01/init.c: 99 fetestexcept( FE_DIVBYZERO )
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[196/591] p:174 f:6   u:4   e:0   I:0   B:3   t:5   i:0   W:0   | riscv32/rv32i: psxfile01.exe
 <<skipping passes>>
[206/591] p:183 f:7   u:4   e:0   I:0   B:3   t:5   i:0   W:0   | riscv32/rv32i: psxintrcritical01.exe
Result: timeout    Time: 0:03:00.435992 psxintrcritical01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/psxtests/psxintrcritical01.exe
] 
] 
=>  test start: PSXINTRCRITICAL 1
] *** BEGIN OF TEST PSXINTRCRITICAL 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate timer fire from ISR while firing
] Init - Variation is: POSIX Timer
] Init - There is no way for the test to know if it hits the case
] *** TIMEOUT TIMEOUT
[207/591] p:184 f:7   u:4   e:0   I:0   B:3   t:5   i:0   W:0   | riscv32/rv32i: psxinttypes01.exe
 <<skipping passes>>
[238/591] p:215 f:7   u:4   e:0   I:0   B:3   t:5   i:0   W:0   | riscv32/rv32i: psxsignal01.exe
Result: timeout    Time: 0:03:00.619899 psxsignal01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/psxtests/psxsignal01.exe
] 
] 
=>  test start: PSXSIGNAL 1
] *** BEGIN OF TEST PSXSIGNAL 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init's ID is 0x0b010001
] Validate signal with SIG_DFL
] Validate signal with SIG_IGN
] Init: Unblock SIGUSR1 SIGFPE SIGILL SIGSEGV
] Init: signal return value verified
] Init: send SIGUSR1 to process
] Signal: 25 caught by 0xb010001 (1)
] Init: send SIGFPE to process
] Handler_1: Signal: 8 caught by 0xb010001 (2)
] Init: send SIGILL to process
] Handler_1: Signal: 4 caught by 0xb010001 (3)
] Init: send SIGSEGV to process
] Handler_1: Signal: 11 caught by 0xb010001 (4)
] *** TIMEOUT TIMEOUT
[239/591] p:215 f:7   u:4   e:0   I:0   B:3   t:6   i:0   W:0   | riscv32/rv32i: psxsignal02.exe
 <<skipping passes>>
[248/591] p:224 f:7   u:4   e:0   I:0   B:3   t:6   i:0   W:0   | riscv32/rv32i: psxstack02.exe
error: spike.cfg:58: execute failed: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/rhealstone/rhtaskpreempt.exe: exit-code:6
error: spike.cfg:58: execute failed: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/rhealstone/rhtaskswitch.exe: exit-code:6
[249/591] p:225 f:7   u:4   e:0   I:0   B:3   t:6   i:0   W:0   | riscv32/rv32i: psxstat.exe
Result: timeout    Time: 0:03:00.591530 psxstat.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/psxtests/psxstat.exe
] 
] 
=>  test start: PSXSTAT
] *** BEGIN OF TEST PSXSTAT ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] --->Current Time:  - rtems_clock_get_tod - 09:00:00   12/31/1988
] Making directory /my_mount_point/dir1\\//
] Making directory /my_mount_point/dir2
] Making directory /my_mount_point/dir3
] Making directory /my_mount_point/dir4
] Making directory /my_mount_point/dir1/dir1
] Making directory /./././my_mount_point/dir1/ dir1
] Making directory /./././my_mount_point/links
] Making directory ///my_mount_point/dir1/dir1/../../dir1/../symlinks/////
] 
] Making file /////my_mount_point/dir1/\//file1
] Making file /my_mount_point/dir1/file2
] Making file /my_mount_point/dir1/file3
] Making file /my_mount_point/dir1/file4
] Making file /my_mount_point/dir1/dir1/file1
] Making file ../../..//my_mount_point/dir1/./././dir1/ file1
] Making file main.c
] 
] Making file dir1/dir1/../../links/dir1
] Making file links/dir2
] Making file links/dir3
] Making file links/dir4
] Making file links/dir1_dir1
] Making file links/dir1_ dir1
] Making file links/../links/../links/links
] 
] Making file links/dir1_file1
] Making file links/dir1_file2
] Making file links/dir1_file3
] Making file links/dir1_file4
] Making file links/dir1_dir1_f1
] Making file links/dir1_dir1 f1
] 
] Making file links/links/links/links_dir1
] Making file links//links_dir2
] Making file links//links_dir3
] Making file links//links_dir4
] Making file links//links_dir1_d1
] Making file links//links_dir1 d1
] Making file links//links_links
] 
] Making file links///links_d1_file1
] Making file links///links_d1_file2
] Making file links///links_d1_file3
] Making file links///links_d1_file4
] Making file links///links_d1_d1_f1
] Making file links///links_r1_d1 f1
] 
] Doing the stat() on all the good absolute paths
] stat( /my_mount_point/dev ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e670
] ...st_mode    40700
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:00 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( ////my_mount_point/dir1/\//file1\\// ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e9d0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( /my_mount_point/dir1/\\/file2 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ea40
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( /my_mount_point/dir1/file3/////\\\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eab0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( /my_mount_point/dir1/file4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb20
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( /my_mount_point/dir1/dir1/file1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb90
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( /my_mount_point/dir1/dir1/ file1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ec00
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( /my_mount_point/dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1680
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/dir2//////\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e730
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/dir3 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e790
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/dir4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e7f0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/dir1/dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e850
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    560
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/dir1/ dir1///\\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e8b0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/\/\/\/\/\/\/links\/\/\/\/\/\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e910
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    7280
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] 
] Doing the stat() on all the good relative paths
] stat( dev ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e670
] ...st_mode    40700
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:00 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( dir1/\//file1\\// ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e9d0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( dir1/\\/file2 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ea40
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( dir1/file3/////\\\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eab0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( dir1/file4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb20
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( dir1/dir1/file1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb90
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( dir1/dir1/ file1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ec00
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1680
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( dir2//////\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e730
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( dir3 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e790
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( dir4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e7f0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( dir1/dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e850
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    560
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( dir1/ dir1///\\ ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e8b0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( main.c ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ec70
] ...st_mode    100004
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:00 1988
] ...st_blksize 80
] ...st_blocks  0
] 
] chdir to dev
] 
] stat relative paths that are now bad
] stat( dev ) returned : No such file or directory
] stat( dir1/\//file1\\// ) returned : No such file or directory
] stat( dir1/\\/file2 ) returned : No such file or directory
] stat( dir1/file3/////\\\ ) returned : No such file or directory
] stat( dir1/file4 ) returned : No such file or directory
] stat( dir1/dir1/file1 ) returned : No such file or directory
] stat( dir1/dir1/ file1 ) returned : No such file or directory
] stat( dir1 ) returned : No such file or directory
] stat( dir2//////\ ) returned : No such file or directory
] stat( dir3 ) returned : No such file or directory
] stat( dir4 ) returned : No such file or directory
] stat( dir1/dir1 ) returned : No such file or directory
] stat( dir1/ dir1///\\ ) returned : No such file or directory
] stat( main.c ) returned : No such file or directory
] 
] chdir to ../links
] Doing the stat() on good links
] 
] stat( dir1/dir1/../../links/dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1680
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/dir2 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e730
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/dir3 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e790
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/dir4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e7f0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/dir1_dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e850
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    560
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/dir1_ dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e8b0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/../links/../links/links ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e910
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    7280
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links/dir1_file1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e9d0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links/dir1_file2 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ea40
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links/dir1_file3 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eab0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links/dir1_file4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb20
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links/dir1_dir1_f1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb90
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links/dir1_dir1 f1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ec00
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links/links/links/links_dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1680
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_dir2 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e730
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_dir3 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e790
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_dir4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e7f0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_dir1_d1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e850
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    560
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_dir1 d1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e8b0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_links ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e910
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    7280
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links///links_d1_file1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e9d0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links///links_d1_file2 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ea40
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links///links_d1_file3 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eab0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links///links_d1_file4 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb20
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links///links_d1_d1_f1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003eb90
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] stat( links///links_r1_d1 f1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003ec00
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] chmod of /my_mount_point/dir1/dir1 to Read/Write
] 
] Doing the stat() on all the bad paths
] stat( /my_mount_point/links/ENAMETOOLONG____________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________ ) returned : No such file or directory
] stat( /my_mount_point/dir1/file4/NOTADIR ) returned : Not a directory
] stat( /my_mount_point/dir1/dir1/EACCES__ ) returned : Permission denied
] Making file /my_mount_point/links/ENAMETOOLONG____________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________________ : File or path name too long
] Making file /my_mount_point/dir1/file4/NOTADIR : Not a directory
] Making file /my_mount_point/dir1/dir1/EACCES__ : Permission denied
] 
] Return /my_mount_point/dir1/dir1 to RWX
] Making file /my_mount_point/symlinks/a_file_symlink
] Verify with readlink
] Making file /my_mount_point/symlinks/a_dir_symlink
] Verify with readlink
] Making file /my_mount_point/symlinks/a_link_symlink
] Verify with readlink
] Making file ../symlinks/no_file
] Verify with readlink
] Making file /my_mount_point/symlinks/a_dir_symlink/a_file_symlink
] Verify with readlink
] Making file /my_mount_point/symlinks/links
] Verify with readlink
] stat( /my_mount_point/symlinks/a_file_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e9d0
] ...st_mode    100004
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:09 1988
] ...st_blksize 80
] ...st_blocks  0
] lstat( /my_mount_point/symlinks/a_file_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f6a0
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    33
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/symlinks/a_dir_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1960
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] lstat( /my_mount_point/symlinks/a_dir_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f720
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    24
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/symlinks/a_link_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1960
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] lstat( /my_mount_point/symlinks/a_link_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f7a0
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    28
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( ../symlinks/no_file ) returned : No such file or directory
] lstat( ../symlinks/no_file ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f820
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    7
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( /my_mount_point/symlinks/a_dir_symlink/a_file_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e6d0
] ...st_mode    40700
] ...st_nlink   3
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    1960
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] lstat( /my_mount_point/symlinks/a_dir_symlink/a_file_symlink ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f880
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    38
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] Making file 0001
] Verify with readlink
] Making file 0002
] Verify with readlink
] Making file 0003
] Verify with readlink
] Making file 0004
] Verify with readlink
] Making file 0005
] Verify with readlink
] Making file 0006
] Verify with readlink
] Making file 0007
] Verify with readlink
] Making file 0008
] Verify with readlink
] Making file 0009
] Verify with readlink
] stat( 0001 ) returned : No such file or directory
] lstat( 0001 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f980
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    9
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0002 ) returned : No such file or directory
] lstat( 0002 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003f9e0
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0003 ) returned : No such file or directory
] lstat( 0003 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003fa40
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0004 ) returned : No such file or directory
] lstat( 0004 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003faa0
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0005 ) returned : No such file or directory
] lstat( 0005 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003fb00
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0006 ) returned : No such file or directory
] lstat( 0006 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003fb60
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0007 ) returned : No such file or directory
] lstat( 0007 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003fbc0
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0008 ) returned : No such file or directory
] lstat( 0008 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003fc20
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( 0009 ) returned : No such file or directory
] lstat( 0009 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003fc80
] ...st_mode    120777
] ...st_nlink   1
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    4
] ...st_atime   Sat Dec 31 09:00:15 1988
] ...st_mtime   Sat Dec 31 09:00:15 1988
] ...st_ctime   Sat Dec 31 09:00:15 1988
] ...st_blksize 0
] ...st_blocks  0
] chdir to a file should fail with ENOTDIR
] Verify RWX permission on /my_mount_point/dir1\\// via access
] chmod of /my_mount_point/dir1\\// to Read/Write
] chmod fred should fail with ENOENT
] Create under /my_mount_point/dir1\\// should fail with EACCES
] chdir to /my_mount_point/dir1/dir1 should fail with EACCES
] mkdir /my_mount_point/dir1\\///BADNAME should fail with EACCESS
] 
] /my_mount_point/dir1\\// Should exist ( access )
] 
] /my_mount_point/dir1\\// Should have read  permission( access )
] 
] /my_mount_point/dir1\\// Should have write permission( access )
] 
] /my_mount_point/dir1\\// Should not have execute permission( access )
] 
] Restore /my_mount_point/dir1\\// to RWX
] chdir to /my_mount_point 
] Remove /./././my_mount_point/dir1/ dir1
] stat( /./././my_mount_point/dir1/ dir1 ) returned : No such file or directory
] stat( links/dir1_ dir1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e8b0
] ...st_mode    40700
] ...st_nlink   2
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] stat( links//links_dir1 d1 ) returned 
] ...st_dev     (0x80000000:0x4001f2d8)
] ...st_ino     8003e8b0
] ...st_mode    40700
] ...st_nlink   2
] ...st_uid     0
] ...st_gid     0
] ...st_rdev    (0x0:0x0)
] ...st_size    0
] ...st_atime   Sat Dec 31 09:00:00 1988
] ...st_mtime   Sat Dec 31 09:00:00 1988
] ...st_ctime   Sat Dec 31 09:00:04 1988
] ...st_blksize 0
] ...st_blocks  0
] Chdir to links/dir1_ dir1
] Chdir to .. should fail with ENOENT
] mkdir ../t should fail with ENOENT
] mkdir t
] chdir to /my_mount_point
] rmdir links/dir1_ dir1
] unlink links//links_dir1 d1 should fail with ENOTEMPTY
] rmdir links//links_dir1 d1/t
] unlink links//links_dir1 d1
] Attempting to mount IMFS file system at /dir1/my_mount_point 
] rmdir /dir1/my_mount_point should fail with EBUSY
] Unmount /my_mount_point/dir1/my_mount_point
] chmod of /////my_mount_point/dir1/\//file1 to group and other execute
] Open /////my_mount_point/dir1/\//file1 for write should fail with EACCES
] chmod of /my_mount_point/dir4 to User Execute and Read
] Mkdir of /my_mount_point/dir4/NewFile should fail with EACCES
] Making too many hard links.
] Making file /LinkName1
] Making file /LinkName2
] Making file /LinkName3
] Making file /LinkName4
] Making file /LinkName5
] Making file /LinkName6
] Making file /LinkName7
] Making file /LinkName8
] chdir to /my_mount_point 
] *** TIMEOUT TIMEOUT
[250/591] p:226 f:7   u:4   e:0   I:0   B:3   t:6   i:0   W:0   | riscv32/rv32i: psxstrsignal01.exe
 <<skipping passes>>
[253/591] p:229 f:7   u:4   e:0   I:0   B:3   t:6   i:0   W:0   | riscv32/rv32i: psxtime.exe
Result: timeout    Time: 0:03:00.438331 psxtime.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/psxtests/psxtime.exe
] 
] 
=>  test start: PSXTIME
] *** BEGIN OF TEST PSXTIME ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] gettimeofday( NULL, NULL ) - EFAULT
] rtems_clock_set          12:45:00   01/01/1988
] adjtime - NULL delta - EINVAL
] adjtime - delta out of range - EINVAL
] adjtime - delta range of 0 - OK
] adjtime - delta too small - do nothing
] adjtime - delta too small - do nothing, olddelta=NULL
] adjtime - delta of one second forward, olddelta=NULL
] adjtime - delta of one second forward
] adjtime - delta of almost two seconds forward
] adjtime - delta of almost one second forward which bumps second
] rtems_clock_get_tod          12:45:05   01/01/1988
] rtems_clock_set          12:45:00   01/01/1988
] rtems_clock_get_tod          12:45:00   01/01/1988
] gettimeofday: Fri Jan  1 12:45:00 1988
] time:         Fri Jan  1 12:45:00 1988
] localtime:    Fri Jan  1 12:45:00 1988
] gmtime:       Fri Jan  1 12:45:00 1988
] 
] rtems_clock_get_tod          12:45:00   01/01/1988
] gettimeofday: Fri Jan  1 12:45:00 1988
] time:         Fri Jan  1 12:45:00 1988
] localtime:    Fri Jan  1 12:45:00 1988
] gmtime:       Fri Jan  1 12:45:00 1988
] 
] rtems_clock_get_tod          12:45:01   01/01/1988
] gettimeofday: Fri Jan  1 12:45:01 1988
] time:         Fri Jan  1 12:45:01 1988
] localtime:    Fri Jan  1 12:45:01 1988
] gmtime:       Fri Jan  1 12:45:01 1988
] 
] rtems_clock_get_tod          12:45:02   01/01/1988
] gettimeofday: Fri Jan  1 12:45:02 1988
] time:         Fri Jan  1 12:45:02 1988
] localtime:    Fri Jan  1 12:45:02 1988
] gmtime:       Fri Jan  1 12:45:02 1988
] 
] rtems_clock_set          09:00:00   12/31/1988
] rtems_clock_get_tod          09:00:00   12/31/1988
] gettimeofday: Sat Dec 31 09:00:00 1988
] time:         Sat Dec 31 09:00:00 1988
] localtime:    Sat Dec 31 09:00:00 1988
] gmtime:       Sat Dec 31 09:00:00 1988
] 
] rtems_clock_get_tod          09:00:00   12/31/1988
] gettimeofday: Sat Dec 31 09:00:01 1988
] time:         Sat Dec 31 09:00:01 1988
] localtime:    Sat Dec 31 09:00:01 1988
] gmtime:       Sat Dec 31 09:00:01 1988
] 
] rtems_clock_get_tod          09:00:01   12/31/1988
] gettimeofday: Sat Dec 31 09:00:02 1988
] time:         Sat Dec 31 09:00:02 1988
] localtime:    Sat Dec 31 09:00:02 1988
] gmtime:       Sat Dec 31 09:00:02 1988
] 
] rtems_clock_get_tod          09:00:02   12/31/1988
] gettimeofday: Sat Dec 31 09:00:03 1988
] time:         Sat Dec 31 09:00:03 1988
] localtime:    Sat Dec 31 09:00:03 1988
] gmtime:       Sat Dec 31 09:00:03 1988
] 
] rtems_clock_set          23:55:59   12/31/1999
] rtems_clock_get_tod          23:55:59   12/31/1999
] gettimeofday: Fri Dec 31 23:55:59 1999
] time:         Fri Dec 31 23:55:59 1999
] localtime:    Fri Dec 31 23:55:59 1999
] gmtime:       Fri Dec 31 23:55:59 1999
] 
] rtems_clock_get_tod          23:55:59   12/31/1999
] gettimeofday: Fri Dec 31 23:56:00 1999
] time:         Fri Dec 31 23:56:00 1999
] localtime:    Fri Dec 31 23:56:00 1999
] gmtime:       Fri Dec 31 23:56:00 1999
] 
] rtems_clock_get_tod          23:56:00   12/31/1999
] gettimeofday: Fri Dec 31 23:56:01 1999
] time:         Fri Dec 31 23:56:01 1999
] localtime:    Fri Dec 31 23:56:01 1999
] gmtime:       Fri Dec 31 23:56:01 1999
] 
] rtems_clock_get_tod          23:56:01   12/31/1999
] gettimeofday: Fri Dec 31 23:56:02 1999
] time:         Fri Dec 31 23:56:02 1999
] localtime:    Fri Dec 31 23:56:02 1999
] gmtime:       Fri Dec 31 23:56:02 1999
] 
] rtems_clock_set          00:01:30   06/30/1999
] rtems_clock_get_tod          00:01:30   06/30/1999
] gettimeofday: Wed Jun 30 00:01:30 1999
] time:         Wed Jun 30 00:01:30 1999
] localtime:    Wed Jun 30 00:01:30 1999
] gmtime:       Wed Jun 30 00:01:30 1999
] 
] rtems_clock_get_tod          00:01:30   06/30/1999
] gettimeofday: Wed Jun 30 00:01:31 1999
] time:         Wed Jun 30 00:01:31 1999
] localtime:    Wed Jun 30 00:01:31 1999
] gmtime:       Wed Jun 30 00:01:31 1999
] 
] rtems_clock_get_tod          00:01:31   06/30/1999
] gettimeofday: Wed Jun 30 00:01:32 1999
] time:         Wed Jun 30 00:01:32 1999
] localtime:    Wed Jun 30 00:01:32 1999
] gmtime:       Wed Jun 30 00:01:32 1999
] 
] rtems_clock_get_tod          00:01:32   06/30/1999
] gettimeofday: Wed Jun 30 00:01:33 1999
] time:         Wed Jun 30 00:01:33 1999
] localtime:    Wed Jun 30 00:01:33 1999
] gmtime:       Wed Jun 30 00:01:33 1999
] 
] rtems_clock_set          00:15:59   01/01/2000
] rtems_clock_get_tod          00:15:59   01/01/2000
] gettimeofday: Sat Jan  1 00:15:59 2000
] time:         Sat Jan  1 00:15:59 2000
] localtime:    Sat Jan  1 00:15:59 2000
] gmtime:       Sat Jan  1 00:15:59 2000
] 
] rtems_clock_get_tod          00:15:59   01/01/2000
] gettimeofday: Sat Jan  1 00:16:00 2000
] time:         Sat Jan  1 00:16:00 2000
] localtime:    Sat Jan  1 00:16:00 2000
] gmtime:       Sat Jan  1 00:16:00 2000
] 
] rtems_clock_get_tod          00:16:00   01/01/2000
] gettimeofday: Sat Jan  1 00:16:01 2000
] time:         Sat Jan  1 00:16:01 2000
] localtime:    Sat Jan  1 00:16:01 2000
] gmtime:       Sat Jan  1 00:16:01 2000
] 
] rtems_clock_get_tod          00:16:01   01/01/2000
] gettimeofday: Sat Jan  1 00:16:02 2000
] time:         Sat Jan  1 00:16:02 2000
] localtime:    Sat Jan  1 00:16:02 2000
] gmtime:       Sat Jan  1 00:16:02 2000
] 
] rtems_clock_set          05:10:59   02/02/2005
] rtems_clock_get_tod          05:10:59   02/02/2005
] gettimeofday: Wed Feb  2 05:10:59 2005
] time:         Wed Feb  2 05:10:59 2005
] localtime:    Wed Feb  2 05:10:59 2005
] gmtime:       Wed Feb  2 05:10:59 2005
] 
] rtems_clock_get_tod          05:10:59   02/02/2005
] gettimeofday: Wed Feb  2 05:11:00 2005
] time:         Wed Feb  2 05:11:00 2005
] localtime:    Wed Feb  2 05:11:00 2005
] gmtime:       Wed Feb  2 05:11:00 2005
] 
] rtems_clock_get_tod          05:11:00   02/02/2005
] gettimeofday: Wed Feb  2 05:11:01 2005
] time:         Wed Feb  2 05:11:01 2005
] localtime:    Wed Feb  2 05:11:01 2005
] gmtime:       Wed Feb  2 05:11:01 2005
] 
] rtems_clock_get_tod          05:11:01   02/02/2005
] gettimeofday: Wed Feb  2 05:11:02 2005
] time:         Wed Feb  2 05:11:02 2005
] localtime:    Wed Feb  2 05:11:02 2005
] gmtime:       Wed Feb  2 05:11:02 2005
] 
] rtems_clock_set          10:05:59   03/03/2010
] rtems_clock_get_tod          10:05:59   03/03/2010
] gettimeofday: Wed Mar  3 10:05:59 2010
] time:         Wed Mar  3 10:05:59 2010
] localtime:    Wed Mar  3 10:05:59 2010
] gmtime:       Wed Mar  3 10:05:59 2010
] 
] rtems_clock_get_tod          10:05:59   03/03/2010
] gettimeofday: Wed Mar  3 10:06:00 2010
] time:         Wed Mar  3 10:06:00 2010
] localtime:    Wed Mar  3 10:06:00 2010
] gmtime:       Wed Mar  3 10:06:00 2010
] 
] *** TIMEOUT TIMEOUT
[254/591] p:230 f:7   u:4   e:0   I:0   B:3   t:6   i:0   W:0   | riscv32/rv32i: psxtimer01.exe
Result: timeout    Time: 0:03:00.472217 psxtimer01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/psxtests/psxtimer01.exe
] 
] 
=>  test start: PSXTIMER 1
] *** BEGIN OF TEST PSXTIMER 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[255/591] p:230 f:7   u:4   e:0   I:0   B:3   t:7   i:0   W:0   | riscv32/rv32i: psxtimer02.exe
 <<skipping passes>>
[319/591] p:294 f:7   u:4   e:0   I:0   B:3   t:7   i:0   W:0   | riscv32/rv32i: rhtaskpreempt.exe
Result: invalid    Time: 0:00:01.009222 rhtaskpreempt.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/rhealstone/rhtaskpreempt.exe
] *** FAILED *** (tohost = 6)
[320/591] p:294 f:7   u:4   e:0   I:0   B:3   t:7   i:1   W:0   | riscv32/rv32i: rhtaskswitch.exe
Result: invalid    Time: 0:00:01.009956 rhtaskswitch.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/rhealstone/rhtaskswitch.exe
] *** FAILED *** (tohost = 6)
[321/591] p:294 f:7   u:4   e:0   I:0   B:3   t:7   i:2   W:0   | riscv32/rv32i: base_sp.exe
 <<skipping passes>>
[330/591] p:298 f:7   u:6   e:0   I:0   B:3   t:10  i:2   W:0   | riscv32/rv32i: ticker.exe
Result: timeout    Time: 0:03:00.407913 ticker.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/samples/ticker.exe
] 
] 
=>  test start: CLOCK TICK
] *** BEGIN OF TEST CLOCK TICK ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[331/591] p:299 f:7   u:6   e:0   I:0   B:3   t:10  i:2   W:0   | riscv32/rv32i: unlimited.exe
[332/591] p:300 f:7   u:6   e:0   I:0   B:3   t:10  i:2   W:0   | riscv32/rv32i: sp01.exe
Result: timeout    Time: 0:03:00.458540 sp01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp01.exe
] 
] 
=>  test start: SP 1
] *** BEGIN OF TEST SP 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[333/591] p:301 f:7   u:6   e:0   I:0   B:3   t:10  i:2   W:0   | riscv32/rv32i: sp02.exe
 <<skipping passes>>
[335/591] p:303 f:7   u:6   e:0   I:0   B:3   t:10  i:2   W:0   | riscv32/rv32i: sp04.exe
Result: timeout    Time: 0:03:00.414147 sp04.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp04.exe
] 
] 
=>  test start: SP 4
] *** BEGIN OF TEST SP 4 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[336/591] p:303 f:7   u:6   e:0   I:0   B:3   t:11  i:2   W:0   | riscv32/rv32i: sp05.exe
Result: timeout    Time: 0:03:00.326127 sp05.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp05.exe
] 
] 
=>  test start: SP 5
] *** BEGIN OF TEST SP 5 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA2 - rtems_task_suspend - suspend self
] TA3 - rtems_task_suspend - suspend self
] TA1 - rtems_task_suspend - suspend TA3
] TA1 - rtems_task_resume - resume TA2
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA1 - rtems_task_suspend - suspend TA2
] TA1 - rtems_task_resume - resume TA3
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA1 - rtems_task_suspend - suspend TA3
] TA1 - rtems_task_resume - resume TA2
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA1 - rtems_task_suspend - suspend TA2
] TA1 - rtems_task_resume - resume TA3
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA3 - rtems_task_wake_after - sleep 1 second
] TA1 - rtems_task_suspend - suspend TA3
] TA1 - rtems_task_resume - resume TA2
] TA1 - rtems_task_wake_after - sleep 5 seconds
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] TA2 - rtems_task_wake_after - sleep 1 second
] *** TIMEOUT TIMEOUT
[337/591] p:303 f:7   u:6   e:0   I:0   B:3   t:12  i:2   W:0   | riscv32/rv32i: sp06.exe
 <<skipping passes>>
[341/591] p:306 f:7   u:6   e:0   I:0   B:3   t:13  i:2   W:0   | riscv32/rv32i: sp12.exe
Result: timeout    Time: 0:03:00.378221 sp12.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp12.exe
] 
] 
=>  test start: SP 12
] *** BEGIN OF TEST SP 12 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[342/591] p:307 f:7   u:6   e:0   I:0   B:3   t:13  i:2   W:0   | riscv32/rv32i: sp13.exe
 <<skipping passes>>
[348/591] p:313 f:7   u:6   e:0   I:0   B:3   t:13  i:2   W:0   | riscv32/rv32i: sp19.exe
Result: timeout    Time: 0:03:00.347260 sp19.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp19.exe
] 
] 
=>  test start: SP 19
] *** BEGIN OF TEST SP 19 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] FP1  - integer base = (0x6000)
] FP1  - float base = (NA)
] FP1  - integer base = (0x6000)
] FP1  - float base = (NA)
] TA1  - integer base = (0x1000)
] TA1  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA2  - integer base = (0x2000)
] TA2  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA3  - integer base = (0x3000)
] TA3  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA4  - integer base = (0x4000)
] TA4  - float base = (4000.4)
] TA4  - rtems_clock_get_tod - 09:00:00   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:01   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:02   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:03   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:04   12/31/1988
] TA5  - integer base = (0x5000)
] TA5  - float base = (5000.5)
] TA5  - rtems_clock_get_tod - 09:00:04   12/31/1988
] TA1  - rtems_clock_get_tod - 09:00:04   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:05   12/31/1988
] TA5  - rtems_clock_get_tod - 09:00:05   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:06   12/31/1988
] TA5  - rtems_clock_get_tod - 09:00:06   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:07   12/31/1988
] TA5  - rtems_clock_get_tod - 09:00:07   12/31/1988
] TA4  - rtems_clock_get_tod - 09:00:08   12/31/1988
] TA5  - rtems_clock_get_tod - 09:00:08   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
] TA4  - rtems_clock_get_tod - 09:00:09   12/31/1988
] TA5  - rtems_clock_get_tod - 09:00:09   12/31/1988
] *** TIMEOUT TIMEOUT
[349/591] p:313 f:7   u:6   e:0   I:0   B:3   t:14  i:2   W:0   | riscv32/rv32i: sp20.exe
Result: timeout    Time: 0:03:00.516796 sp20.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp20.exe
] 
] 
=>  test start: SP 20
] *** BEGIN OF TEST SP 20 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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)
] *** TIMEOUT TIMEOUT
[350/591] p:314 f:7   u:6   e:0   I:0   B:3   t:14  i:2   W:0   | riscv32/rv32i: sp2038.exe
 <<skipping passes>>
[354/591] p:317 f:7   u:6   e:0   I:0   B:3   t:15  i:2   W:0   | riscv32/rv32i: sp24.exe
Result: timeout    Time: 0:03:00.357599 sp24.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp24.exe
] 
] 
=>  test start: SP 24
] *** BEGIN OF TEST SP 24 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[355/591] p:317 f:7   u:6   e:0   I:0   B:3   t:16  i:2   W:0   | riscv32/rv32i: sp25.exe
 <<skipping passes>>
[360/591] p:321 f:7   u:6   e:0   I:0   B:3   t:17  i:2   W:0   | riscv32/rv32i: sp30.exe
Result: timeout    Time: 0:03:00.373754 sp30.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp30.exe
] 
] 
=>  test start: SP 30
] *** BEGIN OF TEST SP 30 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[361/591] p:322 f:7   u:6   e:0   I:0   B:3   t:17  i:2   W:0   | riscv32/rv32i: sp31.exe
error: spike.cfg:58: execute failed: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp42.exe: exit-code:255
[362/591] p:323 f:7   u:6   e:0   I:0   B:3   t:17  i:2   W:0   | riscv32/rv32i: sp32.exe
 <<skipping passes>>
[370/591] p:329 f:7   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: sp42.exe
Result: failed     Time: 0:00:13.067383 sp42.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/sp42.exe
] 
] 
=>  test start: SP 42
] *** BEGIN OF TEST SP 42 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Exercising blocking discipline w/extract in FIFO order 
] Exercising blocking discipline w/unblock in FIFO order
] TA00 - unblocked - OK
] TA01 - unblocked - OK
] TA02 - unblocked - OK
] TA03 - unblocked - OK
] TA04 - unblocked - OK
] TA05 - unblocked - OK
] TA06 - unblocked - OK
] TA07 - unblocked - OK
] TA08 - unblocked - OK
] TA09 - unblocked - OK
] TA10 - unblocked - OK
] TA11 - unblocked - OK
] TA12 - unblocked - OK
] TA13 - unblocked - OK
] TA14 - unblocked - OK
] TA15 - unblocked - OK
] TA16 - unblocked - OK
] TA17 - unblocked - OK
] TA18 - unblocked - OK
] TA19 - unblocked - OK
] Exercising blocking discipline w/extract in priority order 
] Exercising blocking discipline w/unblock in priority order
] TA04 - unblocked - OK
] TA05 - unblocked - OK
] TA06 - unblocked - OK
] TA07 - unblocked - OK
[371/591] p:330 f:7   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: sp43.exe
 <<skipping passes>>
[408/591] p:366 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spcbssched01.exe
Result: timeout    Time: 0:03:00.452269 spcbssched01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spcbssched01.exe
] 
] 
=>  test start: SPCBSSCHED 1
] *** BEGIN OF TEST SPCBSSCHED 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[409/591] p:367 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spcbssched02.exe
[410/591] p:368 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spcbssched03.exe
Result: timeout    Time: 0:03:00.483079 spcbssched03.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spcbssched03.exe
] 
] 
=>  test start: SPCBSSCHED 3
] *** BEGIN OF TEST SPCBSSCHED 3 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] PT1 - rtems_rate_monotonic_create id = 0x42010001
] PT1 - rtems_rate_monotonic_ident id = 0x42010001
] PT1 - (0x42010001) period 30
] PT2 - rtems_rate_monotonic_create id = 0x42010002
] PT2 - rtems_rate_monotonic_ident id = 0x42010002
] PT2 - (0x42010002) period 40
] PT3 - rtems_rate_monotonic_create id = 0x42010003
] PT3 - rtems_rate_monotonic_ident id = 0x42010003
] PT3 - (0x42010003) period 50
] PT4 - rtems_rate_monotonic_create id = 0x42010004
] PT4 - rtems_rate_monotonic_ident id = 0x42010004
] PT4 - (0x42010004) period 70
] AT5 AT6 P1-S ticks:2
] P1-F ticks:12
] P2-S ticks:12
] P2-F ticks:22
] P3-S ticks:22
] P3-F ticks:32
] P1-S ticks:32
] P1-F ticks:42
] P4-S ticks:42
] P4-F ticks:52
] P2-S ticks:52
] P1-S ticks:62
] P1-F ticks:72
] P2-F ticks:72
] P3-S ticks:72
] P3-F ticks:82
] AT6-S ticks:82
] P6-F ticks:87
] Killing task 6
] AT5-S ticks:87
] P1-S ticks:92
] P1-F ticks:102
] P2-S ticks:102
] P2-F ticks:112
] P4-S ticks:112
] P1-S ticks:122
] P1-F ticks:132
] P2-S ticks:132
] P2-F ticks:142
] P3-S ticks:142
] P1-S ticks:152
] P1-F ticks:162
] P3-F ticks:162
] P4-F ticks:162
] P2-S ticks:172
] P1-S ticks:182
] P1-F ticks:192
] P2-F ticks:192
] P3-S ticks:192
] P3-F ticks:202
] P4-S ticks:202
] P1-S ticks:212
] P1-F ticks:222
] P2-S ticks:222
] P2-F ticks:232
] P3-S ticks:232
] P1-S ticks:242
] P1-F ticks:252
] P2-S ticks:252
] P2-F ticks:262
] P3-F ticks:262
] Signal overrun, fixing the task
] P1-S ticks:272
] P1-F ticks:282
] P3-S ticks:282
] P2-S ticks:292
] P1-S ticks:302
] P1-F ticks:312
] P2-F ticks:312
] P3-F ticks:312
] P3-S ticks:322
] P1-S ticks:332
] P1-F ticks:342
] P2-S ticks:342
] P2-F ticks:352
] P3-F ticks:352
] P1-S ticks:362
] P1-F ticks:372
] P2-S ticks:372
] P2-F ticks:382
] P1-S ticks:392
] P1-F ticks:402
] P2-S ticks:412
] P1-S ticks:422
] P1-F ticks:432
] P2-F ticks:432
] P3-S ticks:432
] P1-S ticks:452
] P1-F ticks:462
] P2-S ticks:462
] P2-F ticks:472
] P3-F ticks:472
] P1-S ticks:482
] P1-F ticks:492
] P2-S ticks:492
] P2-F ticks:502
] P1-S ticks:512
] P1-F ticks:522
] P3-S ticks:522
] P2-S ticks:532
] P1-S ticks:542
] P1-F ticks:552
] P2-F ticks:552
] P3-F ticks:552
] P1-S ticks:572
] P1-F ticks:582
] P2-S ticks:582
] P2-F ticks:592
] P1-S ticks:602
] P1-F ticks:612
] P2-S ticks:612
] P2-F ticks:622
] P3-S ticks:622
] P1-S ticks:632
] P1-F ticks:642
] P3-F ticks:642
] P2-S ticks:652
] P1-S ticks:662
] P1-F ticks:672
] P2-F ticks:672
] P1-S ticks:692
] P1-F ticks:702
] P2-S ticks:702
] P2-F ticks:712
] P1-S ticks:722
] *** TIMEOUT TIMEOUT
[411/591] p:369 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spchain.exe
 <<skipping passes>>
[418/591] p:376 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spcontext01.exe
Result: timeout    Time: 0:03:00.447364 spcontext01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spcontext01.exe
] 
] 
=>  test start: SPCONTEXT 1
] *** BEGIN OF TEST SPCONTEXT 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Test configuration N N N... done
] Test configuration N N F... done
] Test configuration N F N... done
] Test configuration N F F... done
] Test configuration F N N... done
] Test configuration F N F... done
] *** TIMEOUT TIMEOUT
] Test configuration F F N... 
] Test configuration F F N... 
[419/591] p:377 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spcoverage.exe
 <<skipping passes>>
[423/591] p:381 f:8   u:6   e:0   I:0   B:3   t:19  i:2   W:0   | riscv32/rv32i: spedfsched01.exe
Result: timeout    Time: 0:03:00.350142 spedfsched01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spedfsched01.exe
] 
] 
=>  test start: SPEDFSCHED 1
] *** BEGIN OF TEST SPEDFSCHED 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[424/591] p:381 f:8   u:6   e:0   I:0   B:3   t:20  i:2   W:0   | riscv32/rv32i: spedfsched02.exe
[425/591] p:381 f:8   u:6   e:0   I:0   B:3   t:21  i:2   W:0   | riscv32/rv32i: spedfsched03.exe
Result: timeout    Time: 0:03:00.381324 spedfsched03.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spedfsched03.exe
] 
] 
=>  test start: SPEDFSCHED 3
] *** BEGIN OF TEST SPEDFSCHED 3 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] PT1 - rtems_rate_monotonic_create id = 0x42010001
] PT1 - rtems_rate_monotonic_ident id = 0x42010001
] PT1 - (0x42010001) period 30
] PT2 - rtems_rate_monotonic_create id = 0x42010002
] PT2 - rtems_rate_monotonic_ident id = 0x42010002
] PT2 - (0x42010002) period 40
] PT3 - rtems_rate_monotonic_create id = 0x42010003
] PT3 - rtems_rate_monotonic_ident id = 0x42010003
] PT3 - (0x42010003) period 50
] PT4 - rtems_rate_monotonic_create id = 0x42010004
] PT4 - rtems_rate_monotonic_ident id = 0x42010004
] PT4 - (0x42010004) period 70
] AT5 AT6 P1-S ticks:2
] P1-F ticks:12
] P2-S ticks:12
] P2-F ticks:22
] P3-S ticks:22
] P1-S ticks:32
] P1-F ticks:42
] P3-F ticks:42
] P4-S ticks:42
] P2-S ticks:52
] P2-F ticks:62
] P1-S ticks:62
] P1-F ticks:72
] P4-F ticks:72
] P3-S ticks:72
] P3-F ticks:82
] AT6-S ticks:82
] P6-F ticks:87
] Killing task 6
] AT5-S ticks:87
] P1-S ticks:92
] P1-F ticks:102
] P2-S ticks:102
] P2-F ticks:112
] P4-S ticks:112
] P1-S ticks:122
] P1-F ticks:132
] P3-S ticks:132
] P3-F ticks:142
] P2-S ticks:142
] P2-F ticks:152
] P4-F ticks:152
] P1-S ticks:152
] P1-F ticks:162
] P2-S ticks:172
] P2-F ticks:182
] P1-S ticks:182
] P1-F ticks:192
] P3-S ticks:192
] P3-F ticks:202
] P4-S ticks:202
] P1-S ticks:212
] P1-F ticks:222
] P4-F ticks:222
] P2-S ticks:222
] P2-F ticks:232
] P3-S ticks:232
] P3-F ticks:242
] P1-S ticks:242
] P1-F ticks:252
] P2-S ticks:252
] P2-F ticks:262
] P4-S ticks:262
] P1-S ticks:272
] P1-F ticks:282
] P4-F ticks:282
] P3-S ticks:282
] P3-F ticks:292
] P2-S ticks:292
] P2-F ticks:302
] P1-S ticks:302
] P1-F ticks:312
] P5-F ticks:312
] Killing task 5
] P3-S ticks:322
] P1-S ticks:332
] P1-F ticks:342
] P3-F ticks:342
] P2-S ticks:342
] P2-F ticks:352
] P4-S ticks:352
] P4-F ticks:362
] P1-S ticks:362
] P1-F ticks:372
] P2-S ticks:372
] P2-F ticks:382
] P3-S ticks:382
] P3-F ticks:392
] P1-S ticks:392
] P1-F ticks:402
] P4-S ticks:402
] P2-S ticks:412
] P2-F ticks:422
] P1-S ticks:422
] P1-F ticks:432
] P4-F ticks:432
] P3-S ticks:432
] P3-F ticks:442
] P1-S ticks:452
] P1-F ticks:462
] P2-S ticks:462
] P2-F ticks:472
] P3-S ticks:472
] P1-S ticks:482
] P1-F ticks:492
] P3-F ticks:492
] P4-S ticks:492
] P4-F ticks:502
] P2-S ticks:502
] P2-F ticks:512
] P1-S ticks:512
] P1-F ticks:522
] P3-S ticks:522
] P3-F ticks:532
] P2-S ticks:532
] P2-F ticks:542
] P1-S ticks:542
] P1-F ticks:552
] P4-S ticks:552
] P4-F ticks:562
] P1-S ticks:572
] P1-F ticks:582
] P2-S ticks:582
] P2-F ticks:592
] P3-S ticks:592
] P3-F ticks:602
] P1-S ticks:602
] P1-F ticks:612
] P2-S ticks:612
] P2-F ticks:622
] P4-S ticks:622
] *** TIMEOUT TIMEOUT
[426/591] p:381 f:8   u:6   e:0   I:0   B:3   t:22  i:2   W:0   | riscv32/rv32i: spedfsched04.exe
 <<skipping passes>>
[466/591] p:420 f:8   u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical01.exe
Result: failed     Time: 0:00:01.009941 spintrcritical01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical01.exe
] 
] 
=>  test start: SPINTRCRITICAL 1
] *** BEGIN OF TEST SPINTRCRITICAL 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate semaphore release from ISR while blocking
] Init - Variation is: FIFO/Without Timeout
] Init - Case not hit - ran too long
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[467/591] p:420 f:9   u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical02.exe
Result: failed     Time: 0:00:01.010567 spintrcritical02.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical02.exe
] 
] 
=>  test start: SPINTRCRITICAL 2
] *** BEGIN OF TEST SPINTRCRITICAL 2 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate semaphore release from ISR while blocking
] Init - Variation is: FIFO/With Timeout
] Init - Case not hit - ran too long
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[468/591] p:421 f:9   u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical03.exe
Result: failed     Time: 0:00:01.009883 spintrcritical03.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical03.exe
] 
] 
=>  test start: SPINTRCRITICAL 3
] *** BEGIN OF TEST SPINTRCRITICAL 3 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate semaphore release from ISR while blocking
] Init - Variation is: Priority/Without Timeout
] Init - Case not hit - ran too long
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[469/591] p:421 f:10  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical04.exe
Result: failed     Time: 0:00:01.011927 spintrcritical04.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical04.exe
] 
] 
=>  test start: SPINTRCRITICAL 4
] *** BEGIN OF TEST SPINTRCRITICAL 4 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate semaphore release from ISR while blocking
] Init - Variation is: Priority/With Timeout
] Init - Case not hit - ran too long
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[470/591] p:421 f:11  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical05.exe
Result: failed     Time: 0:00:01.009853 spintrcritical05.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical05.exe
] 
] 
=>  test start: SPINTRCRITICAL 5
] *** BEGIN OF TEST SPINTRCRITICAL 5 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate semaphore release from ISR while blocking
] Init - Variation is: Priority/Without Timeout (Reverse)
] Init - Case not hit - ran too long
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[471/591] p:421 f:12  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical06.exe
[472/591] p:421 f:13  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical07.exe
Result: timeout    Time: 0:03:00.412250 spintrcritical07.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical07.exe
] 
] 
=>  test start: SPINTRCRITICAL 7
] *** BEGIN OF TEST SPINTRCRITICAL 7 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate semaphore release from ISR while blocking
] Init - There is no way for the test to know if it hits the case
] Init - Variation is: Priority/Restart Search Task (Backward)
] *** TIMEOUT TIMEOUT
[473/591] p:422 f:13  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical08.exe
 <<skipping passes>>
[475/591] p:424 f:13  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical10.exe
Result: failed     Time: 0:00:01.009896 spintrcritical10.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical10.exe
] 
] 
=>  test start: SPINTRCRITICAL 10
] *** BEGIN OF TEST SPINTRCRITICAL 10 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate any satisfied before timeout while blocking on event
] ../../../../../../rtems/c/src/../../testsuites/sptests/spintrcritical10/init.c: 144 ctx->hit
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[476/591] p:424 f:14  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical11.exe
 <<skipping passes>>
[480/591] p:428 f:14  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical15.exe
Result: timeout    Time: 0:03:00.505616 spintrcritical15.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical15.exe
] 
] 
=>  test start: SPINTRCRITICAL 15
] *** BEGIN OF TEST SPINTRCRITICAL 15 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate timeout of a thread while another is blocking
] Init -   on the same thread queue
] Init - There is no way for the test to know if it hits the case
] Init - rtems_semaphore_create - OK
] Init - rtems_task_create - OK
] *** TIMEOUT TIMEOUT
[481/591] p:429 f:14  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical16.exe
Result: failed     Time: 0:00:01.009840 spintrcritical16.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical16.exe
] 
] 
=>  test start: SPINTRCRITICAL 16
] *** BEGIN OF TEST SPINTRCRITICAL 16 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init - Trying to generate timeout of a thread that had its blocking
] Init -   request satisfied while blocking but before time timeout
] Init - rtems_semaphore_create - OK
] Init - Case not hit - ran too long
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[482/591] p:429 f:15  u:6   e:0   I:0   B:3   t:23  i:2   W:0   | riscv32/rv32i: spintrcritical18.exe
Result: timeout    Time: 0:03:00.366667 spintrcritical18.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical18.exe
] 
] 
=>  test start: SPINTRCRITICAL 18
] *** BEGIN OF TEST SPINTRCRITICAL 18 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] *** TIMEOUT TIMEOUT
[483/591] p:429 f:15  u:6   e:0   I:0   B:3   t:24  i:2   W:0   | riscv32/rv32i: spintrcritical20.exe
[484/591] p:430 f:15  u:6   e:0   I:0   B:3   t:24  i:2   W:0   | riscv32/rv32i: spintrcritical21.exe
Result: failed     Time: 0:00:01.008897 spintrcritical21.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical21.exe
] 
] 
=>  test start: SPINTRCRITICAL 21
] *** BEGIN OF TEST SPINTRCRITICAL 21 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Event sent from ISR hitting synchronization point has NOT occurred
] ../../../../../../rtems/c/src/../../testsuites/sptests/spintrcritical21/init.c: 157 case_hit
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[485/591] p:430 f:16  u:6   e:0   I:0   B:3   t:24  i:2   W:0   | riscv32/rv32i: spintrcritical22.exe
Result: failed     Time: 0:00:01.009745 spintrcritical22.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical22.exe
] 
] 
=>  test start: SPINTRCRITICAL 22
] *** BEGIN OF TEST SPINTRCRITICAL 22 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] ../../../../../../rtems/c/src/../../testsuites/sptests/spintrcritical22/init.c: 118 ctx->done
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[486/591] p:430 f:17  u:6   e:0   I:0   B:3   t:24  i:2   W:0   | riscv32/rv32i: spintrcritical23.exe
Result: failed     Time: 0:00:01.008887 spintrcritical23.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical23.exe
] 
] 
=>  test start: SPINTRCRITICAL 23
] *** BEGIN OF TEST SPINTRCRITICAL 23 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] ../../../../../../rtems/c/src/../../testsuites/sptests/spintrcritical23/init.c: 133 ctx->done
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[487/591] p:430 f:18  u:6   e:0   I:0   B:3   t:24  i:2   W:0   | riscv32/rv32i: spintrcritical24.exe
Result: timeout    Time: 0:03:00.329334 spintrcritical24.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spintrcritical24.exe
] 
] 
=>  test start: SPINTRCRITICAL 24
] *** BEGIN OF TEST SPINTRCRITICAL 24 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] *** TIMEOUT TIMEOUT
[488/591] p:430 f:18  u:6   e:0   I:0   B:3   t:25  i:2   W:0   | riscv32/rv32i: splinkersets01.exe
 <<skipping passes>>
[505/591] p:445 f:18  u:6   e:0   I:0   B:3   t:27  i:2   W:0   | riscv32/rv32i: spqreslib.exe
Result: timeout    Time: 0:03:00.696444 spqreslib.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spqreslib.exe
] 
] 
=>  test start: SPQRESLIB
] *** BEGIN OF TEST SPQRESLIB ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] Init: Initializing the qres library
] Init: Create server and Destroy server
] Init: Attach thread
] Init: Detach thread
] Init: Set params and Get params
] Init: Get server id
] Init: Get approved budget
] Init: Get current budget
] Init: Get execution time
] Init: Cleaning up QRES
] Init: Initializing the QRES
] Init: Starting periodic task
] Periodic task: Create server and Attach thread
] Periodic task: ID and Get parameters
] Periodic task: Detach thread and Destroy server
] Periodic task: Current budget and Execution time
] Periodic task: Set parameters
] Periodic task: Approved budget
] Periodic task: Starting periodic behavior
] P1-S ticks:1
] P1-F ticks:11
] P1-S ticks:31
] P1-F ticks:41
] P1-S ticks:61
] P1-F ticks:71
] P1-S ticks:91
] P1-F ticks:101
] *** TIMEOUT TIMEOUT
[506/591] p:446 f:18  u:6   e:0   I:0   B:3   t:27  i:2   W:0   | riscv32/rv32i: spratemon_err01.exe
 <<skipping passes>>
[518/591] p:457 f:18  u:6   e:0   I:0   B:3   t:28  i:2   W:0   | riscv32/rv32i: spsimplesched01.exe
Result: timeout    Time: 0:03:00.638570 spsimplesched01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spsimplesched01.exe
] 
] 
=>  test start: SPSIMPLESCHED 1
] *** BEGIN OF TEST SPSIMPLESCHED 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] 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
] *** TIMEOUT TIMEOUT
[519/591] p:458 f:18  u:6   e:0   I:0   B:3   t:28  i:2   W:0   | riscv32/rv32i: spsimplesched02.exe
 <<skipping passes>>
[525/591] p:464 f:18  u:6   e:0   I:0   B:3   t:28  i:2   W:0   | riscv32/rv32i: spsysinit01.exe
Result: failed     Time: 0:00:01.010832 spsysinit01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/sptests/spsysinit01.exe
] sysinit: begin
] sysinit: stack pointer: 0x800331e0
] sysinit: memory area 0: begin = 0x80033200, size = 0x03fcce00
] sysinit: DIRTY_MEMORY: done
] sysinit: ISR_STACK: done
] sysinit: memory area 0: free begin = 0x800375c6, free size = 0x03fc8a3a
] sysinit: WORKSPACE: done
] sysinit: memory area 0: free begin = 0x84000000, free size = 0x00000000
] sysinit: MALLOC: done
] 
] 
=>  test start: SPSYSINIT 1
] *** BEGIN OF TEST SPSYSINIT 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] sysinit: BSP_START: done
] sysinit: CPU_COUNTER: done
] sysinit: INITIAL_EXTENSIONS: done
] sysinit: DATA_STRUCTURES: done
] sysinit: USER_EXTENSIONS: done
] sysinit: CLASSIC_TASKS: done
] sysinit: CLASSIC_TIMER: done
] sysinit: CLASSIC_MESSAGE_QUEUE: done
] sysinit: CLASSIC_SEMAPHORE: done
] sysinit: CLASSIC_PARTITION: done
] sysinit: CLASSIC_REGION: done
] sysinit: CLASSIC_DUAL_PORTED_MEMORY: done
] sysinit: CLASSIC_RATE_MONOTONIC: done
] sysinit: CLASSIC_BARRIER: done
] sysinit: POSIX_SIGNALS: done
] sysinit: POSIX_THREADS: done
] sysinit: POSIX_MESSAGE_QUEUE: done
] sysinit: POSIX_SEMAPHORE: done
] sysinit: POSIX_TIMER: done
] sysinit: POSIX_SHM: done
] sysinit: POSIX_KEYS: done
] sysinit: POSIX_CLEANUP: done
] sysinit: IDLE_THREADS: done
] sysinit: LIBIO: done
] sysinit: USER_ENVIRONMENT: done
] sysinit: ROOT_FILESYSTEM: done
] sysinit: BSP_PRE_DRIVERS: done
] sysinit: DEVICE_DRIVERS: done
] sysinit: CLASSIC_USER_TASKS: done
] sysinit: POSIX_USER_THREADS: done
] sysinit: STD_FILE_DESCRIPTORS: done
] sysinit: done
] ../../../../../../rtems/c/src/../../testsuites/sptests/spsysinit01/init.c: 902 sem != SEM_FAILED
] 
] *** FATAL ***
] fatal source: 5 (RTEMS_FATAL_SOURCE_EXIT)
] fatal code: 0 (0x00000000)
] RTEMS version: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] RTEMS tools: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] executing thread ID: 0x08a010001
] executing thread name: UI1 
] executing thread name: UI1 
[526/591] p:464 f:19  u:6   e:0   I:0   B:3   t:28  i:2   W:0   | riscv32/rv32i: spsyslock01.exe
 <<skipping passes>>
[588/591] p:527 f:19  u:6   e:0   I:0   B:3   t:29  i:2   W:0   | riscv32/rv32i: tmfine01.exe
Result: timeout    Time: 0:03:00.485229 tmfine01.exe
=>  run: spike --isa=RV32I ./riscv-rtems5/c/rv32i/testsuites/tmtests/tmfine01.exe
] 
] 
=>  test start: TMFINE 1
] *** BEGIN OF TEST TMFINE 1 ***
] *** TEST VERSION: 5.0.0.80cf60efec79ac63cc3a26c6ad8f86790a385847
] *** TEST STATE: EXPECTED_PASS
] *** TEST BUILD: RTEMS_POSIX_API
] *** TEST TOOLS: 9.3.0 20200312 (RTEMS 5, RSB 5 (9482a1b33b4c), Newlib 7947581)
] <TestTimeFine01>
]   <SelfEvent activeWorker="1">
]     <Counter worker="0">7676428</Counter>
]   </SelfEvent>
]   <AllToOneEvent activeWorker="1">
]     <Counter worker="0">7453779</Counter>
]   </AllToOneEvent>
]   <OneMutex activeWorker="1">
]     <Counter worker="0">7237441</Counter>
]   </OneMutex>
]   <ManyMutex activeWorker="1">
]     <Counter worker="0">7237801</Counter>
]   </ManyMutex>
]   <SelfMsg activeWorker="1">
]     <Counter worker="0">3221956</Counter>
]   </SelfMsg>
]   <ManyToOneMsg activeWorker="1">
]     <Counter worker="0">3211675</Counter>
]   </ManyToOneMsg>
]   <ManySysLockMutex activeWorker="1">
]     <Counter worker="0">39951039</Counter>
]   </ManySysLockMutex>
] *** TIMEOUT TIMEOUT
[589/591] p:527 f:19  u:6   e:0   I:0   B:3   t:29  i:2   W:0   | riscv32/rv32i: tmonetoone.exe
 <<skipping passes>>
[591/591] p:528 f:19  u:6   e:0   I:0   B:3   t:29  i:2   W:0   | riscv32/rv32i: tmtimer01.exe

Passed:        530
Failed:         19
User Input:      6
Expected Fail:   0
Indeterminate:   0
Benchmark:       3
Timeout:        31
Invalid:         2
Wrong Version:   0
Wrong Build:     0
Wrong Tools:     0
------------------
Total:         591
Failures:
 dl02.exe
 dl05.exe
 dl06.exe
 dl07.exe
 dl08.exe
 dl09.exe
 psxfenv01.exe
 sp42.exe
 spintrcritical01.exe
 spintrcritical02.exe
 spintrcritical03.exe
 spintrcritical04.exe
 spintrcritical05.exe
 spintrcritical10.exe
 spintrcritical16.exe
 spintrcritical21.exe
 spintrcritical22.exe
 spintrcritical23.exe
 spsysinit01.exe
User Input:
 dl10.exe
 monitor.exe
 termios.exe
 top.exe
 capture.exe
 fileio.exe
Benchmark:
 whetstone.exe
 linpack.exe
 dhrystone.exe
Timeouts:
 block08.exe
 cpuuse.exe
 crypt01.exe
 flashdisk01.exe
 malloctest.exe
 psxintrcritical01.exe
 psxsignal01.exe
 psxstat.exe
 psxtime.exe
 psxtimer01.exe
 ticker.exe
 sp01.exe
 sp04.exe
 sp05.exe
 sp12.exe
 sp19.exe
 sp20.exe
 sp24.exe
 sp30.exe
 spcbssched01.exe
 spcbssched03.exe
 spcontext01.exe
 spedfsched01.exe
 spedfsched03.exe
 spintrcritical07.exe
 spintrcritical15.exe
 spintrcritical18.exe
 spintrcritical24.exe
 spqreslib.exe
 spsimplesched01.exe
 tmfine01.exe
Invalid:
 rhtaskpreempt.exe
 rhtaskswitch.exe
Average test time: 0:00:05.006976
Testing time     : 0:49:19.122823


More information about the build mailing list