Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.MultiBody.PlanarLoopWithMove.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001696/0.001696, allocations: 104.3 kB / 20.52 MB, free: 1.301 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001687/0.001687, allocations: 216.4 kB / 23.82 MB, free: 4.449 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.606/1.606, allocations: 207.7 MB / 234.7 MB, free: 9.754 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.2493/0.2493, allocations: 44.27 MB / 331.2 MB, free: 9.262 MB / 270.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(ModelicaTest.MultiBody.PlanarLoopWithMove,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|combiTimeTable.nextTimeEvent|rh1.1.|rh1.2.|rh1.3.|rv1.1.|rv1.2.|rv1.3.|rv2.1.|rv2.2.|rv2.3.|rh2.1.|rh2.2.|rh2.3.|r1.frame_b.r_0.1.|r1.frame_b.r_0.2.|r1.frame_b.r_0.3.|r1.frame_b.f.1.|r1.frame_b.f.2.|r1.frame_b.f.3.|r1.frame_b.t.1.|r1.frame_b.t.2.|r1.frame_b.t.3.|rod1.frame_b.r_0.1.|rod1.frame_b.r_0.2.|rod1.frame_b.r_0.3.|rod1.frame_b.f.1.|rod1.frame_b.f.2.|rod1.frame_b.f.3.|rod1.frame_b.t.1.|rod1.frame_b.t.2.|rod1.frame_b.t.3.|rod2.frame_b.r_0.1.|rod2.frame_b.r_0.2.|rod2.frame_b.r_0.3.|rod2.frame_b.f.1.|rod2.frame_b.f.2.|rod2.frame_b.f.3.|rod2.frame_b.t.1.|rod2.frame_b.t.2.|rod2.frame_b.t.3.|r2.frame_b.r_0.1.|r2.frame_b.r_0.2.|r2.frame_b.r_0.3.|r2.frame_b.f.1.|r2.frame_b.f.2.|r2.frame_b.f.3.|r2.frame_b.t.1.|r2.frame_b.t.2.|r2.frame_b.t.3.|bodyShape.frame_b.r_0.1.|bodyShape.frame_b.r_0.2.|bodyShape.frame_b.r_0.3.|bodyShape.frame_b.f.1.|bodyShape.frame_b.f.2.|bodyShape.frame_b.f.3.|bodyShape.frame_b.t.1.|bodyShape.frame_b.t.2.|bodyShape.frame_b.t.3.|bodyShape.frameTranslation.frame_b.r_0.1.|bodyShape.frameTranslation.frame_b.r_0.2.|bodyShape.frameTranslation.frame_b.r_0.3.|bodyShape.frameTranslation.frame_b.f.1.|bodyShape.frameTranslation.frame_b.f.2.|bodyShape.frameTranslation.frame_b.f.3.|bodyShape.frameTranslation.frame_b.t.1.|bodyShape.frameTranslation.frame_b.t.2.|bodyShape.frameTranslation.frame_b.t.3.|r3.frame_b.r_0.1.|r3.frame_b.r_0.2.|r3.frame_b.r_0.3.|r3.frame_b.f.1.|r3.frame_b.f.2.|r3.frame_b.f.3.|r3.frame_b.t.1.|r3.frame_b.t.2.|r3.frame_b.t.3.|r4.frame_b.r_0.1.|r4.frame_b.r_0.2.|r4.frame_b.r_0.3.|r4.frame_b.f.1.|r4.frame_b.f.2.|r4.frame_b.f.3.|r4.frame_b.t.1.|r4.frame_b.t.2.|r4.frame_b.t.3.|rod3.frame_b.r_0.1.|rod3.frame_b.r_0.2.|rod3.frame_b.r_0.3.|rod3.frame_b.f.1.|rod3.frame_b.f.2.|rod3.frame_b.f.3.|rod3.frame_b.t.1.|rod3.frame_b.t.2.|rod3.frame_b.t.3.|deg2rad1.y.1.|deg2rad1.y.2.|deg2rad1.y.3.|combiTimeTable.y.1.|combiTimeTable.y.2.|combiTimeTable.y.3.",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.PlanarLoopWithMove")
translateModel(ModelicaTest.MultiBody.PlanarLoopWithMove,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|combiTimeTable.nextTimeEvent|rh1.1.|rh1.2.|rh1.3.|rv1.1.|rv1.2.|rv1.3.|rv2.1.|rv2.2.|rv2.3.|rh2.1.|rh2.2.|rh2.3.|r1.frame_b.r_0.1.|r1.frame_b.r_0.2.|r1.frame_b.r_0.3.|r1.frame_b.f.1.|r1.frame_b.f.2.|r1.frame_b.f.3.|r1.frame_b.t.1.|r1.frame_b.t.2.|r1.frame_b.t.3.|rod1.frame_b.r_0.1.|rod1.frame_b.r_0.2.|rod1.frame_b.r_0.3.|rod1.frame_b.f.1.|rod1.frame_b.f.2.|rod1.frame_b.f.3.|rod1.frame_b.t.1.|rod1.frame_b.t.2.|rod1.frame_b.t.3.|rod2.frame_b.r_0.1.|rod2.frame_b.r_0.2.|rod2.frame_b.r_0.3.|rod2.frame_b.f.1.|rod2.frame_b.f.2.|rod2.frame_b.f.3.|rod2.frame_b.t.1.|rod2.frame_b.t.2.|rod2.frame_b.t.3.|r2.frame_b.r_0.1.|r2.frame_b.r_0.2.|r2.frame_b.r_0.3.|r2.frame_b.f.1.|r2.frame_b.f.2.|r2.frame_b.f.3.|r2.frame_b.t.1.|r2.frame_b.t.2.|r2.frame_b.t.3.|bodyShape.frame_b.r_0.1.|bodyShape.frame_b.r_0.2.|bodyShape.frame_b.r_0.3.|bodyShape.frame_b.f.1.|bodyShape.frame_b.f.2.|bodyShape.frame_b.f.3.|bodyShape.frame_b.t.1.|bodyShape.frame_b.t.2.|bodyShape.frame_b.t.3.|bodyShape.frameTranslation.frame_b.r_0.1.|bodyShape.frameTranslation.frame_b.r_0.2.|bodyShape.frameTranslation.frame_b.r_0.3.|bodyShape.frameTranslation.frame_b.f.1.|bodyShape.frameTranslation.frame_b.f.2.|bodyShape.frameTranslation.frame_b.f.3.|bodyShape.frameTranslation.frame_b.t.1.|bodyShape.frameTranslation.frame_b.t.2.|bodyShape.frameTranslation.frame_b.t.3.|r3.frame_b.r_0.1.|r3.frame_b.r_0.2.|r3.frame_b.r_0.3.|r3.frame_b.f.1.|r3.frame_b.f.2.|r3.frame_b.f.3.|r3.frame_b.t.1.|r3.frame_b.t.2.|r3.frame_b.t.3.|r4.frame_b.r_0.1.|r4.frame_b.r_0.2.|r4.frame_b.r_0.3.|r4.frame_b.f.1.|r4.frame_b.f.2.|r4.frame_b.f.3.|r4.frame_b.t.1.|r4.frame_b.t.2.|r4.frame_b.t.3.|rod3.frame_b.r_0.1.|rod3.frame_b.r_0.2.|rod3.frame_b.r_0.3.|rod3.frame_b.f.1.|rod3.frame_b.f.2.|rod3.frame_b.f.3.|rod3.frame_b.t.1.|rod3.frame_b.t.2.|rod3.frame_b.t.3.|deg2rad1.y.1.|deg2rad1.y.2.|deg2rad1.y.3.|combiTimeTable.y.1.|combiTimeTable.y.2.|combiTimeTable.y.3.",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.PlanarLoopWithMove") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.825e-05/2.825e-05, allocations: 5.656 kB / 468.8 MB, free: 3.848 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.PlanarLoopWithMove): time 0.01546/0.01549, allocations: 10.54 MB / 479.3 MB, free: 9.254 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0122/0.02768, allocations: 4.312 MB / 483.7 MB, free: 4.926 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002679/0.03036, allocations: 95.5 kB / 483.8 MB, free: 4.832 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.006228/0.03659, allocations: 2.932 MB / 486.7 MB, free: 1.891 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006742/0.04333, allocations: 2.03 MB / 488.7 MB, free: 15.86 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002315/0.04565, allocations: 0.8694 MB / 489.6 MB, free: 14.98 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008206/0.05385, allocations: 4.444 MB / 494 MB, free: 10.53 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004473/0.05833, allocations: 1.915 MB / 495.9 MB, free: 8.582 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004465/0.06279, allocations: 1.65 MB / 497.6 MB, free: 6.926 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004328/0.06712, allocations: 1.782 MB / 499.4 MB, free: 5.137 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008739/0.06799, allocations: 168 kB / 499.5 MB, free: 4.973 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002912/0.0709, allocations: 0.8931 MB / 0.4887 GB, free: 4.078 MB / 366.1 MB
Notification: Performance of combineBinaries: time 0.005867/0.07677, allocations: 5.23 MB / 0.4938 GB, free: 14.8 MB / 382.1 MB
Notification: Performance of replaceArrayConstructors: time 0.004408/0.08118, allocations: 3.399 MB / 0.4971 GB, free: 11.36 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006663/0.08185, allocations: 148 kB / 0.4973 GB, free: 11.22 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.0006139/0.08246, allocations: 107 kB / 0.4974 GB, free: 11.11 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1597 (514)
 * Number of variables: 1597 (492)
Notification: Performance of [SIM] Bindings: time 0.01812/0.1006, allocations: 13.77 MB / 0.5108 GB, free: 12.98 MB / 398.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.001884/0.1025, allocations: 1.343 MB / 0.5121 GB, free: 11.58 MB / 398.1 MB
Notification: Performance of [SIM] Early Inline: time 3.401/3.503, allocations: 1.071 GB / 1.583 GB, free: 13.35 MB / 0.5919 GB
Notification: Performance of [SIM] Simplify 1: time 2.505/6.009, allocations: 0.6122 GB / 2.196 GB, free: 144.1 MB / 0.795 GB
Notification: Performance of [SIM] Alias: time 0.6901/6.699, allocations: 264.8 MB / 2.454 GB, free: 40.62 MB / 0.795 GB
Notification: Performance of [SIM] Simplify 2: time 1.784/8.483, allocations: 0.612 GB / 3.066 GB, free: 29.46 MB / 0.795 GB
Notification: Performance of [SIM] Remove Stream: time 0.1093/8.592, allocations: 38.87 MB / 3.104 GB, free: 28.26 MB / 0.795 GB
Notification: Performance of [SIM] Detect States: time 0.7053/9.297, allocations: 112.1 MB / 3.214 GB, free: 272 MB / 0.795 GB
Notification: Performance of [SIM] Events: time 0.7765/10.07, allocations: 315.7 MB / 3.522 GB, free: 362.7 MB / 0.795 GB
Notification: Performance of [SIM] Partitioning: time 0.06336/10.14, allocations: 23.53 MB / 3.545 GB, free: 360.7 MB / 0.795 GB
Error: Internal error NBResolveSingularities.indexReduction failed because slicing during index reduction is not yet supported.
  needed sliced dummy:		[STAT] (3) flow Real[3] bodyShape.frame_a.f
	slice: {2}
"
[Timeout remaining time 650]
[Calling sys.exit(0), Time elapsed: 13.277931474032812]
Failed to read output from testmodel.py, exit status != 0:
['time', 'combiTimeTable.nextTimeEvent', 'rh1[1]', 'rh1[2]', 'rh1[3]', 'rv1[1]', 'rv1[2]', 'rv1[3]', 'rv2[1]', 'rv2[2]', 'rv2[3]', 'rh2[1]', 'rh2[2]', 'rh2[3]', 'r1.frame_b.r_0[1]', 'r1.frame_b.r_0[2]', 'r1.frame_b.r_0[3]', 'r1.frame_b.f[1]', 'r1.frame_b.f[2]', 'r1.frame_b.f[3]', 'r1.frame_b.t[1]', 'r1.frame_b.t[2]', 'r1.frame_b.t[3]', 'rod1.frame_b.r_0[1]', 'rod1.frame_b.r_0[2]', 'rod1.frame_b.r_0[3]', 'rod1.frame_b.f[1]', 'rod1.frame_b.f[2]', 'rod1.frame_b.f[3]', 'rod1.frame_b.t[1]', 'rod1.frame_b.t[2]', 'rod1.frame_b.t[3]', 'rod2.frame_b.r_0[1]', 'rod2.frame_b.r_0[2]', 'rod2.frame_b.r_0[3]', 'rod2.frame_b.f[1]', 'rod2.frame_b.f[2]', 'rod2.frame_b.f[3]', 'rod2.frame_b.t[1]', 'rod2.frame_b.t[2]', 'rod2.frame_b.t[3]', 'r2.frame_b.r_0[1]', 'r2.frame_b.r_0[2]', 'r2.frame_b.r_0[3]', 'r2.frame_b.f[1]', 'r2.frame_b.f[2]', 'r2.frame_b.f[3]', 'r2.frame_b.t[1]', 'r2.frame_b.t[2]', 'r2.frame_b.t[3]', 'bodyShape.frame_b.r_0[1]', 'bodyShape.frame_b.r_0[2]', 'bodyShape.frame_b.r_0[3]', 'bodyShape.frame_b.f[1]', 'bodyShape.frame_b.f[2]', 'bodyShape.frame_b.f[3]', 'bodyShape.frame_b.t[1]', 'bodyShape.frame_b.t[2]', 'bodyShape.frame_b.t[3]', 'bodyShape.frameTranslation.frame_b.r_0[1]', 'bodyShape.frameTranslation.frame_b.r_0[2]', 'bodyShape.frameTranslation.frame_b.r_0[3]', 'bodyShape.frameTranslation.frame_b.f[1]', 'bodyShape.frameTranslation.frame_b.f[2]', 'bodyShape.frameTranslation.frame_b.f[3]', 'bodyShape.frameTranslation.frame_b.t[1]', 'bodyShape.frameTranslation.frame_b.t[2]', 'bodyShape.frameTranslation.frame_b.t[3]', 'r3.frame_b.r_0[1]', 'r3.frame_b.r_0[2]', 'r3.frame_b.r_0[3]', 'r3.frame_b.f[1]', 'r3.frame_b.f[2]', 'r3.frame_b.f[3]', 'r3.frame_b.t[1]', 'r3.frame_b.t[2]', 'r3.frame_b.t[3]', 'r4.frame_b.r_0[1]', 'r4.frame_b.r_0[2]', 'r4.frame_b.r_0[3]', 'r4.frame_b.f[1]', 'r4.frame_b.f[2]', 'r4.frame_b.f[3]', 'r4.frame_b.t[1]', 'r4.frame_b.t[2]', 'r4.frame_b.t[3]', 'rod3.frame_b.r_0[1]', 'rod3.frame_b.r_0[2]', 'rod3.frame_b.r_0[3]', 'rod3.frame_b.f[1]', 'rod3.frame_b.f[2]', 'rod3.frame_b.f[3]', 'rod3.frame_b.t[1]', 'rod3.frame_b.t[2]', 'rod3.frame_b.t[3]', 'deg2rad1.y[1]', 'deg2rad1.y[2]', 'deg2rad1.y[3]', 'combiTimeTable.y[1]', 'combiTimeTable.y[2]', 'combiTimeTable.y[3]']
10.167773528024554 10.178130678 10.093030796
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f4158e06fb0>