Console Output
Skipping 5,994 KB..
Full Log17:13:47 Start 594: smpi-replay-multiple-manual-empty1
17:13:47 593/1134 Test #594: smpi-replay-multiple-manual-empty1 .......................... Passed 0.12 sec
17:13:47 Start 595: smpi-replay-multiple-manual-empty2
17:13:47 594/1134 Test #593: smpi-replay-multiple-manual-coll2-st-sr-noise ............... Passed 0.82 sec
17:13:47 Start 596: smpi-replay-multiple-manual-mixed1
17:13:47 595/1134 Test #596: smpi-replay-multiple-manual-mixed1 .......................... Passed 0.11 sec
17:13:47 Start 597: smpi-replay-multiple-manual-mixed2
17:13:48 596/1134 Test #595: smpi-replay-multiple-manual-empty2 .......................... Passed 0.35 sec
17:13:48 Start 598: smpi-replay-multiple-manual-mixed2-st
17:13:48 597/1134 Test #597: smpi-replay-multiple-manual-mixed2 .......................... Passed 0.18 sec
17:13:48 Start 599: smpi-replay-multiple-manual-mixed2-st-noise
17:13:48 598/1134 Test #598: smpi-replay-multiple-manual-mixed2-st ....................... Passed 0.10 sec
17:13:48 Start 600: smpi-replay-multiple-manual-mixed2-st-sr
17:13:48 599/1134 Test #599: smpi-replay-multiple-manual-mixed2-st-noise ................. Passed 0.08 sec
17:13:48 Start 601: smpi-replay-multiple-manual-mixed2-st-sr-noise
17:13:48 600/1134 Test #600: smpi-replay-multiple-manual-mixed2-st-sr .................... Passed 0.09 sec
17:13:48 Start 602: smpi-s4u-masterworker-thread
17:13:48 601/1134 Test #601: smpi-replay-multiple-manual-mixed2-st-sr-noise .............. Passed 0.08 sec
17:13:48 Start 603: smpi-s4u-masterworker-boost
17:13:48 602/1134 Test #603: smpi-s4u-masterworker-boost ................................. Passed 0.08 sec
17:13:48 Start 604: smpi-s4u-masterworker-raw
17:13:48 603/1134 Test #602: smpi-s4u-masterworker-thread ................................ Passed 0.13 sec
17:13:48 Start 605: smpi-s4u-masterworker-ucontext
17:13:48 604/1134 Test #604: smpi-s4u-masterworker-raw ................................... Passed 0.08 sec
17:13:48 Start 606: pthread-mutex-simple-boost
17:13:48 605/1134 Test #605: smpi-s4u-masterworker-ucontext .............................. Passed 0.09 sec
17:13:48 Start 607: pthread-mutex-simple-raw
17:13:48 606/1134 Test #606: pthread-mutex-simple-boost .................................. Passed 0.09 sec
17:13:48 Start 608: pthread-mutex-simple-ucontext
17:13:48 607/1134 Test #607: pthread-mutex-simple-raw .................................... Passed 0.07 sec
17:13:48 Start 609: pthread-mc-mutex-simple-boost
17:13:48 608/1134 Test #608: pthread-mutex-simple-ucontext ............................... Passed 0.07 sec
17:13:48 Start 610: pthread-mc-mutex-simple-raw
17:13:48 609/1134 Test #609: pthread-mc-mutex-simple-boost ...............................***Failed 0.10 sec
17:13:48 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:48 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:48 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-simple.tesh'
17:13:48 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:48 [pthread-mc-mutex-simple.tesh:5] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-simple --cfg=contexts/factory:boost
17:13:48 Output of <pthread-mc-mutex-simple.tesh:5> mismatch:
17:13:48 --- expected
17:13:48 +++ obtained
17:13:48 @@ -6,4 +6,4 @@
17:13:48 The thread 1 is terminating.
17:13:48 The thread 0 is terminating.
17:13:48 User's main is terminating.
17:13:48 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 18 unique states visited; 2 backtracks (2 transition replays, 20 states visited overall)
17:13:48 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 17 unique states visited; 1 backtracks (0 transition replays, 17 states visited overall)
17:13:48 Test suite `pthread-mc-mutex-simple.tesh': NOK (<pthread-mc-mutex-simple.tesh:5> output mismatch)
17:13:48
17:13:48 Start 611: pthread-mc-mutex-simple-ucontext
17:13:48 610/1134 Test #610: pthread-mc-mutex-simple-raw .................................***Failed 0.10 sec
17:13:48 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:48 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:48 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-simple.tesh'
17:13:48 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:48 [pthread-mc-mutex-simple.tesh:5] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-simple --cfg=contexts/factory:raw
17:13:48 Output of <pthread-mc-mutex-simple.tesh:5> mismatch:
17:13:48 --- expected
17:13:48 +++ obtained
17:13:48 @@ -6,4 +6,4 @@
17:13:48 The thread 1 is terminating.
17:13:48 The thread 0 is terminating.
17:13:48 User's main is terminating.
17:13:48 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 18 unique states visited; 2 backtracks (2 transition replays, 20 states visited overall)
17:13:48 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 17 unique states visited; 1 backtracks (0 transition replays, 17 states visited overall)
17:13:48 Test suite `pthread-mc-mutex-simple.tesh': NOK (<pthread-mc-mutex-simple.tesh:5> output mismatch)
17:13:48
17:13:48 Start 612: pthread-mutex-recursive-boost
17:13:48 611/1134 Test #611: pthread-mc-mutex-simple-ucontext ............................***Failed 0.11 sec
17:13:48 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:48 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:48 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-simple.tesh'
17:13:48 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:48 [pthread-mc-mutex-simple.tesh:5] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-simple --cfg=contexts/factory:ucontext
17:13:48 Output of <pthread-mc-mutex-simple.tesh:5> mismatch:
17:13:48 --- expected
17:13:48 +++ obtained
17:13:48 @@ -6,4 +6,4 @@
17:13:48 The thread 1 is terminating.
17:13:48 The thread 0 is terminating.
17:13:48 User's main is terminating.
17:13:48 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 18 unique states visited; 2 backtracks (2 transition replays, 20 states visited overall)
17:13:48 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 17 unique states visited; 1 backtracks (0 transition replays, 17 states visited overall)
17:13:48 Test suite `pthread-mc-mutex-simple.tesh': NOK (<pthread-mc-mutex-simple.tesh:5> output mismatch)
17:13:48
17:13:48 Start 613: pthread-mutex-recursive-raw
17:13:48 612/1134 Test #612: pthread-mutex-recursive-boost ............................... Passed 0.08 sec
17:13:48 Start 614: pthread-mutex-recursive-ucontext
17:13:48 613/1134 Test #613: pthread-mutex-recursive-raw ................................. Passed 0.07 sec
17:13:48 Start 615: pthread-mc-mutex-recursive-boost
17:13:48 614/1134 Test #614: pthread-mutex-recursive-ucontext ............................ Passed 0.07 sec
17:13:48 Start 616: pthread-mc-mutex-recursive-raw
17:13:48 615/1134 Test #615: pthread-mc-mutex-recursive-boost ............................***Failed 0.11 sec
17:13:48 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:48 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:48 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-recursive.tesh'
17:13:48 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:48 [pthread-mc-mutex-recursive.tesh:4] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-recursive --cfg=contexts/factory:boost
17:13:48 Output of <pthread-mc-mutex-recursive.tesh:4> mismatch:
17:13:48 --- expected
17:13:48 +++ obtained
17:13:48 @@ -3,8 +3,4 @@
17:13:48 Failed to relock the default mutex.
17:13:48 Got the lock on the recursive mutex.
17:13:48 Got the lock again on the recursive mutex.
17:13:48 -Got the lock on the default mutex.
17:13:48 -Failed to relock the default mutex.
17:13:48 -Got the lock on the recursive mutex.
17:13:48 -Got the lock again on the recursive mutex.
17:13:48 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 17 unique states visited; 1 backtracks (3 transition replays, 20 states visited overall)
17:13:48 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 12 unique states visited; 0 backtracks (0 transition replays, 12 states visited overall)
17:13:48 Test suite `pthread-mc-mutex-recursive.tesh': NOK (<pthread-mc-mutex-recursive.tesh:4> output mismatch)
17:13:48
17:13:48 Start 617: pthread-mc-mutex-recursive-ucontext
17:13:48 616/1134 Test #616: pthread-mc-mutex-recursive-raw ..............................***Failed 0.10 sec
17:13:48 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:48 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:48 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-recursive.tesh'
17:13:48 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:48 [pthread-mc-mutex-recursive.tesh:4] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-recursive --cfg=contexts/factory:raw
17:13:48 Output of <pthread-mc-mutex-recursive.tesh:4> mismatch:
17:13:48 --- expected
17:13:48 +++ obtained
17:13:48 @@ -3,8 +3,4 @@
17:13:48 Failed to relock the default mutex.
17:13:48 Got the lock on the recursive mutex.
17:13:48 Got the lock again on the recursive mutex.
17:13:48 -Got the lock on the default mutex.
17:13:48 -Failed to relock the default mutex.
17:13:48 -Got the lock on the recursive mutex.
17:13:48 -Got the lock again on the recursive mutex.
17:13:48 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 17 unique states visited; 1 backtracks (3 transition replays, 20 states visited overall)
17:13:48 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 12 unique states visited; 0 backtracks (0 transition replays, 12 states visited overall)
17:13:48 Test suite `pthread-mc-mutex-recursive.tesh': NOK (<pthread-mc-mutex-recursive.tesh:4> output mismatch)
17:13:48
17:13:48 Start 618: pthread-producer-consumer-boost
17:13:48 617/1134 Test #617: pthread-mc-mutex-recursive-ucontext .........................***Failed 0.10 sec
17:13:48 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:48 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:48 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-recursive.tesh'
17:13:48 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:48 [pthread-mc-mutex-recursive.tesh:4] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-recursive --cfg=contexts/factory:ucontext
17:13:48 Output of <pthread-mc-mutex-recursive.tesh:4> mismatch:
17:13:48 --- expected
17:13:48 +++ obtained
17:13:48 @@ -3,8 +3,4 @@
17:13:48 Failed to relock the default mutex.
17:13:48 Got the lock on the recursive mutex.
17:13:48 Got the lock again on the recursive mutex.
17:13:48 -Got the lock on the default mutex.
17:13:48 -Failed to relock the default mutex.
17:13:48 -Got the lock on the recursive mutex.
17:13:48 -Got the lock again on the recursive mutex.
17:13:48 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 17 unique states visited; 1 backtracks (3 transition replays, 20 states visited overall)
17:13:48 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 12 unique states visited; 0 backtracks (0 transition replays, 12 states visited overall)
17:13:48 Test suite `pthread-mc-mutex-recursive.tesh': NOK (<pthread-mc-mutex-recursive.tesh:4> output mismatch)
17:13:48
17:13:48 Start 619: pthread-producer-consumer-raw
17:13:49 618/1134 Test #618: pthread-producer-consumer-boost ............................. Passed 0.11 sec
17:13:49 Start 620: pthread-producer-consumer-ucontext
17:13:49 619/1134 Test #619: pthread-producer-consumer-raw ............................... Passed 0.10 sec
17:13:49 Start 621: pthread-mc-producer-consumer-boost
17:13:49 620/1134 Test #620: pthread-producer-consumer-ucontext .......................... Passed 0.10 sec
17:13:49 Start 622: pthread-mc-producer-consumer-raw
17:13:49 621/1134 Test #621: pthread-mc-producer-consumer-boost ..........................***Failed 0.59 sec
17:13:49 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:49 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:49 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-producer-consumer.tesh'
17:13:49 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:49 [pthread-mc-producer-consumer.tesh:4] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:boost
17:13:49 Output of <pthread-mc-producer-consumer.tesh:4> mismatch:
17:13:49 --- expected
17:13:49 +++ obtained
17:13:49 @@ -1,2 +1,2 @@
17:13:49 [0.000000] [mc_dfs/INFO] Start a DFS exploration. Reduction is: dpor.
17:13:49 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 735 unique states visited; 73 backtracks (1598 transition replays, 2333 states visited overall)
17:13:49 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 689 unique states visited; 53 backtracks (1112 transition replays, 1801 states visited overall)
17:13:49 Test suite `pthread-mc-producer-consumer.tesh': NOK (<pthread-mc-producer-consumer.tesh:4> output mismatch)
17:13:49 [pthread-mc-producer-consumer.tesh:8] ./../../bin/simgrid-mc --cfg=model-check/reduction:sdpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:boost
17:13:49
17:13:49 [pthread-mc-producer-consumer.tesh:13] ./../../bin/simgrid-mc --cfg=model-check/reduction:odpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:boost
17:13:49
17:13:49
17:13:49 Start 623: pthread-mc-producer-consumer-ucontext
17:13:49 622/1134 Test #622: pthread-mc-producer-consumer-raw ............................***Failed 0.59 sec
17:13:49 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:49 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:49 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-producer-consumer.tesh'
17:13:49 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:49 [pthread-mc-producer-consumer.tesh:4] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:raw
17:13:49 Output of <pthread-mc-producer-consumer.tesh:4> mismatch:
17:13:49 --- expected
17:13:49 +++ obtained
17:13:49 @@ -1,2 +1,2 @@
17:13:49 [0.000000] [mc_dfs/INFO] Start a DFS exploration. Reduction is: dpor.
17:13:49 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 735 unique states visited; 73 backtracks (1598 transition replays, 2333 states visited overall)
17:13:49 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 689 unique states visited; 53 backtracks (1112 transition replays, 1801 states visited overall)
17:13:49 Test suite `pthread-mc-producer-consumer.tesh': NOK (<pthread-mc-producer-consumer.tesh:4> output mismatch)
17:13:49 [pthread-mc-producer-consumer.tesh:8] ./../../bin/simgrid-mc --cfg=model-check/reduction:sdpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:raw
17:13:49
17:13:49 [pthread-mc-producer-consumer.tesh:13] ./../../bin/simgrid-mc --cfg=model-check/reduction:odpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:raw
17:13:49
17:13:49
17:13:49 Start 624: pthread-mc-mutex-simpledeadlock-boost
17:13:50 623/1134 Test #623: pthread-mc-producer-consumer-ucontext .......................***Failed 0.61 sec
17:13:50 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:50 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:50 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-producer-consumer.tesh'
17:13:50 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:50 [pthread-mc-producer-consumer.tesh:4] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:ucontext
17:13:50 Output of <pthread-mc-producer-consumer.tesh:4> mismatch:
17:13:50 --- expected
17:13:50 +++ obtained
17:13:50 @@ -1,2 +1,2 @@
17:13:50 [0.000000] [mc_dfs/INFO] Start a DFS exploration. Reduction is: dpor.
17:13:50 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 735 unique states visited; 73 backtracks (1598 transition replays, 2333 states visited overall)
17:13:50 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 689 unique states visited; 53 backtracks (1112 transition replays, 1801 states visited overall)
17:13:50 Test suite `pthread-mc-producer-consumer.tesh': NOK (<pthread-mc-producer-consumer.tesh:4> output mismatch)
17:13:50 [pthread-mc-producer-consumer.tesh:8] ./../../bin/simgrid-mc --cfg=model-check/reduction:sdpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:ucontext
17:13:50
17:13:50 [pthread-mc-producer-consumer.tesh:13] ./../../bin/simgrid-mc --cfg=model-check/reduction:odpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-producer-consumer -q -C 1 -P 1 --cfg=contexts/factory:ucontext
17:13:50
17:13:50
17:13:50 Start 625: pthread-mc-mutex-simpledeadlock-raw
17:13:51 624/1134 Test #625: pthread-mc-mutex-simpledeadlock-raw .........................***Failed 1.50 sec
17:13:51 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:51 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:51 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-simpledeadlock.tesh'
17:13:51 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:51 [pthread-mc-mutex-simpledeadlock.tesh:7] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-simpledeadlock --cfg=contexts/factory:raw
17:13:51 Output of <pthread-mc-mutex-simpledeadlock.tesh:7> mismatch:
17:13:51 --- expected
17:13:51 +++ obtained
17:13:51 @@ -18,4 +18,4 @@
17:13:51 [0.000000] [mc_global/INFO] Actor 3 in simcall MUTEX_WAIT(mutex: 1, owner: 3)
17:13:51 [0.000000] [mc_global/INFO] Actor 3 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:51 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;3;2;3;3'
17:13:51 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 20 unique states visited; 2 backtracks (7 transition replays, 27 states visited overall)
17:13:51 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 19 unique states visited; 1 backtracks (2 transition replays, 21 states visited overall)
17:13:51 Test suite `pthread-mc-mutex-simpledeadlock.tesh': NOK (<pthread-mc-mutex-simpledeadlock.tesh:7> output mismatch)
17:13:51 [Tesh/INFO] setenv LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so
17:13:51 [pthread-mc-mutex-simpledeadlock.tesh:35] ./pthread-mutex-simpledeadlock --cfg=model-check/replay:'2;2;3;2;3;3' --cfg=contexts/factory:raw
17:13:51 sthread is intercepting the execution of ./pthread-mutex-simpledeadlock. If it's not what you want, export STHREAD_IGNORE_BINARY=./pthread-mutex-simpledeadlock
17:13:51 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'model-check/replay' to '2;2;3;2;3;3'
17:13:51 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'contexts/factory' to 'raw'
17:13:51 [0.000000] [mc_record/INFO] path=2;2;3;2;3;3
17:13:51 All threads are started.
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #1 '2/0' Actor thread 1(pid:2): MUTEX_ASYNC_LOCK(mutex_id:0 owner:none)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 1):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:21
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #2 '2/0' Actor thread 1(pid:2): MUTEX_WAIT(mutex_id:0 owner:2)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 1):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:28
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:21
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #3 '3/0' Actor thread 2(pid:3): MUTEX_ASYNC_LOCK(mutex_id:1 owner:none)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 2):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:31
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #4 '2/0' Actor thread 1(pid:2): MUTEX_ASYNC_LOCK(mutex_id:1 owner:3)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 1):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:22
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #5 '3/0' Actor thread 2(pid:3): MUTEX_WAIT(mutex_id:1 owner:3)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 2):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:28
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:31
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #6 '3/0' Actor thread 2(pid:3): MUTEX_ASYNC_LOCK(mutex_id:0 owner:2)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 2):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:32
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51
17:13:51 [0.000000] [mc_record/INFO] The replay of the trace is complete. DEADLOCK detected.
17:13:51 [0.000000] [ker_engine/INFO] 3 actors are still active, awaiting something. Here is their status:
17:13:51 [0.000000] [ker_engine/INFO] - pid 1 (main thread@Lilibeth) simcall ActorJoin(pid:2)
17:13:51 [0.000000] [ker_engine/INFO] - pid 2 (thread 1@Lilibeth) simcall MUTEX_WAIT(mutex_id:1 owner:3)
17:13:51 [0.000000] [ker_engine/INFO] - pid 3 (thread 2@Lilibeth) simcall MUTEX_WAIT(mutex_id:0 owner:2)
17:13:51 [0.000000] [sthread/INFO] All threads exited. Terminating the simulation.
17:13:51 [0.000000] ../src/kernel/EngineImpl.cpp:271: [ker_engine/WARNING] Process called exit when leaving - Skipping cleanups
17:13:51 [0.000000] ../src/kernel/EngineImpl.cpp:271: [ker_engine/WARNING] Process called exit when leaving - Skipping cleanups
17:13:51
17:13:51 (ignoring the output of <pthread-mc-mutex-simpledeadlock.tesh:35> as requested)
17:13:51
17:13:51 Start 626: pthread-mc-mutex-simpledeadlock-ucontext
17:13:51 625/1134 Test #624: pthread-mc-mutex-simpledeadlock-boost .......................***Failed 2.21 sec
17:13:51 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:51 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:51 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-simpledeadlock.tesh'
17:13:51 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:51 [pthread-mc-mutex-simpledeadlock.tesh:7] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-simpledeadlock --cfg=contexts/factory:boost
17:13:51 Output of <pthread-mc-mutex-simpledeadlock.tesh:7> mismatch:
17:13:51 --- expected
17:13:51 +++ obtained
17:13:51 @@ -18,4 +18,4 @@
17:13:51 [0.000000] [mc_global/INFO] Actor 3 in simcall MUTEX_WAIT(mutex: 1, owner: 3)
17:13:51 [0.000000] [mc_global/INFO] Actor 3 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:51 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;3;2;3;3'
17:13:51 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 20 unique states visited; 2 backtracks (7 transition replays, 27 states visited overall)
17:13:51 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 19 unique states visited; 1 backtracks (2 transition replays, 21 states visited overall)
17:13:51 Test suite `pthread-mc-mutex-simpledeadlock.tesh': NOK (<pthread-mc-mutex-simpledeadlock.tesh:7> output mismatch)
17:13:51 [Tesh/INFO] setenv LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so
17:13:51 [pthread-mc-mutex-simpledeadlock.tesh:35] ./pthread-mutex-simpledeadlock --cfg=model-check/replay:'2;2;3;2;3;3' --cfg=contexts/factory:boost
17:13:51 sthread is intercepting the execution of ./pthread-mutex-simpledeadlock. If it's not what you want, export STHREAD_IGNORE_BINARY=./pthread-mutex-simpledeadlock
17:13:51 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'model-check/replay' to '2;2;3;2;3;3'
17:13:51 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'contexts/factory' to 'boost'
17:13:51 [0.000000] [mc_record/INFO] path=2;2;3;2;3;3
17:13:51 All threads are started.
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #1 '2/0' Actor thread 1(pid:2): MUTEX_ASYNC_LOCK(mutex_id:0 owner:none)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 1):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:21
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51 -> #6 0x00007F951A9EFE37 at ../src/kernel/context/ContextBoost.cpp:46
17:13:51 -> #7 make_fcontext in /lib/x86_64-linux-gnu/libboost_context.so.1.74.0
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #2 '2/0' Actor thread 1(pid:2): MUTEX_WAIT(mutex_id:0 owner:2)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 1):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:28
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:21
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51 -> #6 0x00007F951A9EFE37 at ../src/kernel/context/ContextBoost.cpp:46
17:13:51 -> #7 make_fcontext in /lib/x86_64-linux-gnu/libboost_context.so.1.74.0
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #3 '3/0' Actor thread 2(pid:3): MUTEX_ASYNC_LOCK(mutex_id:1 owner:none)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 2):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:31
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51 -> #6 0x00007F951A9EFE37 at ../src/kernel/context/ContextBoost.cpp:46
17:13:51 -> #7 make_fcontext in /lib/x86_64-linux-gnu/libboost_context.so.1.74.0
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #4 '2/0' Actor thread 1(pid:2): MUTEX_ASYNC_LOCK(mutex_id:1 owner:3)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 1):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:22
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51 -> #6 0x00007F951A9EFE37 at ../src/kernel/context/ContextBoost.cpp:46
17:13:51 -> #7 make_fcontext in /lib/x86_64-linux-gnu/libboost_context.so.1.74.0
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #5 '3/0' Actor thread 2(pid:3): MUTEX_WAIT(mutex_id:1 owner:3)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 2):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:28
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:31
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51 -> #6 0x00007F951A9EFE37 at ../src/kernel/context/ContextBoost.cpp:46
17:13:51 -> #7 make_fcontext in /lib/x86_64-linux-gnu/libboost_context.so.1.74.0
17:13:51
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 [0.000000] [mc_record/INFO] * Path chunk #6 '3/0' Actor thread 2(pid:3): MUTEX_ASYNC_LOCK(mutex_id:0 owner:2)
17:13:51 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:51 Backtrace (displayed in actor thread 2):
17:13:51 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:51 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:51 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:51 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:32
17:13:51 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:51 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:51 -> #6 0x00007F951A9EFE37 at ../src/kernel/context/ContextBoost.cpp:46
17:13:51 -> #7 make_fcontext in /lib/x86_64-linux-gnu/libboost_context.so.1.74.0
17:13:51
17:13:51 [0.000000] [mc_record/INFO] The replay of the trace is complete. DEADLOCK detected.
17:13:51 [0.000000] [ker_engine/INFO] 3 actors are still active, awaiting something. Here is their status:
17:13:51 [0.000000] [ker_engine/INFO] - pid 1 (main thread@Lilibeth) simcall ActorJoin(pid:2)
17:13:51 [0.000000] [ker_engine/INFO] - pid 2 (thread 1@Lilibeth) simcall MUTEX_WAIT(mutex_id:1 owner:3)
17:13:51 [0.000000] [ker_engine/INFO] - pid 3 (thread 2@Lilibeth) simcall MUTEX_WAIT(mutex_id:0 owner:2)
17:13:51 [0.000000] [sthread/INFO] All threads exited. Terminating the simulation.
17:13:51 [0.000000] ../src/kernel/EngineImpl.cpp:271: [ker_engine/WARNING] Process called exit when leaving - Skipping cleanups
17:13:51 [0.000000] ../src/kernel/EngineImpl.cpp:271: [ker_engine/WARNING] Process called exit when leaving - Skipping cleanups
17:13:51
17:13:51 (ignoring the output of <pthread-mc-mutex-simpledeadlock.tesh:35> as requested)
17:13:51
17:13:51 Start 627: sthread-mc-stdobject-boost
17:13:52 626/1134 Test #627: sthread-mc-stdobject-boost .................................. Passed 0.11 sec
17:13:52 Start 628: sthread-mc-stdobject-raw
17:13:52 627/1134 Test #628: sthread-mc-stdobject-raw .................................... Passed 0.10 sec
17:13:52 Start 629: sthread-mc-stdobject-ucontext
17:13:52 628/1134 Test #629: sthread-mc-stdobject-ucontext ............................... Passed 0.10 sec
17:13:52 Start 630: sthread-mutex-simple-boost
17:13:52 629/1134 Test #630: sthread-mutex-simple-boost .................................. Passed 0.08 sec
17:13:52 Start 631: sthread-mutex-simple-raw
17:13:52 630/1134 Test #631: sthread-mutex-simple-raw .................................... Passed 0.07 sec
17:13:52 Start 632: sthread-mutex-simple-ucontext
17:13:52 631/1134 Test #632: sthread-mutex-simple-ucontext ............................... Passed 0.08 sec
17:13:52 Start 633: stack-overflow-boost
17:13:52 632/1134 Test #626: pthread-mc-mutex-simpledeadlock-ucontext ....................***Failed 0.69 sec
17:13:52 [Tesh/INFO] change directory to /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/examples/sthread
17:13:52 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:52 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/examples/sthread/pthread-mc-mutex-simpledeadlock.tesh'
17:13:52 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:52 [pthread-mc-mutex-simpledeadlock.tesh:7] ./../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so ./pthread-mutex-simpledeadlock --cfg=contexts/factory:ucontext
17:13:52 Output of <pthread-mc-mutex-simpledeadlock.tesh:7> mismatch:
17:13:52 --- expected
17:13:52 +++ obtained
17:13:52 @@ -18,4 +18,4 @@
17:13:52 [0.000000] [mc_global/INFO] Actor 3 in simcall MUTEX_WAIT(mutex: 1, owner: 3)
17:13:52 [0.000000] [mc_global/INFO] Actor 3 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:52 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;3;2;3;3'
17:13:52 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 20 unique states visited; 2 backtracks (7 transition replays, 27 states visited overall)
17:13:52 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 19 unique states visited; 1 backtracks (2 transition replays, 21 states visited overall)
17:13:52 Test suite `pthread-mc-mutex-simpledeadlock.tesh': NOK (<pthread-mc-mutex-simpledeadlock.tesh:7> output mismatch)
17:13:52 [Tesh/INFO] setenv LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so
17:13:52 [pthread-mc-mutex-simpledeadlock.tesh:35] ./pthread-mutex-simpledeadlock --cfg=model-check/replay:'2;2;3;2;3;3' --cfg=contexts/factory:ucontext
17:13:52 sthread is intercepting the execution of ./pthread-mutex-simpledeadlock. If it's not what you want, export STHREAD_IGNORE_BINARY=./pthread-mutex-simpledeadlock
17:13:52 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'model-check/replay' to '2;2;3;2;3;3'
17:13:52 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'contexts/factory' to 'ucontext'
17:13:52 [0.000000] [mc_record/INFO] path=2;2;3;2;3;3
17:13:52 All threads are started.
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 [0.000000] [mc_record/INFO] * Path chunk #1 '2/0' Actor thread 1(pid:2): MUTEX_ASYNC_LOCK(mutex_id:0 owner:none)
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 Backtrace (displayed in actor thread 1):
17:13:52 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:52 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:52 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:52 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:21
17:13:52 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:52 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:52 -> #6 0x00007FD88FFF4E5A at ../src/kernel/context/ContextUnix.cpp:31
17:13:52 -> #7 0x00007FD890495D80 at ../sysdeps/unix/sysv/linux/x86_64/__start_context.S:106
17:13:52
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 [0.000000] [mc_record/INFO] * Path chunk #2 '2/0' Actor thread 1(pid:2): MUTEX_WAIT(mutex_id:0 owner:2)
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 Backtrace (displayed in actor thread 1):
17:13:52 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:28
17:13:52 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:52 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:52 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:21
17:13:52 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:52 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:52 -> #6 0x00007FD88FFF4E5A at ../src/kernel/context/ContextUnix.cpp:31
17:13:52 -> #7 0x00007FD890495D80 at ../sysdeps/unix/sysv/linux/x86_64/__start_context.S:106
17:13:52
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 [0.000000] [mc_record/INFO] * Path chunk #3 '3/0' Actor thread 2(pid:3): MUTEX_ASYNC_LOCK(mutex_id:1 owner:none)
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 Backtrace (displayed in actor thread 2):
17:13:52 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:52 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:52 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:52 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:31
17:13:52 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:52 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:52 -> #6 0x00007FD88FFF4E5A at ../src/kernel/context/ContextUnix.cpp:31
17:13:52 -> #7 0x00007FD890495D80 at ../sysdeps/unix/sysv/linux/x86_64/__start_context.S:106
17:13:52
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 [0.000000] [mc_record/INFO] * Path chunk #4 '2/0' Actor thread 1(pid:2): MUTEX_ASYNC_LOCK(mutex_id:1 owner:3)
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 Backtrace (displayed in actor thread 1):
17:13:52 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:52 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:52 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:52 -> #3 thread_fun1 at ../examples/sthread/pthread-mutex-simpledeadlock.c:22
17:13:52 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:52 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:52 -> #6 0x00007FD88FFF4E5A at ../src/kernel/context/ContextUnix.cpp:31
17:13:52 -> #7 0x00007FD890495D80 at ../sysdeps/unix/sysv/linux/x86_64/__start_context.S:106
17:13:52
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 [0.000000] [mc_record/INFO] * Path chunk #5 '3/0' Actor thread 2(pid:3): MUTEX_WAIT(mutex_id:1 owner:3)
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 Backtrace (displayed in actor thread 2):
17:13:52 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:28
17:13:52 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:52 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:52 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:31
17:13:52 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:52 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:52 -> #6 0x00007FD88FFF4E5A at ../src/kernel/context/ContextUnix.cpp:31
17:13:52 -> #7 0x00007FD890495D80 at ../sysdeps/unix/sysv/linux/x86_64/__start_context.S:106
17:13:52
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 [0.000000] [mc_record/INFO] * Path chunk #6 '3/0' Actor thread 2(pid:3): MUTEX_ASYNC_LOCK(mutex_id:0 owner:2)
17:13:52 [0.000000] [mc_record/INFO] ***********************************************************************************
17:13:52 Backtrace (displayed in actor thread 2):
17:13:52 -> #0 simgrid::s4u::Mutex::lock() at ../src/s4u/s4u_Mutex.cpp:24
17:13:52 -> #1 sthread_mutex_lock at ../src/sthread/sthread_impl.cpp:203
17:13:52 -> #2 pthread_mutex_lock at /usr/include/pthread.h:738
17:13:52 -> #3 thread_fun2 at ../examples/sthread/pthread-mutex-simpledeadlock.c:32
17:13:52 -> #4 std::_Function_handler<void (), std::_Bind<sthread_create::{lambda(auto:1*, auto:2*)#1} (void* (*)(sthread_create::{lambda(auto:1*, auto:2*)#1}), sthread_create::{lambda(auto:1*, auto:2*)#1})> >::_M_invoke(std::_Any_data const&) at /usr/include/c++/10/bits/std_function.h:293
17:13:52 -> #5 smx_ctx_wrapper at ../src/kernel/context/ContextSwapped.cpp:43
17:13:52 -> #6 0x00007FD88FFF4E5A at ../src/kernel/context/ContextUnix.cpp:31
17:13:52 -> #7 0x00007FD890495D80 at ../sysdeps/unix/sysv/linux/x86_64/__start_context.S:106
17:13:52
17:13:52 [0.000000] [mc_record/INFO] The replay of the trace is complete. DEADLOCK detected.
17:13:52 [0.000000] [ker_engine/INFO] 3 actors are still active, awaiting something. Here is their status:
17:13:52 [0.000000] [ker_engine/INFO] - pid 1 (main thread@Lilibeth) simcall ActorJoin(pid:2)
17:13:52 [0.000000] [ker_engine/INFO] - pid 2 (thread 1@Lilibeth) simcall MUTEX_WAIT(mutex_id:1 owner:3)
17:13:52 [0.000000] [ker_engine/INFO] - pid 3 (thread 2@Lilibeth) simcall MUTEX_WAIT(mutex_id:0 owner:2)
17:13:52 [0.000000] [sthread/INFO] All threads exited. Terminating the simulation.
17:13:52 [0.000000] ../src/kernel/EngineImpl.cpp:271: [ker_engine/WARNING] Process called exit when leaving - Skipping cleanups
17:13:52 [0.000000] ../src/kernel/EngineImpl.cpp:271: [ker_engine/WARNING] Process called exit when leaving - Skipping cleanups
17:13:52
17:13:52 (ignoring the output of <pthread-mc-mutex-simpledeadlock.tesh:35> as requested)
17:13:52
17:13:52 Start 634: stack-overflow-raw
17:13:52 633/1134 Test #634: stack-overflow-raw .......................................... Passed 0.07 sec
17:13:52 Start 635: stack-overflow-ucontext
17:13:52 634/1134 Test #633: stack-overflow-boost ........................................ Passed 0.09 sec
17:13:52 Start 636: tesh-kernel-context-default
17:13:52 635/1134 Test #635: stack-overflow-ucontext ..................................... Passed 0.07 sec
17:13:52 Start 637: tesh-kernel-factory-raw
17:13:52 636/1134 Test #636: tesh-kernel-context-default ................................. Passed 0.07 sec
17:13:52 Start 638: tesh-kernel-factory-thread
17:13:52 637/1134 Test #637: tesh-kernel-factory-raw ..................................... Passed 0.08 sec
17:13:52 Start 639: tesh-kernel-factory-boost
17:13:52 638/1134 Test #638: tesh-kernel-factory-thread .................................. Passed 0.07 sec
17:13:52 Start 640: tesh-kernel-factory-ucontext
17:13:52 639/1134 Test #639: tesh-kernel-factory-boost ................................... Passed 0.07 sec
17:13:52 Start 641: mc-random-bug-replay
17:13:52 640/1134 Test #640: tesh-kernel-factory-ucontext ................................ Passed 0.08 sec
17:13:52 Start 642: tesh-mc-mutex-handling
17:13:52 641/1134 Test #641: mc-random-bug-replay ........................................ Passed 0.12 sec
17:13:52 Start 643: tesh-mc-mutex-handling-dpor
17:13:53 642/1134 Test #643: tesh-mc-mutex-handling-dpor ................................. Passed 0.14 sec
17:13:53 Start 644: tesh-mc-without-mutex-handling
17:13:53 643/1134 Test #644: tesh-mc-without-mutex-handling .............................. Passed 0.19 sec
17:13:53 Start 645: tesh-mc-without-mutex-handling-dpor
17:13:53 644/1134 Test #645: tesh-mc-without-mutex-handling-dpor ......................... Passed 0.13 sec
17:13:53 Start 646: mc-random-bug
17:13:53 645/1134 Test #642: tesh-mc-mutex-handling ...................................... Passed 0.70 sec
17:13:53 Start 647: mc-mini-simple_barrier_ok
17:13:53 646/1134 Test #647: mc-mini-simple_barrier_ok ................................... Passed 0.11 sec
17:13:53 Start 648: mc-mini-simple_barrier_deadlock
17:13:53 647/1134 Test #648: mc-mini-simple_barrier_deadlock ............................. Passed 0.10 sec
17:13:53 Start 649: mc-mini-simple_barrier_with_threads_ok
17:13:53 648/1134 Test #649: mc-mini-simple_barrier_with_threads_ok ...................... Passed 0.15 sec
17:13:53 Start 650: mc-mini-simple_barrier_with_threads_deadlock
17:13:53 649/1134 Test #650: mc-mini-simple_barrier_with_threads_deadlock ................ Passed 0.11 sec
17:13:53 Start 651: mc-mini-simple_cond_ok
17:13:54 650/1134 Test #651: mc-mini-simple_cond_ok ...................................... Passed 0.11 sec
17:13:54 Start 652: mc-mini-simple_cond_deadlock
17:13:54 651/1134 Test #652: mc-mini-simple_cond_deadlock ................................ Passed 0.12 sec
17:13:54 Start 653: mc-mini-simple_cond_broadcast_ok
17:13:54 652/1134 Test #646: mc-random-bug ............................................... Passed 0.88 sec
17:13:54 Start 654: mc-mini-simple_cond_broadcast_deadlock
17:13:54 653/1134 Test #654: mc-mini-simple_cond_broadcast_deadlock ...................... Passed 0.13 sec
17:13:54 Start 655: mc-mini-simple_mutex_ok
17:13:54 654/1134 Test #655: mc-mini-simple_mutex_ok ..................................... Passed 0.23 sec
17:13:54 Start 656: mc-mini-simple_mutex_deadlock
17:13:54 655/1134 Test #656: mc-mini-simple_mutex_deadlock ............................... Passed 0.17 sec
17:13:54 Start 657: mc-mini-simple_mutex_with_threads_ok
17:13:54 656/1134 Test #653: mc-mini-simple_cond_broadcast_ok ............................ Passed 0.63 sec
17:13:54 Start 658: mc-mini-simple_mutex_with_threads_deadlock
17:13:54 657/1134 Test #658: mc-mini-simple_mutex_with_threads_deadlock .................. Passed 0.16 sec
17:13:54 Start 659: mc-mini-simple_semaphore_deadlock
17:13:55 658/1134 Test #659: mc-mini-simple_semaphore_deadlock ........................... Passed 0.12 sec
17:13:55 Start 660: mc-mini-simple_semaphores_deadlock
17:13:55 659/1134 Test #660: mc-mini-simple_semaphores_deadlock .......................... Passed 0.11 sec
17:13:55 Start 661: mc-mini-simple_semaphores_ok
17:13:55 660/1134 Test #661: mc-mini-simple_semaphores_ok ................................ Passed 0.13 sec
17:13:55 Start 662: mc-mini-simple_semaphores_with_threads_ok
17:13:55 661/1134 Test #662: mc-mini-simple_semaphores_with_threads_ok ................... Passed 0.29 sec
17:13:55 Start 663: mc-mini-simple_semaphores_with_threads_deadlock
17:13:55 662/1134 Test #663: mc-mini-simple_semaphores_with_threads_deadlock ............. Passed 0.11 sec
17:13:55 Start 664: mc-mini-simple_threads_ok
17:13:55 663/1134 Test #657: mc-mini-simple_mutex_with_threads_ok ........................***Failed 1.04 sec
17:13:55 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:55 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/teshsuite/mc/mcmini/simple_mutex_with_threads_ok.tesh'
17:13:55 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:55 [Tesh/INFO] setenv bindir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc
17:13:55 [simple_mutex_with_threads_ok.tesh:4] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:dpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsgmalloc.so:/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-simple_mutex_with_threads_ok 4
17:13:55 Output of <simple_mutex_with_threads_ok.tesh:4> mismatch:
17:13:55 --- expected
17:13:55 +++ obtained
17:13:55 @@ -1,3 +1,3 @@
17:13:55 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'model-check/reduction' to 'dpor'
17:13:55 [0.000000] [mc_dfs/INFO] Start a DFS exploration. Reduction is: dpor.
17:13:55 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 623 unique states visited; 40 backtracks (279 transition replays, 902 states visited overall)
17:13:55 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 593 unique states visited; 23 backtracks (80 transition replays, 673 states visited overall)
17:13:55 Test suite `simple_mutex_with_threads_ok.tesh': NOK (<simple_mutex_with_threads_ok.tesh:4> output mismatch)
17:13:55 [simple_mutex_with_threads_ok.tesh:9] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:dpor --cfg=model-check/exploration-algo:OOO --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsgmalloc.so:/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-simple_mutex_with_threads_ok 4
17:13:55 Output of <simple_mutex_with_threads_ok.tesh:9> mismatch:
17:13:55 --- expected
17:13:55 +++ obtained
17:13:55 @@ -1,4 +1,4 @@
17:13:55 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'model-check/reduction' to 'dpor'
17:13:55 [0.000000] [xbt_cfg/INFO] Configuration change: Set 'model-check/exploration-algo' to 'OOO'
17:13:55 [0.000000] [mc_ooo/INFO] Start an Out-of-Order exploration. Reduction is: dpor.
17:13:55 -[0.000000] [mc_ooo/INFO] Out-of-Order exploration ended. 623 unique states visited; 40 backtracks (279 transition replays, 902 states visited overall)
17:13:55 +[0.000000] [mc_ooo/INFO] Out-of-Order exploration ended. 593 unique states visited; 23 backtracks (80 transition replays, 673 states visited overall)
17:13:55 Test suite `simple_mutex_with_threads_ok.tesh': NOK (<simple_mutex_with_threads_ok.tesh:9> output mismatch)
17:13:55 [simple_mutex_with_threads_ok.tesh:15] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:odpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsgmalloc.so:/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-simple_mutex_with_threads_ok 4
17:13:55
17:13:55 [simple_mutex_with_threads_ok.tesh:20] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:udpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsgmalloc.so:/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-simple_mutex_with_threads_ok 4
17:13:55
17:13:55
17:13:55 Start 665: mc-mini-barber_shop_ok
17:13:55 664/1134 Test #664: mc-mini-simple_threads_ok ................................... Passed 0.12 sec
17:13:55 Start 666: mc-mini-barber_shop_deadlock
17:13:55 665/1134 Test #666: mc-mini-barber_shop_deadlock ................................ Passed 0.15 sec
17:13:55 Start 667: mc-mini-philosophers_semaphores_ok
17:13:56 666/1134 Test #667: mc-mini-philosophers_semaphores_ok .......................... Passed 0.18 sec
17:13:56 Start 668: mc-mini-philosophers_semaphores_deadlock
17:13:56 667/1134 Test #665: mc-mini-barber_shop_ok ...................................... Passed 0.64 sec
17:13:56 Start 669: mc-mini-philosophers_mutex_ok
17:13:56 668/1134 Test #668: mc-mini-philosophers_semaphores_deadlock ....................***Failed 0.42 sec
17:13:56 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:56 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/teshsuite/mc/mcmini/philosophers_semaphores_deadlock.tesh'
17:13:56 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:56 [Tesh/INFO] setenv bindir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc
17:13:56 [philosophers_semaphores_deadlock.tesh:5] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-philosophers_semaphores_deadlock 3 0
17:13:56 Output of <philosophers_semaphores_deadlock.tesh:5> mismatch:
17:13:56 --- expected
17:13:56 +++ obtained
17:13:56 @@ -24,4 +24,4 @@
17:13:56 [0.000000] [mc_global/INFO] Actor 4 in simcall MUTEX_WAIT(mutex: 2, owner: 4)
17:13:56 [0.000000] [mc_global/INFO] Actor 4 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:56 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;2;2;3;3;3;2;3;4;4;4;3;4;4'
17:13:56 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 618 unique states visited; 53 backtracks (809 transition replays, 1427 states visited overall)
17:13:56 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 517 unique states visited; 30 backtracks (399 transition replays, 916 states visited overall)
17:13:56 Test suite `philosophers_semaphores_deadlock.tesh': NOK (<philosophers_semaphores_deadlock.tesh:5> output mismatch)
17:13:56 [philosophers_semaphores_deadlock.tesh:35] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/exploration-algo:OOO --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-philosophers_semaphores_deadlock 3 0
17:13:56 Output of <philosophers_semaphores_deadlock.tesh:35> mismatch:
17:13:56 --- expected
17:13:56 +++ obtained
17:13:56 @@ -25,4 +25,4 @@
17:13:56 [0.000000] [mc_global/INFO] Actor 4 in simcall MUTEX_WAIT(mutex: 2, owner: 4)
17:13:56 [0.000000] [mc_global/INFO] Actor 4 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:56 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;2;2;3;3;3;2;3;4;4;4;3;4;4'
17:13:56 -[0.000000] [mc_ooo/INFO] Out-of-Order exploration ended. 618 unique states visited; 53 backtracks (809 transition replays, 1427 states visited overall)
17:13:56 +[0.000000] [mc_ooo/INFO] Out-of-Order exploration ended. 517 unique states visited; 30 backtracks (399 transition replays, 916 states visited overall)
17:13:56 Test suite `philosophers_semaphores_deadlock.tesh': NOK (<philosophers_semaphores_deadlock.tesh:35> output mismatch)
17:13:56
17:13:56 Start 670: mc-mini-philosophers_mutex_deadlock
17:13:56 669/1134 Test #670: mc-mini-philosophers_mutex_deadlock .........................***Failed 0.37 sec
17:13:56 Ignore all cruft seen on SimGrid's continuous integration servers
17:13:56 Test suite '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/teshsuite/mc/mcmini/philosophers_mutex_deadlock.tesh'
17:13:56 [Tesh/INFO] setenv libdir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib
17:13:56 [Tesh/INFO] setenv bindir=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc
17:13:56 [philosophers_mutex_deadlock.tesh:5] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:dpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-philosophers_mutex_deadlock 4 0
17:13:56 Output of <philosophers_mutex_deadlock.tesh:5> mismatch:
17:13:56 --- expected
17:13:56 +++ obtained
17:13:56 @@ -23,4 +23,4 @@
17:13:56 [0.000000] [mc_global/INFO] Actor 5 in simcall MUTEX_WAIT(mutex: 3, owner: 5)
17:13:56 [0.000000] [mc_global/INFO] Actor 5 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:56 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;3;2;3;4;3;4;5;4;5;5'
17:13:56 -[0.000000] [mc_dfs/INFO] DFS exploration ended. 296 unique states visited; 36 backtracks (494 transition replays, 790 states visited overall)
17:13:56 +[0.000000] [mc_dfs/INFO] DFS exploration ended. 141 unique states visited; 7 backtracks (68 transition replays, 209 states visited overall)
17:13:56 Test suite `philosophers_mutex_deadlock.tesh': NOK (<philosophers_mutex_deadlock.tesh:5> output mismatch)
17:13:56 [philosophers_mutex_deadlock.tesh:34] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:dpor --cfg=model-check/exploration-algo:OOO --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-philosophers_mutex_deadlock 4 0
17:13:56 Output of <philosophers_mutex_deadlock.tesh:34> mismatch:
17:13:56 --- expected
17:13:56 +++ obtained
17:13:56 @@ -24,4 +24,4 @@
17:13:56 [0.000000] [mc_global/INFO] Actor 5 in simcall MUTEX_WAIT(mutex: 3, owner: 5)
17:13:56 [0.000000] [mc_global/INFO] Actor 5 in simcall MUTEX_ASYNC_LOCK(mutex: 0, owner: 2)
17:13:56 [0.000000] [mc_Session/INFO] You can debug the problem (and see the whole details) by rerunning out of simgrid-mc with --cfg=model-check/replay:'2;2;3;2;3;4;3;4;5;4;5;5'
17:13:56 -[0.000000] [mc_ooo/INFO] Out-of-Order exploration ended. 296 unique states visited; 36 backtracks (494 transition replays, 790 states visited overall)
17:13:56 +[0.000000] [mc_ooo/INFO] Out-of-Order exploration ended. 141 unique states visited; 7 backtracks (68 transition replays, 209 states visited overall)
17:13:56 Test suite `philosophers_mutex_deadlock.tesh': NOK (<philosophers_mutex_deadlock.tesh:34> output mismatch)
17:13:56 [philosophers_mutex_deadlock.tesh:64] /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/../../bin/simgrid-mc --cfg=model-check/reduction:odpor --cfg=model-check/setenv:LD_PRELOAD=/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/lib/libsthread.so /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/teshsuite/mc/mcmini/mcmini-philosophers_mutex_deadlock 5 0
17:13:56
17:13:56
17:13:56 Start 671: mc-mini-producer_consumer_ok
17:13:57 670/1134 Test #671: mc-mini-producer_consumer_ok ................................ Passed 0.38 sec
17:13:57 Start 672: mc-mini-producer_consumer_deadlock
17:13:57 671/1134 Test #672: mc-mini-producer_consumer_deadlock .......................... Passed 0.15 sec
17:13:57 Start 673: tesh-model-lmm_usage
17:13:57 672/1134 Test #673: tesh-model-lmm_usage ........................................ Passed 0.09 sec
17:13:57 Start 674: tesh-model-core_usage
17:13:57 673/1134 Test #674: tesh-model-core_usage ....................................... Passed 0.09 sec
17:13:57 Start 675: tesh-model-core_usage2
17:13:57 674/1134 Test #675: tesh-model-core_usage2 ...................................... Passed 0.08 sec
17:13:57 Start 676: tesh-model-cloud-sharing
17:13:57 675/1134 Test #676: tesh-model-cloud-sharing .................................... Passed 0.11 sec
17:13:57 Start 677: tesh-model-ptask_L07_usage
17:13:57 676/1134 Test #677: tesh-model-ptask_L07_usage .................................. Passed 0.11 sec
17:13:57 Start 678: tesh-model-wifi_usage
17:13:58 677/1134 Test #678: tesh-model-wifi_usage ....................................... Passed 0.13 sec
17:13:58 Start 679: tesh-model-wifi_usage_decay
17:13:58 678/1134 Test #679: tesh-model-wifi_usage_decay ................................. Passed 0.08 sec
17:13:58 Start 680: tesh-model-cm02-set-lat-bw
17:13:58 679/1134 Test #680: tesh-model-cm02-set-lat-bw .................................. Passed 0.08 sec
17:13:58 Start 681: tesh-model-cm02-tcpgamma
17:13:58 680/1134 Test #681: tesh-model-cm02-tcpgamma .................................... Passed 0.08 sec
17:13:58 Start 682: tesh-model-issue105
17:13:58 681/1134 Test #682: tesh-model-issue105 ......................................... Passed 0.08 sec
17:13:58 Start 683: tesh-model-ptask-subflows
17:13:58 682/1134 Test #683: tesh-model-ptask-subflows ................................... Passed 0.11 sec
17:13:58 Start 684: tesh-model-cm02-set-lat-bw-bmf
17:13:58 683/1134 Test #684: tesh-model-cm02-set-lat-bw-bmf .............................. Passed 0.07 sec
17:13:58 Start 685: tesh-maxmin-small
17:13:58 684/1134 Test #685: tesh-maxmin-small ........................................... Passed 0.15 sec
17:13:58 Start 686: tesh-maxmin-medium
17:13:59 685/1134 Test #686: tesh-maxmin-medium .......................................... Passed 0.48 sec
17:13:59 Start 687: tesh-maxmin-large
17:14:00 686/1134 Test #687: tesh-maxmin-large ........................................... Passed 0.91 sec
17:14:00 Start 688: tesh-platform-flatifier
17:14:00 687/1134 Test #688: tesh-platform-flatifier ..................................... Passed 0.52 sec
17:14:00 Start 689: tesh-parser-bogus-symmetric
17:14:00 688/1134 Test #689: tesh-parser-bogus-symmetric ................................. Passed 0.07 sec
17:14:00 Start 690: tesh-parser-bogus-missing-gw
17:14:00 689/1134 Test #690: tesh-parser-bogus-missing-gw ................................ Passed 0.10 sec
17:14:00 Start 691: tesh-python-borken-context
17:14:00 690/1134 Test #691: tesh-python-borken-context .................................. Passed 0.11 sec
17:14:00 Start 692: tesh-python-corrupt-stack
17:14:01 691/1134 Test #692: tesh-python-corrupt-stack ................................... Passed 0.13 sec
17:14:01 Start 693: tesh-python-platform-mix
17:14:01 692/1134 Test #693: tesh-python-platform-mix .................................... Passed 0.11 sec
17:14:01 Start 694: tesh-s4u-actor-thread
17:14:01 693/1134 Test #694: tesh-s4u-actor-thread ....................................... Passed 0.08 sec
17:14:01 Start 695: tesh-s4u-actor-boost
17:14:01 694/1134 Test #695: tesh-s4u-actor-boost ........................................ Passed 0.07 sec
17:14:01 Start 696: tesh-s4u-actor-raw
17:14:01 695/1134 Test #696: tesh-s4u-actor-raw .......................................... Passed 0.07 sec
17:14:01 Start 697: tesh-s4u-actor-ucontext
17:14:01 696/1134 Test #697: tesh-s4u-actor-ucontext ..................................... Passed 0.07 sec
17:14:01 Start 698: tesh-s4u-actor-autorestart-thread
17:14:01 697/1134 Test #698: tesh-s4u-actor-autorestart-thread ........................... Passed 0.08 sec
17:14:01 Start 699: tesh-s4u-actor-autorestart-boost
17:14:01 698/1134 Test #699: tesh-s4u-actor-autorestart-boost ............................ Passed 0.07 sec
17:14:01 Start 700: tesh-s4u-actor-autorestart-raw
17:14:01 699/1134 Test #700: tesh-s4u-actor-autorestart-raw .............................. Passed 0.07 sec
17:14:01 Start 701: tesh-s4u-actor-autorestart-ucontext
17:14:01 700/1134 Test #701: tesh-s4u-actor-autorestart-ucontext ......................... Passed 0.07 sec
17:14:01 Start 702: tesh-s4u-actor-suspend-thread
17:14:01 701/1134 Test #702: tesh-s4u-actor-suspend-thread ............................... Passed 0.08 sec
17:14:01 Start 703: tesh-s4u-actor-suspend-boost
17:14:01 702/1134 Test #703: tesh-s4u-actor-suspend-boost ................................ Passed 0.07 sec
17:14:01 Start 704: tesh-s4u-actor-suspend-raw
17:14:02 703/1134 Test #704: tesh-s4u-actor-suspend-raw .................................. Passed 0.07 sec
17:14:02 Start 705: tesh-s4u-actor-suspend-ucontext
17:14:02 704/1134 Test #705: tesh-s4u-actor-suspend-ucontext ............................. Passed 0.07 sec
17:14:02 Start 706: tesh-s4u-activity-lifecycle-thread
17:14:02 705/1134 Test #706: tesh-s4u-activity-lifecycle-thread .......................... Passed 0.14 sec
17:14:02 Start 707: tesh-s4u-activity-lifecycle-boost
17:14:02 706/1134 Test #707: tesh-s4u-activity-lifecycle-boost ........................... Passed 0.11 sec
17:14:02 Start 708: tesh-s4u-activity-lifecycle-raw
17:14:02 707/1134 Test #708: tesh-s4u-activity-lifecycle-raw ............................. Passed 0.11 sec
17:14:02 Start 709: tesh-s4u-activity-lifecycle-ucontext
17:14:02 708/1134 Test #709: tesh-s4u-activity-lifecycle-ucontext ........................ Passed 0.10 sec
17:14:02 Start 710: tesh-s4u-comm-get-sender-thread
17:14:02 709/1134 Test #710: tesh-s4u-comm-get-sender-thread ............................. Passed 0.10 sec
17:14:02 Start 711: tesh-s4u-comm-get-sender-boost
17:14:02 710/1134 Test #711: tesh-s4u-comm-get-sender-boost .............................. Passed 0.08 sec
17:14:02 Start 712: tesh-s4u-comm-get-sender-raw
17:14:02 711/1134 Test #712: tesh-s4u-comm-get-sender-raw ................................ Passed 0.08 sec
17:14:02 Start 713: tesh-s4u-comm-get-sender-ucontext
17:14:02 712/1134 Test #713: tesh-s4u-comm-get-sender-ucontext ........................... Passed 0.08 sec
17:14:02 Start 714: tesh-s4u-cloud-interrupt-migration-thread
17:14:02 713/1134 Test #714: tesh-s4u-cloud-interrupt-migration-thread ................... Passed 0.08 sec
17:14:02 Start 715: tesh-s4u-cloud-interrupt-migration-boost
17:14:03 714/1134 Test #715: tesh-s4u-cloud-interrupt-migration-boost .................... Passed 0.07 sec
17:14:03 Start 716: tesh-s4u-cloud-interrupt-migration-raw
17:14:03 715/1134 Test #716: tesh-s4u-cloud-interrupt-migration-raw ...................... Passed 0.08 sec
17:14:03 Start 717: tesh-s4u-cloud-interrupt-migration-ucontext
17:14:03 716/1134 Test #717: tesh-s4u-cloud-interrupt-migration-ucontext ................. Passed 0.09 sec
17:14:03 Start 718: tesh-s4u-cloud-two-execs-thread
17:14:03 717/1134 Test #718: tesh-s4u-cloud-two-execs-thread ............................. Passed 0.10 sec
17:14:03 Start 719: tesh-s4u-cloud-two-execs-boost
17:14:03 718/1134 Test #719: tesh-s4u-cloud-two-execs-boost .............................. Passed 0.09 sec
17:14:03 Start 720: tesh-s4u-cloud-two-execs-raw
17:14:03 719/1134 Test #720: tesh-s4u-cloud-two-execs-raw ................................ Passed 0.09 sec
17:14:03 Start 721: tesh-s4u-cloud-two-execs-ucontext
17:14:03 720/1134 Test #721: tesh-s4u-cloud-two-execs-ucontext ........................... Passed 0.09 sec
17:14:03 Start 722: tesh-s4u-concurrent_rw-thread
17:14:03 721/1134 Test #722: tesh-s4u-concurrent_rw-thread ............................... Passed 0.09 sec
17:14:03 Start 723: tesh-s4u-concurrent_rw-boost
17:14:03 722/1134 Test #723: tesh-s4u-concurrent_rw-boost ................................ Passed 0.07 sec
17:14:03 Start 724: tesh-s4u-concurrent_rw-raw
17:14:03 723/1134 Test #724: tesh-s4u-concurrent_rw-raw .................................. Passed 0.07 sec
17:14:03 Start 725: tesh-s4u-concurrent_rw-ucontext
17:14:03 724/1134 Test #725: tesh-s4u-concurrent_rw-ucontext ............................. Passed 0.08 sec
17:14:03 Start 726: tesh-s4u-dag-incomplete-simulation-thread
17:14:03 725/1134 Test #726: tesh-s4u-dag-incomplete-simulation-thread ................... Passed 0.08 sec
17:14:03 Start 727: tesh-s4u-dag-incomplete-simulation-boost
17:14:04 726/1134 Test #727: tesh-s4u-dag-incomplete-simulation-boost .................... Passed 0.07 sec
17:14:04 Start 728: tesh-s4u-dag-incomplete-simulation-raw
17:14:04 727/1134 Test #728: tesh-s4u-dag-incomplete-simulation-raw ...................... Passed 0.07 sec
17:14:04 Start 729: tesh-s4u-dag-incomplete-simulation-ucontext
17:14:04 728/1134 Test #729: tesh-s4u-dag-incomplete-simulation-ucontext ................. Passed 0.07 sec
17:14:04 Start 730: tesh-s4u-dependencies-thread
17:14:04 729/1134 Test #730: tesh-s4u-dependencies-thread ................................ Passed 0.08 sec
17:14:04 Start 731: tesh-s4u-dependencies-boost
17:14:04 730/1134 Test #731: tesh-s4u-dependencies-boost ................................. Passed 0.07 sec
17:14:04 Start 732: tesh-s4u-dependencies-raw
17:14:04 731/1134 Test #732: tesh-s4u-dependencies-raw ................................... Passed 0.07 sec
17:14:04 Start 733: tesh-s4u-dependencies-ucontext
17:14:04 732/1134 Test #733: tesh-s4u-dependencies-ucontext .............................. Passed 0.09 sec
17:14:04 Start 734: tesh-s4u-io-set-bw-thread
17:14:04 733/1134 Test #734: tesh-s4u-io-set-bw-thread ................................... Passed 0.09 sec
17:14:04 Start 735: tesh-s4u-io-set-bw-boost
17:14:04 734/1134 Test #735: tesh-s4u-io-set-bw-boost .................................... Passed 0.08 sec
17:14:04 Start 736: tesh-s4u-io-set-bw-raw
17:14:04 735/1134 Test #736: tesh-s4u-io-set-bw-raw ...................................... Passed 0.07 sec
17:14:04 Start 737: tesh-s4u-io-set-bw-ucontext
17:14:04 736/1134 Test #737: tesh-s4u-io-set-bw-ucontext ................................. Passed 0.07 sec
17:14:04 Start 738: tesh-s4u-io-stream-thread
17:14:04 737/1134 Test #738: tesh-s4u-io-stream-thread ................................... Passed 0.15 sec
17:14:04 Start 739: tesh-s4u-io-stream-boost
17:14:05 738/1134 Test #739: tesh-s4u-io-stream-boost .................................... Passed 0.10 sec
17:14:05 Start 740: tesh-s4u-io-stream-raw
17:14:05 739/1134 Test #740: tesh-s4u-io-stream-raw ...................................... Passed 0.12 sec
17:14:05 Start 741: tesh-s4u-io-stream-ucontext
17:14:05 740/1134 Test #741: tesh-s4u-io-stream-ucontext ................................. Passed 0.10 sec
17:14:05 Start 742: tesh-s4u-vm-live-migration-thread
17:14:05 741/1134 Test #742: tesh-s4u-vm-live-migration-thread ........................... Passed 0.08 sec
17:14:05 Start 743: tesh-s4u-vm-live-migration-boost
17:14:05 742/1134 Test #743: tesh-s4u-vm-live-migration-boost ............................ Passed 0.07 sec
17:14:05 Start 744: tesh-s4u-vm-live-migration-raw
17:14:05 743/1134 Test #744: tesh-s4u-vm-live-migration-raw .............................. Passed 0.07 sec
17:14:05 Start 745: tesh-s4u-vm-live-migration-ucontext
17:14:05 744/1134 Test #745: tesh-s4u-vm-live-migration-ucontext ......................... Passed 0.07 sec
17:14:05 Start 746: tesh-s4u-vm-suicide-thread
17:14:05 745/1134 Test #746: tesh-s4u-vm-suicide-thread .................................. Passed 0.08 sec
17:14:05 Start 747: tesh-s4u-vm-suicide-boost
17:14:05 746/1134 Test #747: tesh-s4u-vm-suicide-boost ................................... Passed 0.07 sec
17:14:05 Start 748: tesh-s4u-vm-suicide-raw
17:14:05 747/1134 Test #748: tesh-s4u-vm-suicide-raw ..................................... Passed 0.07 sec
17:14:05 Start 749: tesh-s4u-vm-suicide-ucontext
17:14:05 748/1134 Test #749: tesh-s4u-vm-suicide-ucontext ................................ Passed 0.07 sec
17:14:05 Start 750: tesh-s4u-basic-link-test
17:14:05 749/1134 Test #750: tesh-s4u-basic-link-test .................................... Passed 0.11 sec
17:14:05 Start 751: tesh-s4u-basic-parsing-test
17:14:06 750/1134 Test #751: tesh-s4u-basic-parsing-test ................................. Passed 0.37 sec
17:14:06 Start 752: tesh-s4u-host-on-off
17:14:06 751/1134 Test #752: tesh-s4u-host-on-off ........................................ Passed 0.08 sec
17:14:06 Start 753: tesh-s4u-host-on-off-actors
17:14:06 752/1134 Test #753: tesh-s4u-host-on-off-actors ................................. Passed 0.21 sec
17:14:06 Start 754: tesh-s4u-host-on-off-recv
17:14:06 753/1134 Test #754: tesh-s4u-host-on-off-recv ................................... Passed 0.09 sec
17:14:06 Start 755: tesh-s4u-comm-fault-scenarios
17:14:06 754/1134 Test #755: tesh-s4u-comm-fault-scenarios ............................... Passed 0.11 sec
17:14:06 Start 756: tesh-s4u-host-multicore-speed-file
17:14:06 755/1134 Test #756: tesh-s4u-host-multicore-speed-file .......................... Passed 0.08 sec
17:14:06 Start 757: tesh-s4u-is-router
17:14:07 756/1134 Test #757: tesh-s4u-is-router .......................................... Passed 0.08 sec
17:14:07 Start 758: tesh-s4u-listen_async
17:14:07 757/1134 Test #758: tesh-s4u-listen_async ....................................... Passed 0.07 sec
17:14:07 Start 759: tesh-s4u-monkey-masterworkers
17:14:07 758/1134 Test #759: tesh-s4u-monkey-masterworkers ............................... Passed 0.08 sec
17:14:07 Start 760: tesh-s4u-monkey-semaphore
17:14:07 759/1134 Test #760: tesh-s4u-monkey-semaphore ................................... Passed 0.08 sec
17:14:07 Start 761: tesh-s4u-pid
17:14:07 760/1134 Test #761: tesh-s4u-pid ................................................ Passed 0.08 sec
17:14:07 Start 762: tesh-s4u-storage_client_server
17:14:07 761/1134 Test #762: tesh-s4u-storage_client_server .............................. Passed 0.08 sec
17:14:07 Start 763: tesh-s4u-trace-integration
17:14:07 762/1134 Test #669: mc-mini-philosophers_mutex_ok ............................... Passed 11.16 sec
17:14:07 Start 764: tesh-s4u-seal-platform
17:14:07 763/1134 Test #764: tesh-s4u-seal-platform ...................................... Passed 0.08 sec
17:14:07 Start 765: tesh-s4u-issue71
17:14:07 764/1134 Test #765: tesh-s4u-issue71 ............................................ Passed 0.08 sec
17:14:07 Start 766: tesh-python-monkey-masterworkers
17:14:07 765/1134 Test #766: tesh-python-monkey-masterworkers ............................ Passed 0.11 sec
17:14:07 Start 767: monkey-s4u-monkey-masterworkers
17:14:07 766/1134 Test #763: tesh-s4u-trace-integration .................................. Passed 0.53 sec
17:14:07 Start 768: monkey-python-monkey-masterworkers
17:14:09 767/1134 Test #767: monkey-s4u-monkey-masterworkers ............................. Passed 2.10 sec
17:14:09 Start 769: monkey-s4u-monkey-semaphore
17:14:11 768/1134 Test #769: monkey-s4u-monkey-semaphore ................................. Passed 1.81 sec
17:14:11 Start 770: tesh-s4u-comm-pt2pt
17:14:11 769/1134 Test #770: tesh-s4u-comm-pt2pt ......................................... Passed 0.04 sec
17:14:11 Start 771: tesh-s4u-ns3-simultaneous-send-rcv
17:14:12 770/1134 Test #771: tesh-s4u-ns3-simultaneous-send-rcv .......................... Passed 0.65 sec
17:14:12 Start 772: tesh-s4u-ns3-from-src-to-itself
17:14:12 771/1134 Test #772: tesh-s4u-ns3-from-src-to-itself ............................. Passed 0.13 sec
17:14:12 Start 773: tesh-s4u-cloud-two-execs-bmf
17:14:12 772/1134 Test #773: tesh-s4u-cloud-two-execs-bmf ................................ Passed 0.08 sec
17:14:12 Start 774: tesh-s4u-concurrent_rw-bmf
17:14:12 773/1134 Test #774: tesh-s4u-concurrent_rw-bmf .................................. Passed 0.07 sec
17:14:12 Start 775: test-help-version
17:14:12 774/1134 Test #775: test-help-version ........................................... Passed 0.03 sec
17:14:12 Start 776: test-help-logs
17:14:12 775/1134 Test #776: test-help-logs .............................................. Passed 0.03 sec
17:14:12 Start 777: tesh-parser-bypass
17:14:12 776/1134 Test #777: tesh-parser-bypass .......................................... Passed 0.10 sec
17:14:12 Start 778: tesh-parser-sym-full
17:14:13 777/1134 Test #778: tesh-parser-sym-full ........................................ Passed 0.23 sec
17:14:13 Start 779: tesh-parser-full-links01
17:14:13 778/1134 Test #779: tesh-parser-full-links01 .................................... Passed 0.03 sec
17:14:13 Start 780: tesh-parser-full-links02
17:14:13 779/1134 Test #780: tesh-parser-full-links02 .................................... Passed 0.04 sec
17:14:13 Start 781: tesh-parser-one-link-g5k
17:14:13 780/1134 Test #781: tesh-parser-one-link-g5k .................................... Passed 0.06 sec
17:14:13 Start 782: test-smpi-help-coll
17:14:13 781/1134 Test #782: test-smpi-help-coll ......................................... Passed 0.03 sec
17:14:13 Start 783: tesh-smpi-macro-shared-thread
17:14:13 782/1134 Test #783: tesh-smpi-macro-shared-thread ............................... Passed 0.13 sec
17:14:13 Start 784: tesh-smpi-macro-shared-boost
17:14:13 783/1134 Test #768: monkey-python-monkey-masterworkers .......................... Passed 5.56 sec
17:14:13 Start 785: tesh-smpi-macro-shared-raw
17:14:13 784/1134 Test #784: tesh-smpi-macro-shared-boost ................................ Passed 0.12 sec
17:14:13 Start 786: tesh-smpi-macro-shared-ucontext
17:14:13 785/1134 Test #785: tesh-smpi-macro-shared-raw .................................. Passed 0.12 sec
17:14:13 Start 787: tesh-smpi-auto-shared-thread
17:14:13 786/1134 Test #786: tesh-smpi-macro-shared-ucontext ............................. Passed 0.11 sec
17:14:13 Start 788: tesh-smpi-auto-shared-boost
17:14:13 787/1134 Test #788: tesh-smpi-auto-shared-boost ................................. Passed 0.12 sec
17:14:13 Start 789: tesh-smpi-auto-shared-raw
17:14:13 788/1134 Test #787: tesh-smpi-auto-shared-thread ................................ Passed 0.12 sec
17:14:13 Start 790: tesh-smpi-auto-shared-ucontext
17:14:13 789/1134 Test #789: tesh-smpi-auto-shared-raw ................................... Passed 0.11 sec
17:14:13 Start 791: tesh-smpi-macro-partial-shared-thread
17:14:13 790/1134 Test #790: tesh-smpi-auto-shared-ucontext .............................. Passed 0.11 sec
17:14:13 Start 792: tesh-smpi-macro-partial-shared-boost
17:14:14 791/1134 Test #791: tesh-smpi-macro-partial-shared-thread ....................... Passed 0.22 sec
17:14:14 Start 793: tesh-smpi-macro-partial-shared-raw
17:14:14 792/1134 Test #792: tesh-smpi-macro-partial-shared-boost ........................ Passed 0.22 sec
17:14:14 Start 794: tesh-smpi-macro-partial-shared-ucontext
17:14:14 793/1134 Test #793: tesh-smpi-macro-partial-shared-raw .......................... Passed 0.19 sec
17:14:14 Start 795: tesh-smpi-macro-partial-shared-communication-thread
17:14:14 794/1134 Test #794: tesh-smpi-macro-partial-shared-ucontext ..................... Passed 0.20 sec
17:14:14 Start 796: tesh-smpi-macro-partial-shared-communication-boost
17:14:14 795/1134 Test #795: tesh-smpi-macro-partial-shared-communication-thread ......... Passed 0.17 sec
17:14:14 Start 797: tesh-smpi-macro-partial-shared-communication-raw
17:14:14 796/1134 Test #796: tesh-smpi-macro-partial-shared-communication-boost .......... Passed 0.16 sec
17:14:14 Start 798: tesh-smpi-macro-partial-shared-communication-ucontext
17:14:14 797/1134 Test #798: tesh-smpi-macro-partial-shared-communication-ucontext ....... Passed 0.14 sec
17:14:14 Start 799: tesh-smpi-coll-allgather-thread
17:14:14 798/1134 Test #797: tesh-smpi-macro-partial-shared-communication-raw ............ Passed 0.15 sec
17:14:14 Start 800: tesh-smpi-coll-allgather-boost
17:14:14 799/1134 Test #800: tesh-smpi-coll-allgather-boost .............................. Passed 0.13 sec
17:14:14 Start 801: tesh-smpi-coll-allgather-raw
17:14:14 800/1134 Test #799: tesh-smpi-coll-allgather-thread ............................. Passed 0.13 sec
17:14:14 Start 802: tesh-smpi-coll-allgather-ucontext
17:14:14 801/1134 Test #801: tesh-smpi-coll-allgather-raw ................................ Passed 0.11 sec
17:14:14 Start 803: tesh-smpi-coll-allgatherv-thread
17:14:14 802/1134 Test #802: tesh-smpi-coll-allgather-ucontext ........................... Passed 0.11 sec
17:14:14 Start 804: tesh-smpi-coll-allgatherv-boost
17:14:14 803/1134 Test #804: tesh-smpi-coll-allgatherv-boost ............................. Passed 0.12 sec
17:14:14 Start 805: tesh-smpi-coll-allgatherv-raw
17:14:14 804/1134 Test #803: tesh-smpi-coll-allgatherv-thread ............................ Passed 0.14 sec
17:14:14 Start 806: tesh-smpi-coll-allgatherv-ucontext
17:14:15 805/1134 Test #806: tesh-smpi-coll-allgatherv-ucontext .......................... Passed 0.10 sec
17:14:15 Start 807: tesh-smpi-coll-allreduce-thread
17:14:15 806/1134 Test #805: tesh-smpi-coll-allgatherv-raw ............................... Passed 0.12 sec
17:14:15 Start 808: tesh-smpi-coll-allreduce-boost
17:14:15 807/1134 Test #808: tesh-smpi-coll-allreduce-boost .............................. Passed 0.11 sec
17:14:15 Start 809: tesh-smpi-coll-allreduce-raw
17:14:15 808/1134 Test #807: tesh-smpi-coll-allreduce-thread ............................. Passed 0.11 sec
17:14:15 Start 810: tesh-smpi-coll-allreduce-ucontext
17:14:15 809/1134 Test #809: tesh-smpi-coll-allreduce-raw ................................ Passed 0.11 sec
17:14:15 Start 811: tesh-smpi-coll-alltoall-thread
17:14:15 810/1134 Test #810: tesh-smpi-coll-allreduce-ucontext ........................... Passed 0.10 sec
17:14:15 Start 812: tesh-smpi-coll-alltoall-boost
17:14:15 811/1134 Test #811: tesh-smpi-coll-alltoall-thread .............................. Passed 0.12 sec
17:14:15 Start 813: tesh-smpi-coll-alltoall-raw
17:14:15 812/1134 Test #812: tesh-smpi-coll-alltoall-boost ............................... Passed 0.12 sec
17:14:15 Start 814: tesh-smpi-coll-alltoall-ucontext
17:14:15 813/1134 Test #813: tesh-smpi-coll-alltoall-raw ................................. Passed 0.11 sec
17:14:15 Start 815: tesh-smpi-coll-alltoallv-thread
17:14:15 814/1134 Test #814: tesh-smpi-coll-alltoall-ucontext ............................ Passed 0.11 sec
17:14:15 Start 816: tesh-smpi-coll-alltoallv-boost
17:14:15 815/1134 Test #816: tesh-smpi-coll-alltoallv-boost .............................. Passed 0.13 sec
17:14:15 Start 817: tesh-smpi-coll-alltoallv-raw
17:14:15 816/1134 Test #815: tesh-smpi-coll-alltoallv-thread ............................. Passed 0.14 sec
17:14:15 Start 818: tesh-smpi-coll-alltoallv-ucontext
17:14:15 817/1134 Test #817: tesh-smpi-coll-alltoallv-raw ................................ Passed 0.11 sec
17:14:15 Start 819: tesh-smpi-coll-barrier-thread
17:14:15 818/1134 Test #818: tesh-smpi-coll-alltoallv-ucontext ........................... Passed 0.11 sec
17:14:15 Start 820: tesh-smpi-coll-barrier-boost
17:14:15 819/1134 Test #819: tesh-smpi-coll-barrier-thread ............................... Passed 0.12 sec
17:14:15 Start 821: tesh-smpi-coll-barrier-raw
17:14:15 820/1134 Test #820: tesh-smpi-coll-barrier-boost ................................ Passed 0.11 sec
17:14:15 Start 822: tesh-smpi-coll-barrier-ucontext
17:14:15 821/1134 Test #821: tesh-smpi-coll-barrier-raw .................................. Passed 0.10 sec
17:14:15 Start 823: tesh-smpi-coll-bcast-thread
17:14:15 822/1134 Test #822: tesh-smpi-coll-barrier-ucontext ............................. Passed 0.10 sec
17:14:15 Start 824: tesh-smpi-coll-bcast-boost
17:14:16 823/1134 Test #824: tesh-smpi-coll-bcast-boost .................................. Passed 0.12 sec
17:14:16 Start 825: tesh-smpi-coll-bcast-raw
17:14:16 824/1134 Test #823: tesh-smpi-coll-bcast-thread ................................. Passed 0.13 sec
17:14:16 Start 826: tesh-smpi-coll-bcast-ucontext
17:14:16 825/1134 Test #825: tesh-smpi-coll-bcast-raw .................................... Passed 0.11 sec
17:14:16 Start 827: tesh-smpi-coll-gather-thread
17:14:16 826/1134 Test #826: tesh-smpi-coll-bcast-ucontext ............................... Passed 0.10 sec
17:14:16 Start 828: tesh-smpi-coll-gather-boost
17:14:16 827/1134 Test #828: tesh-smpi-coll-gather-boost ................................. Passed 0.11 sec
17:14:16 Start 829: tesh-smpi-coll-gather-raw
17:14:16 828/1134 Test #827: tesh-smpi-coll-gather-thread ................................ Passed 0.12 sec
17:14:16 Start 830: tesh-smpi-coll-gather-ucontext
17:14:16 829/1134 Test #830: tesh-smpi-coll-gather-ucontext .............................. Passed 0.10 sec
17:14:16 Start 831: tesh-smpi-coll-reduce-thread
17:14:16 830/1134 Test #829: tesh-smpi-coll-gather-raw ................................... Passed 0.11 sec
17:14:16 Start 832: tesh-smpi-coll-reduce-boost
17:14:16 831/1134 Test #832: tesh-smpi-coll-reduce-boost ................................. Passed 0.12 sec
17:14:16 Start 833: tesh-smpi-coll-reduce-raw
17:14:16 832/1134 Test #831: tesh-smpi-coll-reduce-thread ................................ Passed 0.12 sec
17:14:16 Start 834: tesh-smpi-coll-reduce-ucontext
17:14:16 833/1134 Test #833: tesh-smpi-coll-reduce-raw ................................... Passed 0.10 sec
17:14:16 Start 835: tesh-smpi-coll-reduce-scatter-thread
17:14:16 834/1134 Test #834: tesh-smpi-coll-reduce-ucontext .............................. Passed 0.10 sec
17:14:16 Start 836: tesh-smpi-coll-reduce-scatter-boost
17:14:16 835/1134 Test #836: tesh-smpi-coll-reduce-scatter-boost ......................... Passed 0.11 sec
17:14:16 Start 837: tesh-smpi-coll-reduce-scatter-raw
17:14:16 836/1134 Test #835: tesh-smpi-coll-reduce-scatter-thread ........................ Passed 0.11 sec
17:14:16 Start 838: tesh-smpi-coll-reduce-scatter-ucontext
17:14:16 837/1134 Test #837: tesh-smpi-coll-reduce-scatter-raw ........................... Passed 0.10 sec
17:14:16 Start 839: tesh-smpi-coll-scatter-thread
17:14:16 838/1134 Test #838: tesh-smpi-coll-reduce-scatter-ucontext ...................... Passed 0.10 sec
17:14:16 Start 840: tesh-smpi-coll-scatter-boost
17:14:16 839/1134 Test #840: tesh-smpi-coll-scatter-boost ................................ Passed 0.11 sec
17:14:16 Start 841: tesh-smpi-coll-scatter-raw
17:14:16 840/1134 Test #839: tesh-smpi-coll-scatter-thread ............................... Passed 0.11 sec
17:14:16 Start 842: tesh-smpi-coll-scatter-ucontext
17:14:17 841/1134 Test #842: tesh-smpi-coll-scatter-ucontext ............................. Passed 0.10 sec
17:14:17 Start 843: tesh-smpi-macro-sample-thread
17:14:17 842/1134 Test #841: tesh-smpi-coll-scatter-raw .................................. Passed 0.11 sec
17:14:17 Start 844: tesh-smpi-macro-sample-boost
17:14:17 843/1134 Test #844: tesh-smpi-macro-sample-boost ................................ Passed 0.61 sec
17:14:17 Start 845: tesh-smpi-macro-sample-raw
17:14:17 844/1134 Test #843: tesh-smpi-macro-sample-thread ............................... Passed 0.66 sec
17:14:17 Start 846: tesh-smpi-macro-sample-ucontext
17:14:18 845/1134 Test #845: tesh-smpi-macro-sample-raw .................................. Passed 0.61 sec
17:14:18 Start 847: tesh-smpi-pt2pt-dsend-thread
17:14:18 846/1134 Test #846: tesh-smpi-macro-sample-ucontext ............................. Passed 0.61 sec
17:14:18 Start 848: tesh-smpi-pt2pt-dsend-boost
17:14:18 847/1134 Test #847: tesh-smpi-pt2pt-dsend-thread ................................ Passed 0.12 sec
17:14:18 Start 849: tesh-smpi-pt2pt-dsend-raw
17:14:18 848/1134 Test #848: tesh-smpi-pt2pt-dsend-boost ................................. Passed 0.11 sec
17:14:18 Start 850: tesh-smpi-pt2pt-dsend-ucontext
17:14:18 849/1134 Test #849: tesh-smpi-pt2pt-dsend-raw ................................... Passed 0.11 sec
17:14:18 Start 851: tesh-smpi-pt2pt-pingpong-thread
17:14:18 850/1134 Test #850: tesh-smpi-pt2pt-dsend-ucontext .............................. Passed 0.16 sec
17:14:18 Start 852: tesh-smpi-pt2pt-pingpong-boost
17:14:18 851/1134 Test #851: tesh-smpi-pt2pt-pingpong-thread ............................. Passed 0.10 sec
17:14:18 Start 853: tesh-smpi-pt2pt-pingpong-raw
17:14:18 852/1134 Test #852: tesh-smpi-pt2pt-pingpong-boost .............................. Passed 0.10 sec
17:14:18 Start 854: tesh-smpi-pt2pt-pingpong-ucontext
17:14:18 853/1134 Test #853: tesh-smpi-pt2pt-pingpong-raw ................................ Passed 0.09 sec
17:14:18 Start 855: tesh-smpi-type-hvector-thread
17:14:18 854/1134 Test #854: tesh-smpi-pt2pt-pingpong-ucontext ........................... Passed 0.09 sec
17:14:18 Start 856: tesh-smpi-type-hvector-boost
17:14:18 855/1134 Test #855: tesh-smpi-type-hvector-thread ............................... Passed 0.09 sec
17:14:18 Start 857: tesh-smpi-type-hvector-raw
17:14:18 856/1134 Test #856: tesh-smpi-type-hvector-boost ................................ Passed 0.09 sec
17:14:18 Start 858: tesh-smpi-type-hvector-ucontext
17:14:18 857/1134 Test #857: tesh-smpi-type-hvector-raw .................................. Passed 0.09 sec
17:14:18 Start 859: tesh-smpi-type-indexed-thread
17:14:18 858/1134 Test #858: tesh-smpi-type-hvector-ucontext ............................. Passed 0.09 sec
17:14:18 Start 860: tesh-smpi-type-indexed-boost
17:14:18 859/1134 Test #859: tesh-smpi-type-indexed-thread ............................... Passed 0.09 sec
17:14:18 Start 861: tesh-smpi-type-indexed-raw
17:14:19 860/1134 Test #860: tesh-smpi-type-indexed-boost ................................ Passed 0.09 sec
17:14:19 Start 862: tesh-smpi-type-indexed-ucontext
17:14:19 861/1134 Test #861: tesh-smpi-type-indexed-raw .................................. Passed 0.08 sec
17:14:19 Start 863: tesh-smpi-type-struct-thread
17:14:19 862/1134 Test #862: tesh-smpi-type-indexed-ucontext ............................. Passed 0.08 sec
17:14:19 Start 864: tesh-smpi-type-struct-boost
17:14:19 863/1134 Test #863: tesh-smpi-type-struct-thread ................................ Passed 0.10 sec
17:14:19 Start 865: tesh-smpi-type-struct-raw
17:14:19 864/1134 Test #864: tesh-smpi-type-struct-boost ................................. Passed 0.11 sec
17:14:19 Start 866: tesh-smpi-type-struct-ucontext
17:14:19 865/1134 Test #865: tesh-smpi-type-struct-raw ................................... Passed 0.11 sec
17:14:19 Start 867: tesh-smpi-type-vector-thread
17:14:19 866/1134 Test #866: tesh-smpi-type-struct-ucontext .............................. Passed 0.09 sec
17:14:19 Start 868: tesh-smpi-type-vector-boost
17:14:19 867/1134 Test #867: tesh-smpi-type-vector-thread ................................ Passed 0.09 sec
17:14:19 Start 869: tesh-smpi-type-vector-raw
17:14:19 868/1134 Test #868: tesh-smpi-type-vector-boost ................................. Passed 0.10 sec
17:14:19 Start 870: tesh-smpi-type-vector-ucontext
17:14:19 869/1134 Test #869: tesh-smpi-type-vector-raw ................................... Passed 0.09 sec
17:14:19 Start 871: tesh-smpi-bug-17132-thread
17:14:19 870/1134 Test #870: tesh-smpi-type-vector-ucontext .............................. Passed 0.09 sec
17:14:19 Start 872: tesh-smpi-bug-17132-boost
17:14:19 871/1134 Test #871: tesh-smpi-bug-17132-thread .................................. Passed 0.11 sec
17:14:19 Start 873: tesh-smpi-bug-17132-raw
17:14:19 872/1134 Test #872: tesh-smpi-bug-17132-boost ................................... Passed 0.10 sec
17:14:19 Start 874: tesh-smpi-bug-17132-ucontext
17:14:19 873/1134 Test #873: tesh-smpi-bug-17132-raw ..................................... Passed 0.12 sec
17:14:19 Start 875: tesh-smpi-timers-thread
17:14:19 874/1134 Test #874: tesh-smpi-bug-17132-ucontext ................................ Passed 0.12 sec
17:14:19 Start 876: tesh-smpi-timers-boost
17:14:19 875/1134 Test #875: tesh-smpi-timers-thread ..................................... Passed 0.09 sec
17:14:19 Start 877: tesh-smpi-timers-raw
17:14:19 876/1134 Test #876: tesh-smpi-timers-boost ...................................... Passed 0.08 sec
17:14:19 Start 878: tesh-smpi-timers-ucontext
17:14:19 877/1134 Test #877: tesh-smpi-timers-raw ........................................ Passed 0.08 sec
17:14:19 Start 879: tesh-smpi-io-simple-thread
17:14:19 878/1134 Test #878: tesh-smpi-timers-ucontext ................................... Passed 0.08 sec
17:14:19 Start 880: tesh-smpi-io-simple-boost
17:14:19 879/1134 Test #879: tesh-smpi-io-simple-thread .................................. Passed 0.09 sec
17:14:19 Start 881: tesh-smpi-io-simple-raw
17:14:19 880/1134 Test #880: tesh-smpi-io-simple-boost ................................... Passed 0.08 sec
17:14:19 Start 882: tesh-smpi-io-simple-ucontext
17:14:20 881/1134 Test #881: tesh-smpi-io-simple-raw ..................................... Passed 0.09 sec
17:14:20 Start 883: tesh-smpi-io-simple-at-thread
17:14:20 882/1134 Test #882: tesh-smpi-io-simple-ucontext ................................ Passed 0.08 sec
17:14:20 Start 884: tesh-smpi-io-simple-at-boost
17:14:20 883/1134 Test #883: tesh-smpi-io-simple-at-thread ............................... Passed 0.10 sec
17:14:20 Start 885: tesh-smpi-io-simple-at-raw
17:14:20 884/1134 Test #884: tesh-smpi-io-simple-at-boost ................................ Passed 0.08 sec
17:14:20 Start 886: tesh-smpi-io-simple-at-ucontext
17:14:20 885/1134 Test #885: tesh-smpi-io-simple-at-raw .................................. Passed 0.07 sec
17:14:20 Start 887: tesh-smpi-io-all-thread
17:14:20 886/1134 Test #886: tesh-smpi-io-simple-at-ucontext ............................. Passed 0.07 sec
17:14:20 Start 888: tesh-smpi-io-all-boost
17:14:20 887/1134 Test #888: tesh-smpi-io-all-boost ...................................... Passed 0.08 sec
17:14:20 Start 889: tesh-smpi-io-all-raw
17:14:20 888/1134 Test #887: tesh-smpi-io-all-thread ..................................... Passed 0.09 sec
17:14:20 Start 890: tesh-smpi-io-all-ucontext
17:14:20 889/1134 Test #889: tesh-smpi-io-all-raw ........................................ Passed 0.09 sec
17:14:20 Start 891: tesh-smpi-io-all-at-thread
17:14:20 890/1134 Test #890: tesh-smpi-io-all-ucontext ................................... Passed 0.08 sec
17:14:20 Start 892: tesh-smpi-io-all-at-boost
17:14:20 891/1134 Test #892: tesh-smpi-io-all-at-boost ................................... Passed 0.08 sec
17:14:20 Start 893: tesh-smpi-io-all-at-raw
17:14:20 892/1134 Test #891: tesh-smpi-io-all-at-thread .................................. Passed 0.09 sec
17:14:20 Start 894: tesh-smpi-io-all-at-ucontext
17:14:20 893/1134 Test #893: tesh-smpi-io-all-at-raw ..................................... Passed 0.09 sec
17:14:20 Start 895: tesh-smpi-io-shared-thread
17:14:20 894/1134 Test #894: tesh-smpi-io-all-at-ucontext ................................ Passed 0.08 sec
17:14:20 Start 896: tesh-smpi-io-shared-boost
17:14:20 895/1134 Test #896: tesh-smpi-io-shared-boost ................................... Passed 0.08 sec
17:14:20 Start 897: tesh-smpi-io-shared-raw
17:14:20 896/1134 Test #895: tesh-smpi-io-shared-thread .................................. Passed 0.09 sec
17:14:20 Start 898: tesh-smpi-io-shared-ucontext
17:14:20 897/1134 Test #897: tesh-smpi-io-shared-raw ..................................... Passed 0.08 sec
17:14:20 Start 899: tesh-smpi-io-ordered-thread
17:14:20 898/1134 Test #898: tesh-smpi-io-shared-ucontext ................................ Passed 0.08 sec
17:14:20 Start 900: tesh-smpi-io-ordered-boost
17:14:20 899/1134 Test #900: tesh-smpi-io-ordered-boost .................................. Passed 0.08 sec
17:14:20 Start 901: tesh-smpi-io-ordered-raw
17:14:20 900/1134 Test #899: tesh-smpi-io-ordered-thread ................................. Passed 0.09 sec
17:14:20 Start 902: tesh-smpi-io-ordered-ucontext
17:14:20 901/1134 Test #901: tesh-smpi-io-ordered-raw .................................... Passed 0.08 sec
17:14:20 Start 903: tesh-smpi-topo-cart-sub-thread
17:14:20 902/1134 Test #902: tesh-smpi-io-ordered-ucontext ............................... Passed 0.08 sec
17:14:20 Start 904: tesh-smpi-topo-cart-sub-boost
17:14:20 903/1134 Test #904: tesh-smpi-topo-cart-sub-boost ............................... Passed 0.08 sec
17:14:20 Start 905: tesh-smpi-topo-cart-sub-raw
17:14:20 904/1134 Test #903: tesh-smpi-topo-cart-sub-thread .............................. Passed 0.08 sec
17:14:20 Start 906: tesh-smpi-topo-cart-sub-ucontext
17:14:21 905/1134 Test #905: tesh-smpi-topo-cart-sub-raw ................................. Passed 0.08 sec
17:14:21 Start 907: tesh-smpi-fort_args-thread
17:14:21 906/1134 Test #906: tesh-smpi-topo-cart-sub-ucontext ............................ Passed 0.08 sec
17:14:21 Start 908: tesh-smpi-fort_args-boost
17:14:21 907/1134 Test #907: tesh-smpi-fort_args-thread .................................. Passed 0.08 sec
17:14:21 Start 909: tesh-smpi-fort_args-raw
17:14:21 908/1134 Test #908: tesh-smpi-fort_args-boost ................................... Passed 0.08 sec
17:14:21 Start 910: tesh-smpi-fort_args-ucontext
17:14:21 909/1134 Test #910: tesh-smpi-fort_args-ucontext ................................ Passed 0.07 sec
17:14:21 Start 911: tesh-smpi-coll-allgather-2dmesh
17:14:21 910/1134 Test #909: tesh-smpi-fort_args-raw ..................................... Passed 0.08 sec
17:14:21 Start 912: tesh-smpi-coll-allgather-3dmesh
17:14:21 911/1134 Test #912: tesh-smpi-coll-allgather-3dmesh ............................. Passed 0.10 sec
17:14:21 Start 913: tesh-smpi-coll-allgather-bruck
17:14:21 912/1134 Test #911: tesh-smpi-coll-allgather-2dmesh ............................. Passed 0.10 sec
17:14:21 Start 914: tesh-smpi-coll-allgather-GB
17:14:21 913/1134 Test #913: tesh-smpi-coll-allgather-bruck .............................. Passed 0.11 sec
17:14:21 Start 915: tesh-smpi-coll-allgather-loosely_lr
17:14:21 914/1134 Test #914: tesh-smpi-coll-allgather-GB ................................. Passed 0.11 sec
17:14:21 Start 916: tesh-smpi-coll-allgather-NTSLR
17:14:21 915/1134 Test #915: tesh-smpi-coll-allgather-loosely_lr ......................... Passed 0.12 sec
17:14:21 Start 917: tesh-smpi-coll-allgather-NTSLR_NB
17:14:21 916/1134 Test #916: tesh-smpi-coll-allgather-NTSLR .............................. Passed 0.12 sec
17:14:21 Start 918: tesh-smpi-coll-allgather-pair
17:14:21 917/1134 Test #917: tesh-smpi-coll-allgather-NTSLR_NB ........................... Passed 0.11 sec
17:14:21 Start 919: tesh-smpi-coll-allgather-rdb
17:14:21 918/1134 Test #918: tesh-smpi-coll-allgather-pair ............................... Passed 0.11 sec
17:14:21 Start 920: tesh-smpi-coll-allgather-rhv
17:14:21 919/1134 Test #919: tesh-smpi-coll-allgather-rdb ................................ Passed 0.12 sec
17:14:21 Start 921: tesh-smpi-coll-allgather-ring
17:14:21 920/1134 Test #920: tesh-smpi-coll-allgather-rhv ................................ Passed 0.13 sec
17:14:21 Start 922: tesh-smpi-coll-allgather-SMP_NTS
17:14:21 921/1134 Test #922: tesh-smpi-coll-allgather-SMP_NTS ............................ Passed 0.15 sec
17:14:21 Start 923: tesh-smpi-coll-allgather-smp_simple
17:14:21 922/1134 Test #921: tesh-smpi-coll-allgather-ring ............................... Passed 0.18 sec
17:14:21 Start 924: tesh-smpi-coll-allgather-spreading_simple
17:14:22 923/1134 Test #923: tesh-smpi-coll-allgather-smp_simple ......................... Passed 0.11 sec
17:14:22 Start 925: tesh-smpi-coll-allgather-ompi
17:14:22 924/1134 Test #924: tesh-smpi-coll-allgather-spreading_simple ................... Passed 0.11 sec
17:14:22 Start 926: tesh-smpi-coll-allgather-mpich
17:14:22 925/1134 Test #925: tesh-smpi-coll-allgather-ompi ............................... Passed 0.11 sec
17:14:22 Start 927: tesh-smpi-coll-allgather-ompi_neighborexchange
17:14:22 926/1134 Test #926: tesh-smpi-coll-allgather-mpich .............................. Passed 0.11 sec
17:14:22 Start 928: tesh-smpi-coll-allgather-mvapich2
17:14:22 927/1134 Test #927: tesh-smpi-coll-allgather-ompi_neighborexchange .............. Passed 0.12 sec
17:14:22 Start 929: tesh-smpi-coll-allgather-mvapich2_smp
17:14:22 928/1134 Test #928: tesh-smpi-coll-allgather-mvapich2 ........................... Passed 0.11 sec
17:14:22 Start 930: tesh-smpi-coll-allgather-impi
17:14:22 929/1134 Test #929: tesh-smpi-coll-allgather-mvapich2_smp ....................... Passed 0.11 sec
17:14:22 Start 931: tesh-smpi-coll-allgatherv-GB
17:14:22 930/1134 Test #930: tesh-smpi-coll-allgather-impi ............................... Passed 0.11 sec
17:14:22 Start 932: tesh-smpi-coll-allgatherv-pair
17:14:22 931/1134 Test #931: tesh-smpi-coll-allgatherv-GB ................................ Passed 0.11 sec
17:14:22 Start 933: tesh-smpi-coll-allgatherv-ring
17:14:22 932/1134 Test #932: tesh-smpi-coll-allgatherv-pair .............................. Passed 0.11 sec
17:14:22 Start 934: tesh-smpi-coll-allgatherv-ompi
17:14:22 933/1134 Test #933: tesh-smpi-coll-allgatherv-ring .............................. Passed 0.11 sec
17:14:22 Start 935: tesh-smpi-coll-allgatherv-mpich
17:14:22 934/1134 Test #934: tesh-smpi-coll-allgatherv-ompi .............................. Passed 0.11 sec
17:14:22 Start 936: tesh-smpi-coll-allgatherv-ompi_neighborexchange
17:14:22 935/1134 Test #935: tesh-smpi-coll-allgatherv-mpich ............................. Passed 0.11 sec
17:14:22 Start 937: tesh-smpi-coll-allgatherv-ompi_bruck
17:14:22 936/1134 Test #936: tesh-smpi-coll-allgatherv-ompi_neighborexchange ............. Passed 0.11 sec
17:14:22 Start 938: tesh-smpi-coll-allgatherv-mpich_rdb
17:14:22 937/1134 Test #938: tesh-smpi-coll-allgatherv-mpich_rdb ......................... Passed 0.10 sec
17:14:22 Start 939: tesh-smpi-coll-allgatherv-mpich_ring
17:14:22 938/1134 Test #937: tesh-smpi-coll-allgatherv-ompi_bruck ........................ Passed 0.11 sec
17:14:22 Start 940: tesh-smpi-coll-allgatherv-mvapich2
17:14:22 939/1134 Test #940: tesh-smpi-coll-allgatherv-mvapich2 .......................... Passed 0.10 sec
17:14:22 Start 941: tesh-smpi-coll-allgatherv-impi
17:14:22 940/1134 Test #939: tesh-smpi-coll-allgatherv-mpich_ring ........................ Passed 0.11 sec
17:14:22 Start 942: tesh-smpi-coll-allreduce-lr
17:14:23 941/1134 Test #942: tesh-smpi-coll-allreduce-lr ................................. Passed 0.11 sec
17:14:23 Start 943: tesh-smpi-coll-allreduce-rab1
17:14:23 942/1134 Test #941: tesh-smpi-coll-allgatherv-impi .............................. Passed 0.12 sec
17:14:23 Start 944: tesh-smpi-coll-allreduce-rab2
17:14:23 943/1134 Test #943: tesh-smpi-coll-allreduce-rab1 ............................... Passed 0.11 sec
17:14:23 Start 945: tesh-smpi-coll-allreduce-rab_rdb
17:14:23 944/1134 Test #944: tesh-smpi-coll-allreduce-rab2 ............................... Passed 0.11 sec
17:14:23 Start 946: tesh-smpi-coll-allreduce-rdb
17:14:23 945/1134 Test #946: tesh-smpi-coll-allreduce-rdb ................................ Passed 0.10 sec
17:14:23 Start 947: tesh-smpi-coll-allreduce-smp_binomial
17:14:23 946/1134 Test #945: tesh-smpi-coll-allreduce-rab_rdb ............................ Passed 0.11 sec
17:14:23 Start 948: tesh-smpi-coll-allreduce-smp_binomial_pipeline
17:14:23 947/1134 Test #948: tesh-smpi-coll-allreduce-smp_binomial_pipeline .............. Passed 0.11 sec
17:14:23 Start 949: tesh-smpi-coll-allreduce-smp_rdb
17:14:23 948/1134 Test #947: tesh-smpi-coll-allreduce-smp_binomial ....................... Passed 0.11 sec
17:14:23 Start 950: tesh-smpi-coll-allreduce-smp_rsag
17:14:23 949/1134 Test #950: tesh-smpi-coll-allreduce-smp_rsag ........................... Passed 0.11 sec
17:14:23 Start 951: tesh-smpi-coll-allreduce-smp_rsag_lr
17:14:23 950/1134 Test #949: tesh-smpi-coll-allreduce-smp_rdb ............................ Passed 0.13 sec
17:14:23 Start 952: tesh-smpi-coll-allreduce-impi
17:14:23 951/1134 Test #951: tesh-smpi-coll-allreduce-smp_rsag_lr ........................ Passed 0.12 sec
17:14:23 Start 953: tesh-smpi-coll-allreduce-smp_rsag_rab
17:14:23 952/1134 Test #952: tesh-smpi-coll-allreduce-impi ............................... Passed 0.11 sec
17:14:23 Start 954: tesh-smpi-coll-allreduce-redbcast
17:14:23 953/1134 Test #953: tesh-smpi-coll-allreduce-smp_rsag_rab ....................... Passed 0.12 sec
17:14:23 Start 955: tesh-smpi-coll-allreduce-ompi
17:14:23 954/1134 Test #954: tesh-smpi-coll-allreduce-redbcast ........................... Passed 0.11 sec
17:14:23 Start 956: tesh-smpi-coll-allreduce-mpich
17:14:23 955/1134 Test #955: tesh-smpi-coll-allreduce-ompi ............................... Passed 0.10 sec
17:14:23 Start 957: tesh-smpi-coll-allreduce-ompi_ring_segmented
17:14:23 956/1134 Test #956: tesh-smpi-coll-allreduce-mpich .............................. Passed 0.11 sec
17:14:23 Start 958: tesh-smpi-coll-allreduce-mvapich2
17:14:23 957/1134 Test #957: tesh-smpi-coll-allreduce-ompi_ring_segmented ................ Passed 0.11 sec
17:14:23 Start 959: tesh-smpi-coll-allreduce-mvapich2_rs
17:14:23 958/1134 Test #958: tesh-smpi-coll-allreduce-mvapich2 ........................... Passed 0.10 sec
17:14:23 Start 960: tesh-smpi-coll-allreduce-mvapich2_two_level
17:14:24 959/1134 Test #959: tesh-smpi-coll-allreduce-mvapich2_rs ........................ Passed 0.10 sec
17:14:24 Start 961: tesh-smpi-coll-alltoall-2dmesh
17:14:24 960/1134 Test #960: tesh-smpi-coll-allreduce-mvapich2_two_level ................. Passed 0.10 sec
17:14:24 Start 962: tesh-smpi-coll-alltoall-3dmesh
17:14:24 961/1134 Test #961: tesh-smpi-coll-alltoall-2dmesh .............................. Passed 0.11 sec
17:14:24 Start 963: tesh-smpi-coll-alltoall-pair
17:14:24 962/1134 Test #962: tesh-smpi-coll-alltoall-3dmesh .............................. Passed 0.10 sec
17:14:24 Start 964: tesh-smpi-coll-alltoall-pair_rma
17:14:24 963/1134 Test #963: tesh-smpi-coll-alltoall-pair ................................ Passed 0.10 sec
17:14:24 Start 965: tesh-smpi-coll-alltoall-pair_one_barrier
17:14:24 964/1134 Test #964: tesh-smpi-coll-alltoall-pair_rma ............................ Passed 0.11 sec
17:14:24 Start 966: tesh-smpi-coll-alltoall-pair_light_barrier
17:14:24 965/1134 Test #965: tesh-smpi-coll-alltoall-pair_one_barrier .................... Passed 0.11 sec
17:14:24 Start 967: tesh-smpi-coll-alltoall-pair_mpi_barrier
17:14:24 966/1134 Test #966: tesh-smpi-coll-alltoall-pair_light_barrier .................. Passed 0.10 sec
17:14:24 Start 968: tesh-smpi-coll-alltoall-rdb
17:14:24 967/1134 Test #968: tesh-smpi-coll-alltoall-rdb ................................. Passed 0.10 sec
17:14:24 Start 969: tesh-smpi-coll-alltoall-ring
17:14:24 968/1134 Test #967: tesh-smpi-coll-alltoall-pair_mpi_barrier .................... Passed 0.11 sec
17:14:24 Start 970: tesh-smpi-coll-alltoall-ring_light_barrier
17:14:24 969/1134 Test #969: tesh-smpi-coll-alltoall-ring ................................ Passed 0.11 sec
17:14:24 Start 971: tesh-smpi-coll-alltoall-ring_mpi_barrier
17:14:24 970/1134 Test #970: tesh-smpi-coll-alltoall-ring_light_barrier .................. Passed 0.11 sec
17:14:24 Start 972: tesh-smpi-coll-alltoall-ring_one_barrier
17:14:24 971/1134 Test #972: tesh-smpi-coll-alltoall-ring_one_barrier .................... Passed 0.10 sec
17:14:24 Start 973: tesh-smpi-coll-alltoall-bruck
17:14:24 972/1134 Test #971: tesh-smpi-coll-alltoall-ring_mpi_barrier .................... Passed 0.11 sec
17:14:24 Start 974: tesh-smpi-coll-alltoall-basic_linear
17:14:24 973/1134 Test #973: tesh-smpi-coll-alltoall-bruck ............................... Passed 0.11 sec
17:14:24 Start 975: tesh-smpi-coll-alltoall-ompi
17:14:24 974/1134 Test #974: tesh-smpi-coll-alltoall-basic_linear ........................ Passed 0.11 sec
17:14:24 Start 976: tesh-smpi-coll-alltoall-mpich
17:14:24 975/1134 Test #975: tesh-smpi-coll-alltoall-ompi ................................ Passed 0.11 sec
17:14:24 Start 977: tesh-smpi-coll-alltoall-mvapich2
17:14:24 976/1134 Test #976: tesh-smpi-coll-alltoall-mpich ............................... Passed 0.10 sec
17:14:24 Start 978: tesh-smpi-coll-alltoall-mvapich2_scatter_dest
17:14:24 977/1134 Test #977: tesh-smpi-coll-alltoall-mvapich2 ............................ Passed 0.11 sec
17:14:24 Start 979: tesh-smpi-coll-alltoall-impi
17:14:24 978/1134 Test #978: tesh-smpi-coll-alltoall-mvapich2_scatter_dest ............... Passed 0.11 sec
17:14:24 Start 980: tesh-smpi-coll-alltoallv-pair
17:14:25 979/1134 Test #979: tesh-smpi-coll-alltoall-impi ................................ Passed 0.11 sec
17:14:25 Start 981: tesh-smpi-coll-alltoallv-pair_light_barrier
17:14:25 980/1134 Test #980: tesh-smpi-coll-alltoallv-pair ............................... Passed 0.11 sec
17:14:25 Start 982: tesh-smpi-coll-alltoallv-pair_mpi_barrier
17:14:25 981/1134 Test #981: tesh-smpi-coll-alltoallv-pair_light_barrier ................. Passed 0.11 sec
17:14:25 Start 983: tesh-smpi-coll-alltoallv-pair_one_barrier
17:14:25 982/1134 Test #982: tesh-smpi-coll-alltoallv-pair_mpi_barrier ................... Passed 0.11 sec
17:14:25 Start 984: tesh-smpi-coll-alltoallv-ring
17:14:25 983/1134 Test #983: tesh-smpi-coll-alltoallv-pair_one_barrier ................... Passed 0.12 sec
17:14:25 Start 985: tesh-smpi-coll-alltoallv-ring_light_barrier
17:14:25 984/1134 Test #984: tesh-smpi-coll-alltoallv-ring ............................... Passed 0.12 sec
17:14:25 Start 986: tesh-smpi-coll-alltoallv-ring_mpi_barrier
17:14:25 985/1134 Test #985: tesh-smpi-coll-alltoallv-ring_light_barrier ................. Passed 0.12 sec
17:14:25 Start 987: tesh-smpi-coll-alltoallv-ring_one_barrier
17:14:25 986/1134 Test #986: tesh-smpi-coll-alltoallv-ring_mpi_barrier ................... Passed 0.13 sec
17:14:25 Start 988: tesh-smpi-coll-alltoallv-bruck
17:14:25 987/1134 Test #987: tesh-smpi-coll-alltoallv-ring_one_barrier ................... Passed 0.12 sec
17:14:25 Start 989: tesh-smpi-coll-alltoallv-ompi
17:14:25 988/1134 Test #988: tesh-smpi-coll-alltoallv-bruck .............................. Passed 0.12 sec
17:14:25 Start 990: tesh-smpi-coll-alltoallv-mpich
17:14:25 989/1134 Test #990: tesh-smpi-coll-alltoallv-mpich .............................. Passed 0.12 sec
17:14:25 Start 991: tesh-smpi-coll-alltoallv-mvapich2
17:14:25 990/1134 Test #989: tesh-smpi-coll-alltoallv-ompi ............................... Passed 0.12 sec
17:14:25 Start 992: tesh-smpi-coll-alltoallv-ompi_basic_linear
17:14:25 991/1134 Test #991: tesh-smpi-coll-alltoallv-mvapich2 ........................... Passed 0.12 sec
17:14:25 Start 993: tesh-smpi-coll-alltoallv-impi
17:14:25 992/1134 Test #992: tesh-smpi-coll-alltoallv-ompi_basic_linear .................. Passed 0.12 sec
17:14:25 Start 994: tesh-smpi-coll-barrier-ompi
17:14:25 993/1134 Test #994: tesh-smpi-coll-barrier-ompi ................................. Passed 0.10 sec
17:14:25 Start 995: tesh-smpi-coll-barrier-mpich
17:14:25 994/1134 Test #993: tesh-smpi-coll-alltoallv-impi ............................... Passed 0.11 sec
17:14:25 Start 996: tesh-smpi-coll-barrier-mpich_smp
17:14:26 995/1134 Test #995: tesh-smpi-coll-barrier-mpich ................................ Passed 0.11 sec
17:14:26 Start 997: tesh-smpi-coll-barrier-ompi_basic_linear
17:14:26 996/1134 Test #996: tesh-smpi-coll-barrier-mpich_smp ............................ Passed 0.11 sec
17:14:26 Start 998: tesh-smpi-coll-barrier-ompi_tree
17:14:26 997/1134 Test #997: tesh-smpi-coll-barrier-ompi_basic_linear .................... Passed 0.11 sec
17:14:26 Start 999: tesh-smpi-coll-barrier-ompi_bruck
17:14:26 998/1134 Test #998: tesh-smpi-coll-barrier-ompi_tree ............................ Passed 0.11 sec
17:14:26 Start 1000: tesh-smpi-coll-barrier-ompi_recursivedoubling
17:14:26 999/1134 Test #999: tesh-smpi-coll-barrier-ompi_bruck ........................... Passed 0.10 sec
17:14:26 Start 1001: tesh-smpi-coll-barrier-ompi_doublering
17:14:26 1000/1134 Test #1000: tesh-smpi-coll-barrier-ompi_recursivedoubling ............... Passed 0.10 sec
17:14:26 Start 1002: tesh-smpi-coll-barrier-mvapich2_pair
17:14:26 1001/1134 Test #1001: tesh-smpi-coll-barrier-ompi_doublering ...................... Passed 0.10 sec
17:14:26 Start 1003: tesh-smpi-coll-barrier-mvapich2
17:14:26 1002/1134 Test #1002: tesh-smpi-coll-barrier-mvapich2_pair ........................ Passed 0.10 sec
17:14:26 Start 1004: tesh-smpi-coll-barrier-impi
17:14:26 1003/1134 Test #1003: tesh-smpi-coll-barrier-mvapich2 ............................. Passed 0.10 sec
17:14:26 Start 1005: tesh-smpi-coll-bcast-arrival_pattern_aware
17:14:26 1004/1134 Test #1004: tesh-smpi-coll-barrier-impi ................................. Passed 0.11 sec
17:14:26 Start 1006: tesh-smpi-coll-bcast-arrival_pattern_aware_wait
17:14:26 1005/1134 Test #1005: tesh-smpi-coll-bcast-arrival_pattern_aware .................. Passed 0.11 sec
17:14:26 Start 1007: tesh-smpi-coll-bcast-arrival_scatter
17:14:26 1006/1134 Test #1006: tesh-smpi-coll-bcast-arrival_pattern_aware_wait ............. Passed 0.12 sec
17:14:26 Start 1008: tesh-smpi-coll-bcast-binomial_tree
17:14:26 1007/1134 Test #1007: tesh-smpi-coll-bcast-arrival_scatter ........................ Passed 0.11 sec
17:14:26 Start 1009: tesh-smpi-coll-bcast-flattree
17:14:26 1008/1134 Test #1008: tesh-smpi-coll-bcast-binomial_tree .......................... Passed 0.10 sec
17:14:26 Start 1010: tesh-smpi-coll-bcast-flattree_pipeline
17:14:26 1009/1134 Test #1010: tesh-smpi-coll-bcast-flattree_pipeline ...................... Passed 0.10 sec
17:14:26 Start 1011: tesh-smpi-coll-bcast-NTSB
17:14:26 1010/1134 Test #1009: tesh-smpi-coll-bcast-flattree ............................... Passed 0.11 sec
17:14:26 Start 1012: tesh-smpi-coll-bcast-NTSL
17:14:26 1011/1134 Test #1012: tesh-smpi-coll-bcast-NTSL ................................... Passed 0.10 sec
17:14:26 Start 1013: tesh-smpi-coll-bcast-NTSL_Isend
17:14:26 1012/1134 Test #1011: tesh-smpi-coll-bcast-NTSB ................................... Passed 0.11 sec
17:14:26 Start 1014: tesh-smpi-coll-bcast-scatter_LR_allgather
17:14:26 1013/1134 Test #1013: tesh-smpi-coll-bcast-NTSL_Isend ............................. Passed 0.11 sec
17:14:26 Start 1015: tesh-smpi-coll-bcast-scatter_rdb_allgather
17:14:26 1014/1134 Test #1014: tesh-smpi-coll-bcast-scatter_LR_allgather ................... Passed 0.11 sec
17:14:26 Start 1016: tesh-smpi-coll-bcast-SMP_binary
17:14:27 1015/1134 Test #1015: tesh-smpi-coll-bcast-scatter_rdb_allgather .................. Passed 0.11 sec
17:14:27 Start 1017: tesh-smpi-coll-bcast-SMP_binomial
17:14:27 1016/1134 Test #1016: tesh-smpi-coll-bcast-SMP_binary ............................. Passed 0.11 sec
17:14:27 Start 1018: tesh-smpi-coll-bcast-SMP_linear
17:14:27 1017/1134 Test #1017: tesh-smpi-coll-bcast-SMP_binomial ........................... Passed 0.11 sec
17:14:27 Start 1019: tesh-smpi-coll-bcast-ompi
17:14:27 1018/1134 Test #1018: tesh-smpi-coll-bcast-SMP_linear ............................. Passed 0.11 sec
17:14:27 Start 1020: tesh-smpi-coll-bcast-mpich
17:14:27 1019/1134 Test #1019: tesh-smpi-coll-bcast-ompi ................................... Passed 0.11 sec
17:14:27 Start 1021: tesh-smpi-coll-bcast-ompi_split_bintree
17:14:27 1020/1134 Test #1020: tesh-smpi-coll-bcast-mpich .................................. Passed 0.11 sec
17:14:27 Start 1022: tesh-smpi-coll-bcast-ompi_pipeline
17:14:27 1021/1134 Test #1022: tesh-smpi-coll-bcast-ompi_pipeline .......................... Passed 0.10 sec
17:14:27 Start 1023: tesh-smpi-coll-bcast-mvapich2
17:14:27 1022/1134 Test #1021: tesh-smpi-coll-bcast-ompi_split_bintree ..................... Passed 0.11 sec
17:14:27 Start 1024: tesh-smpi-coll-bcast-mvapich2_intra_node
17:14:27 1023/1134 Test #1023: tesh-smpi-coll-bcast-mvapich2 ............................... Passed 0.11 sec
17:14:27 Start 1025: tesh-smpi-coll-bcast-mvapich2_knomial_intra_node
17:14:27 1024/1134 Test #1024: tesh-smpi-coll-bcast-mvapich2_intra_node .................... Passed 0.11 sec
17:14:27 Start 1026: tesh-smpi-coll-bcast-impi
17:14:27 1025/1134 Test #1026: tesh-smpi-coll-bcast-impi ................................... Passed 0.11 sec
17:14:27 Start 1027: tesh-smpi-coll-gather-ompi
17:14:27 1026/1134 Test #1025: tesh-smpi-coll-bcast-mvapich2_knomial_intra_node ............ Passed 0.11 sec
17:14:27 Start 1028: tesh-smpi-coll-gather-mpich
17:14:27 1027/1134 Test #1028: tesh-smpi-coll-gather-mpich ................................. Passed 0.10 sec
17:14:27 Start 1029: tesh-smpi-coll-gather-ompi_basic_linear
17:14:27 1028/1134 Test #1027: tesh-smpi-coll-gather-ompi .................................. Passed 0.14 sec
17:14:27 Start 1030: tesh-smpi-coll-gather-ompi_linear_sync
17:14:27 1029/1134 Test #1029: tesh-smpi-coll-gather-ompi_basic_linear ..................... Passed 0.11 sec
17:14:27 Start 1031: tesh-smpi-coll-gather-ompi_binomial
17:14:27 1030/1134 Test #1030: tesh-smpi-coll-gather-ompi_linear_sync ...................... Passed 0.12 sec
17:14:27 Start 1032: tesh-smpi-coll-gather-mvapich2
17:14:27 1031/1134 Test #1031: tesh-smpi-coll-gather-ompi_binomial ......................... Passed 0.11 sec
17:14:27 Start 1033: tesh-smpi-coll-gather-mvapich2_two_level
17:14:28 1032/1134 Test #1032: tesh-smpi-coll-gather-mvapich2 .............................. Passed 0.11 sec
17:14:28 Start 1034: tesh-smpi-coll-gather-impi
17:14:28 1033/1134 Test #1033: tesh-smpi-coll-gather-mvapich2_two_level .................... Passed 0.11 sec
17:14:28 Start 1035: tesh-smpi-coll-reduce-arrival_pattern_aware
17:14:28 1034/1134 Test #1034: tesh-smpi-coll-gather-impi .................................. Passed 0.12 sec
17:14:28 Start 1036: tesh-smpi-coll-reduce-binomial
17:14:28 1035/1134 Test #1035: tesh-smpi-coll-reduce-arrival_pattern_aware ................. Passed 0.12 sec
17:14:28 Start 1037: tesh-smpi-coll-reduce-flat_tree
17:14:28 1036/1134 Test #1036: tesh-smpi-coll-reduce-binomial .............................. Passed 0.10 sec
17:14:28 Start 1038: tesh-smpi-coll-reduce-NTSL
17:14:28 1037/1134 Test #1037: tesh-smpi-coll-reduce-flat_tree ............................. Passed 0.10 sec
17:14:28 Start 1039: tesh-smpi-coll-reduce-scatter_gather
17:14:28 1038/1134 Test #1038: tesh-smpi-coll-reduce-NTSL .................................. Passed 0.11 sec
17:14:28 Start 1040: tesh-smpi-coll-reduce-ompi
17:14:28 1039/1134 Test #1039: tesh-smpi-coll-reduce-scatter_gather ........................ Passed 0.12 sec
17:14:28 Start 1041: tesh-smpi-coll-reduce-mpich
17:14:28 1040/1134 Test #1040: tesh-smpi-coll-reduce-ompi .................................. Passed 0.12 sec
17:14:28 Start 1042: tesh-smpi-coll-reduce-ompi_chain
17:14:28 1041/1134 Test #1041: tesh-smpi-coll-reduce-mpich ................................. Passed 0.11 sec
17:14:28 Start 1043: tesh-smpi-coll-reduce-ompi_binary
17:14:28 1042/1134 Test #1042: tesh-smpi-coll-reduce-ompi_chain ............................ Passed 0.11 sec
17:14:28 Start 1044: tesh-smpi-coll-reduce-impi
17:14:28 1043/1134 Test #1043: tesh-smpi-coll-reduce-ompi_binary ........................... Passed 0.11 sec
17:14:28 Start 1045: tesh-smpi-coll-reduce-ompi_basic_linear
17:14:28 1044/1134 Test #1044: tesh-smpi-coll-reduce-impi .................................. Passed 0.11 sec
17:14:28 Start 1046: tesh-smpi-coll-reduce-ompi_binomial
17:14:28 1045/1134 Test #1045: tesh-smpi-coll-reduce-ompi_basic_linear ..................... Passed 0.11 sec
17:14:28 Start 1047: tesh-smpi-coll-reduce-ompi_in_order_binary
17:14:28 1046/1134 Test #1046: tesh-smpi-coll-reduce-ompi_binomial ......................... Passed 0.11 sec
17:14:28 Start 1048: tesh-smpi-coll-reduce-mvapich2
17:14:28 1047/1134 Test #1047: tesh-smpi-coll-reduce-ompi_in_order_binary .................. Passed 0.12 sec
17:14:28 Start 1049: tesh-smpi-coll-reduce-mvapich2_knomial
17:14:28 1048/1134 Test #1048: tesh-smpi-coll-reduce-mvapich2 .............................. Passed 0.12 sec
17:14:28 Start 1050: tesh-smpi-coll-reduce-mvapich2_two_level
17:14:28 1049/1134 Test #1049: tesh-smpi-coll-reduce-mvapich2_knomial ...................... Passed 0.11 sec
17:14:28 Start 1051: tesh-smpi-coll-reduce-rab
17:14:29 1050/1134 Test #1050: tesh-smpi-coll-reduce-mvapich2_two_level .................... Passed 0.12 sec
17:14:29 Start 1052: tesh-smpi-coll-reduce-scatter-ompi
17:14:29 1051/1134 Test #1051: tesh-smpi-coll-reduce-rab ................................... Passed 0.12 sec
17:14:29 Start 1053: tesh-smpi-coll-reduce-scatter-mpich
17:14:29 1052/1134 Test #1052: tesh-smpi-coll-reduce-scatter-ompi .......................... Passed 0.12 sec
17:14:29 Start 1054: tesh-smpi-coll-reduce-scatter-ompi_basic_recursivehalving
17:14:29 1053/1134 Test #1053: tesh-smpi-coll-reduce-scatter-mpich ......................... Passed 0.12 sec
17:14:29 Start 1055: tesh-smpi-coll-reduce-scatter-ompi_ring
17:14:29 1054/1134 Test #1054: tesh-smpi-coll-reduce-scatter-ompi_basic_recursivehalving ... Passed 0.11 sec
17:14:29 Start 1056: tesh-smpi-coll-reduce-scatter-ompi_butterfly
17:14:29 1055/1134 Test #1055: tesh-smpi-coll-reduce-scatter-ompi_ring ..................... Passed 0.10 sec
17:14:29 Start 1057: tesh-smpi-coll-reduce-scatter-mpich_noncomm
17:14:29 1056/1134 Test #1056: tesh-smpi-coll-reduce-scatter-ompi_butterfly ................ Passed 0.12 sec
17:14:29 Start 1058: tesh-smpi-coll-reduce-scatter-mpich_pair
17:14:29 1057/1134 Test #1057: tesh-smpi-coll-reduce-scatter-mpich_noncomm ................. Passed 0.12 sec
17:14:29 Start 1059: tesh-smpi-coll-reduce-scatter-mvapich2
17:14:29 1058/1134 Test #1058: tesh-smpi-coll-reduce-scatter-mpich_pair .................... Passed 0.11 sec
17:14:29 Start 1060: tesh-smpi-coll-reduce-scatter-mpich_rdb
17:14:29 1059/1134 Test #1059: tesh-smpi-coll-reduce-scatter-mvapich2 ...................... Passed 0.10 sec
17:14:29 Start 1061: tesh-smpi-coll-reduce-scatter-impi
17:14:29 1060/1134 Test #1060: tesh-smpi-coll-reduce-scatter-mpich_rdb ..................... Passed 0.10 sec
17:14:29 Start 1062: tesh-smpi-coll-scatter-ompi
17:14:29 1061/1134 Test #1061: tesh-smpi-coll-reduce-scatter-impi .......................... Passed 0.11 sec
17:14:29 Start 1063: tesh-smpi-coll-scatter-mpich
17:14:29 1062/1134 Test #1062: tesh-smpi-coll-scatter-ompi ................................. Passed 0.11 sec
17:14:29 Start 1064: tesh-smpi-coll-scatter-ompi_basic_linear
17:14:29 1063/1134 Test #1063: tesh-smpi-coll-scatter-mpich ................................ Passed 0.10 sec
17:14:29 Start 1065: tesh-smpi-coll-scatter-ompi_binomial
17:14:29 1064/1134 Test #1064: tesh-smpi-coll-scatter-ompi_basic_linear .................... Passed 0.10 sec
17:14:29 Start 1066: tesh-smpi-coll-scatter-ompi_linear_nb
17:14:29 1065/1134 Test #1065: tesh-smpi-coll-scatter-ompi_binomial ........................ Passed 0.10 sec
17:14:29 Start 1067: tesh-smpi-coll-scatter-mvapich2
17:14:29 1066/1134 Test #1066: tesh-smpi-coll-scatter-ompi_linear_nb ....................... Passed 0.10 sec
17:14:29 Start 1068: tesh-smpi-coll-scatter-mvapich2_two_level_binomial
17:14:29 1067/1134 Test #1067: tesh-smpi-coll-scatter-mvapich2 ............................. Passed 0.10 sec
17:14:29 Start 1069: tesh-smpi-coll-scatter-mvapich2_two_level_direct
17:14:30 1068/1134 Test #1068: tesh-smpi-coll-scatter-mvapich2_two_level_binomial .......... Passed 0.11 sec
17:14:30 Start 1070: tesh-smpi-coll-scatter-impi
17:14:30 1069/1134 Test #1069: tesh-smpi-coll-scatter-mvapich2_two_level_direct ............ Passed 0.11 sec
17:14:30 Start 1071: tesh-smpi-coll-allreduce-large
17:14:30 1070/1134 Test #1070: tesh-smpi-coll-scatter-impi ................................. Passed 0.11 sec
17:14:30 Start 1072: tesh-smpi-coll-allreduce-automatic
17:14:30 1071/1134 Test #1071: tesh-smpi-coll-allreduce-large .............................. Passed 0.13 sec
17:14:30 Start 1073: tesh-smpi-cluster-types
17:14:30 1072/1134 Test #1072: tesh-smpi-coll-allreduce-automatic .......................... Passed 0.15 sec
17:14:30 Start 1074: tesh-smpi-coll-allreduce-with-leaks
17:14:30 1073/1134 Test #1074: tesh-smpi-coll-allreduce-with-leaks ......................... Passed 0.12 sec
17:14:30 Start 1075: tesh-mc-smpi-coll-allreduce-with-leaks
17:14:30 1074/1134 Test #1075: tesh-mc-smpi-coll-allreduce-with-leaks ...................... Passed 0.27 sec
17:14:30 Start 1076: tesh-smpi-broken-thread
17:14:30 1075/1134 Test #1073: tesh-smpi-cluster-types ..................................... Passed 0.54 sec
17:14:30 Start 1077: tesh-smpi-replay-ti-tracing
17:14:30 1076/1134 Test #1076: tesh-smpi-broken-thread ..................................... Passed 0.09 sec
17:14:30 Start 1078: tesh-smpi-replay-ti-tracing-coll
17:14:30 1077/1134 Test #1077: tesh-smpi-replay-ti-tracing ................................. Passed 0.23 sec
17:14:30 Start 1079: tesh-smpi-gh-139-thread
17:14:31 1078/1134 Test #1079: tesh-smpi-gh-139-thread ..................................... Passed 0.08 sec
17:14:31 Start 1080: tesh-smpi-privatization-dlopen-thread
17:14:31 1079/1134 Test #1080: tesh-smpi-privatization-dlopen-thread ....................... Passed 0.13 sec
17:14:31 Start 1081: tesh-smpi-privatization-dlopen-boost
17:14:31 1080/1134 Test #1078: tesh-smpi-replay-ti-tracing-coll ............................ Passed 0.46 sec
17:14:31 Start 1082: tesh-smpi-privatization-dlopen-raw
17:14:31 1081/1134 Test #1081: tesh-smpi-privatization-dlopen-boost ........................ Passed 0.09 sec
17:14:31 Start 1083: tesh-smpi-privatization-dlopen-ucontext
17:14:31 1082/1134 Test #1082: tesh-smpi-privatization-dlopen-raw .......................... Passed 0.09 sec
17:14:31 Start 1084: tesh-smpi-privatization-mmap-thread
17:14:31 1083/1134 Test #1083: tesh-smpi-privatization-dlopen-ucontext ..................... Passed 0.10 sec
17:14:31 Start 1085: tesh-smpi-privatization-mmap-boost
17:14:31 1084/1134 Test #1084: tesh-smpi-privatization-mmap-thread ......................... Passed 0.14 sec
17:14:31 Start 1086: tesh-smpi-privatization-mmap-raw
17:14:31 1085/1134 Test #1085: tesh-smpi-privatization-mmap-boost .......................... Passed 0.13 sec
17:14:31 Start 1087: tesh-smpi-privatization-mmap-ucontext
17:14:31 1086/1134 Test #1086: tesh-smpi-privatization-mmap-raw ............................ Passed 0.13 sec
17:14:31 Start 1088: test-smpi-mpich3-thread-f77
17:14:31 1087/1134 Test #1087: tesh-smpi-privatization-mmap-ucontext ....................... Passed 0.12 sec
17:14:31 Start 1089: test-smpi-mpich3-thread-f90
17:14:34 1088/1134 Test #1089: test-smpi-mpich3-thread-f90 ................................. Passed 2.55 sec
17:14:34 Start 1090: test-smpi-mpich3-attr-raw
17:14:34 1089/1134 Test #1088: test-smpi-mpich3-thread-f77 ................................. Passed 3.27 sec
17:14:34 Start 1091: test-smpi-mpich3-coll-default
17:14:35 1090/1134 Test #1090: test-smpi-mpich3-attr-raw ................................... Passed 1.08 sec
17:14:35 Start 1092: test-smpi-mpich3-coll-ompi-dlopen
17:14:47 1091/1134 Test #1091: test-smpi-mpich3-coll-default ............................... Passed 13.14 sec
17:14:47 Start 1093: test-smpi-mpich3-coll-mpich-dlopen-boost
17:14:48 1092/1134 Test #1092: test-smpi-mpich3-coll-ompi-dlopen ........................... Passed 12.87 sec
17:14:48 Start 1094: test-smpi-mpich3-coll-mvapich2-mmap-ucontext
17:15:01 1093/1134 Test #1093: test-smpi-mpich3-coll-mpich-dlopen-boost .................... Passed 13.86 sec
17:15:01 Start 1095: test-smpi-mpich3-coll-impi-mmap-raw
17:15:10 1094/1134 Test #1094: test-smpi-mpich3-coll-mvapich2-mmap-ucontext ................ Passed 22.74 sec
17:15:10 Start 1096: test-smpi-mpich3-comm-raw
17:15:12 1095/1134 Test #1096: test-smpi-mpich3-comm-raw ................................... Passed 2.05 sec
17:15:12 Start 1097: test-smpi-mpich3-datatype-raw
17:15:14 1096/1134 Test #1097: test-smpi-mpich3-datatype-raw ............................... Passed 2.09 sec
17:15:14 Start 1098: test-smpi-mpich3-errhan-raw
17:15:15 1097/1134 Test #1098: test-smpi-mpich3-errhan-raw ................................. Passed 0.34 sec
17:15:15 Start 1099: test-smpi-mpich3-group-raw
17:15:16 1098/1134 Test #1099: test-smpi-mpich3-group-raw .................................. Passed 1.09 sec
17:15:16 Start 1100: test-smpi-mpich3-info-raw
17:15:16 1099/1134 Test #1100: test-smpi-mpich3-info-raw ................................... Passed 0.50 sec
17:15:16 Start 1101: test-smpi-mpich3-init-raw
17:15:17 1100/1134 Test #1101: test-smpi-mpich3-init-raw ................................... Passed 0.40 sec
17:15:17 Start 1102: test-smpi-mpich3-io-raw
17:15:18 1101/1134 Test #1102: test-smpi-mpich3-io-raw ..................................... Passed 0.86 sec
17:15:18 Start 1103: test-smpi-mpich3-perf-raw
17:15:20 1102/1134 Test #1103: test-smpi-mpich3-perf-raw ................................... Passed 2.28 sec
17:15:20 Start 1104: test-smpi-mpich3-pt2pt-raw
17:15:21 1103/1134 Test #1095: test-smpi-mpich3-coll-impi-mmap-raw ......................... Passed 19.63 sec
17:15:21 Start 1105: test-smpi-mpich3-rma-raw
17:15:28 1104/1134 Test #1104: test-smpi-mpich3-pt2pt-raw .................................. Passed 8.23 sec
17:15:28 Start 1106: test-smpi-mpich3-topo-raw
17:15:29 1105/1134 Test #1106: test-smpi-mpich3-topo-raw ................................... Passed 0.30 sec
17:15:29 Start 1107: tesh-xbt-cmdline
17:15:29 1106/1134 Test #1107: tesh-xbt-cmdline ............................................ Passed 0.08 sec
17:15:29 Start 1108: tesh-xbt-log_large
17:15:29 1107/1134 Test #1108: tesh-xbt-log_large .......................................... Passed 0.10 sec
17:15:29 Start 1109: tesh-xbt-parallel_log_crashtest
17:15:29 1108/1134 Test #1109: tesh-xbt-parallel_log_crashtest ............................. Passed 0.15 sec
17:15:29 Start 1110: tesh-xbt-parmap_test
17:15:29 1109/1134 Test #1110: tesh-xbt-parmap_test ........................................ Passed 0.60 sec
17:15:29 Start 1111: tesh-xbt-signals
17:15:30 1110/1134 Test #1111: tesh-xbt-signals ............................................ Passed 0.08 sec
17:15:30 Start 1112: tesh-xbt-log
17:15:30 1111/1134 Test #1112: tesh-xbt-log ................................................ Passed 0.21 sec
17:15:30 Start 1113: graphicator
17:15:30 1112/1134 Test #1113: graphicator ................................................. Passed 0.15 sec
17:15:30 Start 1114: tesh-self-setenv
17:15:30 1113/1134 Test #1114: tesh-self-setenv ............................................ Passed 0.08 sec
17:15:30 Start 1115: tesh-self-set-output-ignore
17:15:30 1114/1134 Test #1115: tesh-self-set-output-ignore ................................. Passed 0.08 sec
17:15:30 Start 1116: tesh-self-set-output-sort
17:15:30 1115/1134 Test #1116: tesh-self-set-output-sort ................................... Passed 0.26 sec
17:15:30 Start 1117: tesh-self-set-return
17:15:30 1116/1134 Test #1117: tesh-self-set-return ........................................ Passed 0.05 sec
17:15:30 Start 1118: tesh-self-set-timeout
17:15:37 1117/1134 Test #1118: tesh-self-set-timeout ....................................... Passed 7.06 sec
17:15:37 Start 1119: tesh-self-background
17:15:39 1118/1134 Test #1119: tesh-self-background ........................................ Passed 2.07 sec
17:15:39 Start 1120: tesh-self-basic
17:15:40 1119/1134 Test #1120: tesh-self-basic ............................................. Passed 0.48 sec
17:15:40 Start 1121: tesh-self-bg-basic
17:15:40 1120/1134 Test #1121: tesh-self-bg-basic .......................................... Passed 0.05 sec
17:15:40 Start 1122: tesh-self-bg-set-signal
17:15:40 1121/1134 Test #1122: tesh-self-bg-set-signal ..................................... Passed 0.06 sec
17:15:40 Start 1123: tesh-self-catch-all-bg-output
17:15:42 1122/1134 Test #1123: tesh-self-catch-all-bg-output ............................... Passed 2.09 sec
17:15:42 Start 1124: tesh-self-catch-return
17:15:42 1123/1134 Test #1124: tesh-self-catch-return ...................................... Passed 0.09 sec
17:15:42 Start 1125: tesh-self-catch-signal
17:15:42 1124/1134 Test #1125: tesh-self-catch-signal ...................................... Passed 0.12 sec
17:15:42 Start 1126: tesh-self-catch-timeout
17:15:44 1125/1134 Test #1126: tesh-self-catch-timeout ..................................... Passed 1.29 sec
17:15:44 Start 1127: tesh-self-catch-timeout-output
17:15:45 1126/1134 Test #1127: tesh-self-catch-timeout-output .............................. Passed 1.29 sec
17:15:45 Start 1128: tesh-self-catch-wrong-output
17:15:45 1127/1134 Test #1128: tesh-self-catch-wrong-output ................................ Passed 0.11 sec
17:15:45 Start 1129: tesh-self-cd
17:15:45 1128/1134 Test #1129: tesh-self-cd ................................................ Passed 0.12 sec
17:15:45 Start 1130: tesh-self-color
17:15:45 1129/1134 Test #1130: tesh-self-color ............................................. Passed 0.06 sec
17:15:45 Start 1131: tesh-self-ignore-regexp
17:15:45 1130/1134 Test #1131: tesh-self-ignore-regexp ..................................... Passed 0.05 sec
17:15:45 Start 1132: tesh-self-IO-bigsize
17:15:45 1131/1134 Test #1132: tesh-self-IO-bigsize ........................................ Passed 0.07 sec
17:15:45 Start 1133: tesh-self-IO-broken-pipe
17:15:46 1132/1134 Test #1133: tesh-self-IO-broken-pipe .................................... Passed 0.16 sec
17:15:46 Start 1134: tesh-self-IO-orders
17:15:46 1133/1134 Test #1134: tesh-self-IO-orders ......................................... Passed 0.28 sec
17:15:46 1134/1134 Test #1105: test-smpi-mpich3-rma-raw .................................... Passed 25.32 sec
17:15:46
17:15:46 99% tests passed, 15 tests failed out of 1134
17:15:46
17:15:46 Total Test time (real) = 162.61 sec
17:15:46
17:15:46 The following tests FAILED:
17:15:46 609 - pthread-mc-mutex-simple-boost (Failed)
17:15:46 610 - pthread-mc-mutex-simple-raw (Failed)
17:15:46 611 - pthread-mc-mutex-simple-ucontext (Failed)
17:15:46 615 - pthread-mc-mutex-recursive-boost (Failed)
17:15:46 616 - pthread-mc-mutex-recursive-raw (Failed)
17:15:46 617 - pthread-mc-mutex-recursive-ucontext (Failed)
17:15:46 621 - pthread-mc-producer-consumer-boost (Failed)
17:15:46 622 - pthread-mc-producer-consumer-raw (Failed)
17:15:46 623 - pthread-mc-producer-consumer-ucontext (Failed)
17:15:46 624 - pthread-mc-mutex-simpledeadlock-boost (Failed)
17:15:46 625 - pthread-mc-mutex-simpledeadlock-raw (Failed)
17:15:46 626 - pthread-mc-mutex-simpledeadlock-ucontext (Failed)
17:15:46 657 - mc-mini-simple_mutex_with_threads_ok (Failed)
17:15:46 668 - mc-mini-philosophers_semaphores_deadlock (Failed)
17:15:46 670 - mc-mini-philosophers_mutex_deadlock (Failed)
17:15:46 Create notes file
17:15:46 Add file: /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/Testing/Notes/Build
17:15:46 Errors while running CTest
17:15:46 + true
17:15:46 + test -n /home/ci/simgrid_install
17:15:46 + [ origin/master = origin/master ]
17:15:46 + echo XX
17:15:46 XX
17:15:46 + echo XX Test done. Install everything since it's a regular build, not on a Windows.
17:15:46 XX Test done. Install everything since it's a regular build, not on a Windows.
17:15:46 + echo XX
17:15:46 XX
17:15:46 + rm -rf /home/ci/simgrid_install
17:15:47 + make install
17:15:47 [ 0%] Built target mpi
17:15:47 [ 0%] Built target maintainer_files
17:15:50 [ 90%] Built target simgrid
17:15:50 [ 90%] Built target sthread
17:15:50 [ 90%] Built target simgrid-mc
17:15:50 [ 90%] Built target smpimain
17:15:50 [ 90%] Built target smpireplaymain
17:15:50 [ 90%] Built target python-bindings
17:15:50 [ 90%] Install /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/bin/simgrid_update_xml
17:15:50 [ 90%] Built target simgrid_update_xml
17:15:50 [ 90%] Install /builds/workspace/SimGrid/build_mode/Debug/node/debian-stable/build/simgrid-3.35.1/build/bin/simgrid_convert_TI_traces
17:15:50 [ 90%] Built target simgrid_convert_TI_traces
17:15:50 [ 95%] Built target griffon
17:15:50 [ 95%] Built target routing_cluster
17:15:50 [ 95%] Built target supernode
17:15:50 [ 95%] Built target smpi_simple-execute
17:15:50 [ 95%] Built target smpi_comm-dynamic-cost
17:15:50 [ 95%] Built target mtest_c
17:15:50 [ 95%] Built target mtest_f77
17:15:50 [100%] Built target mtest_f90
17:15:50 [100%] Built target graphicator
17:15:50 Install the project...
17:15:50 -- Install configuration: ""
17:15:50 -- Installing: /builds/simgrid_install/bin/simgrid-mc
17:15:50 -- Set non-toolchain portion of runtime path of "/builds/simgrid_install/bin/simgrid-mc" to ""
17:15:50 -- Installing: /builds/simgrid_install/lib/simgrid/smpimain
17:15:50 -- Set non-toolchain portion of runtime path of "/builds/simgrid_install/lib/simgrid/smpimain" to ""
17:15:50 -- Installing: /builds/simgrid_install/lib/simgrid/smpireplaymain
17:15:50 -- Set non-toolchain portion of runtime path of "/builds/simgrid_install/lib/simgrid/smpireplaymain" to ""
17:15:50 -- Installing: /builds/simgrid_install/lib/python3/dist-packages/simgrid.cpython-39-x86_64-linux-gnu.so
17:15:51 -- Set non-toolchain portion of runtime path of "/builds/simgrid_install/lib/python3/dist-packages/simgrid.cpython-39-x86_64-linux-gnu.so" to ""
17:15:51 -- Installing: /builds/simgrid_install/bin/smpicc
17:15:51 -- Installing: /builds/simgrid_install/bin/smpicxx
17:15:51 -- Installing: /builds/simgrid_install/bin/smpirun
17:15:51 -- Installing: /builds/simgrid_install/bin/smpif90
17:15:51 -- Installing: /builds/simgrid_install/bin/smpiff
17:15:51 -- Installing: /builds/simgrid_install/include/smpi/mpi.mod
17:15:51 -- Installing: /builds/simgrid_install/bin/tesh
17:15:51 -- Installing: /builds/simgrid_install/bin/simgrid_update_xml
17:15:51 -- Installing: /builds/simgrid_install/bin/simgrid_convert_TI_traces
17:15:51 -- Installing: /builds/simgrid_install/lib/libsimgrid.so.3.35.1
17:15:52 -- Installing: /builds/simgrid_install/lib/libsimgrid.so
17:15:52 -- Installing: /builds/simgrid_install/lib/libsthread.so.3.35.1
17:15:52 -- Set non-toolchain portion of runtime path of "/builds/simgrid_install/lib/libsthread.so.3.35.1" to ""
17:15:52 -- Installing: /builds/simgrid_install/lib/libsthread.so
17:15:52 -- Installing: /builds/simgrid_install/lib/pkgconfig/simgrid.pc
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/actor.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/activity_set.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/barrier.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/comm.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/engine.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/exec.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/Exception.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/chrono.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/battery.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/chiller.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/dvfs.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/energy.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/file_system.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/jbod.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/live_migration.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/load.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/solar_panel.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/ProducerConsumer.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/instr.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/mailbox.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/modelchecker.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/forward.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/simcall.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/simix.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/ProfileBuilder.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/Timer.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/disk.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/host.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/link.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/cond.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/mutex.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/semaphore.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/vm.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/zone.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Activity.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/ActivitySet.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Actor.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Barrier.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Comm.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/ConditionVariable.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Disk.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Engine.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Exec.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Host.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Io.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Link.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Mailbox.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/MessageQueue.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Mess.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Mutex.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/NetZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Semaphore.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/Task.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u/VirtualMachine.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/s4u.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/resource/Action.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/resource/Model.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/ClusterZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/DijkstraZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/DragonflyZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/EmptyZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/FatTreeZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/FloydZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/FullZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/NetPoint.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/NetZoneImpl.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/RoutedZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/StarZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/TorusZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/VivaldiZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/kernel/routing/WifiZone.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/mpi.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/sampi.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/smpi.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/smpi_helpers.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/smpi_helpers_internal.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/smpi_extended_traces.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/smpi_extended_traces_fortran.h
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/forward.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/asserts.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/asserts.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/backtrace.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/base.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/config.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/config.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/dict.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/dynar.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/ex.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/Extendable.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/file.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/functional.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/function_types.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/graph.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/log.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/log.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/mallocator.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/misc.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/module.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/PropertyHolder.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/parmap.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/parse_units.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/range.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/random.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/replay.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/signal.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/str.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/string.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/sysdep.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/system_error.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/utility.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/virtu.h
17:15:52 -- Installing: /builds/simgrid_install/include/xbt/xbt_os_time.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/plugins/ns3.hpp
17:15:52 -- Installing: /builds/simgrid_install/include/smpi/mpif.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/config.h
17:15:52 -- Installing: /builds/simgrid_install/include/simgrid/version.h
17:15:53 -- Installing: /builds/simgrid_install/bin/graphicator
17:15:53 -- Set non-toolchain portion of runtime path of "/builds/simgrid_install/bin/graphicator" to ""
17:15:53 + echo XX
17:15:53 XX
17:15:53 + echo XX Done. Return the results to cmake
17:15:53 XX Done. Return the results to cmake
17:15:53 + echo XX
17:15:53 XX
17:15:54 INFO: Processing CTest-Version 3.x (default)
17:15:56 INFO: [CTest-Version 3.x (default)] - 1 test report file(s) were found with the pattern 'build/simgrid*/build/Testing/*/Test.xml' relative to '/builds/workspace/SimGrid/build_mode/Debug/node/debian-stable' for the testing framework 'CTest-Version 3.x (default)'.
17:16:14 INFO: Check 'Failed Tests' threshold.
17:16:14 INFO: The total number of tests for the threshold 'Failed Tests' exceeds the specified "unstable threshold" value.
17:16:14 [Checks API] No suitable checks publisher found.
17:16:14 INFO: Setting the build status to UNSTABLE
17:16:14 Build step 'Publish xUnit test result report' changed build result to UNSTABLE
17:16:14 [Text Finder] Searching console output...
17:16:14 [Text Finder] Finished searching for pattern 'Build\sskipped' in console output.
17:16:14 An attempt to send an e-mail to empty list of recipients, ignored.
17:16:14 [PostBuildScript] - [INFO] Executing post build scripts.
17:16:14 [PostBuildScript] - [INFO] Node does not have the role MASTER. Did not execute build step #0.
17:16:14 Started calculate disk usage of build
17:16:14 Finished Calculation of disk usage of build in 0 seconds
17:16:14 Started calculate disk usage of workspace
17:16:17 Finished Calculation of disk usage of workspace in 2 second
17:16:17 [WS-CLEANUP] Deleting project workspace...
17:16:17 [WS-CLEANUP] Skipped based on build state UNSTABLE
17:16:17 Finished: UNSTABLE