Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.0.0_ModelicaTest.MultiBody.Joints.JointUSP.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.001744/0.001744, allocations: 103.6 kB / 21.25 MB, free: 0.5664 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.002072/0.002072, allocations: 212.5 kB / 24.55 MB, free: 3.824 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.528/1.528, allocations: 230.6 MB / 258.3 MB, free: 7.742 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo): time 0.2282/0.2282, allocations: 44.93 MB / 359.7 MB, free: 6.012 MB / 286.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+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.Joints.JointUSP,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|rRod1_ia.1.|rRod1_ia.2.|rRod1_ia.3.|rRod2_ib.1.|rRod2_ib.2.|rRod2_ib.3.|Fixed1.frame_b.r_0.1.|Fixed1.frame_b.r_0.2.|Fixed1.frame_b.r_0.3.|Fixed1.frame_b.f.1.|Fixed1.frame_b.f.2.|Fixed1.frame_b.f.3.|Fixed1.frame_b.t.1.|Fixed1.frame_b.t.2.|Fixed1.frame_b.t.3.|jointUSP.frame_b.r_0.1.|jointUSP.frame_b.r_0.2.|jointUSP.frame_b.r_0.3.|jointUSP.frame_b.f.1.|jointUSP.frame_b.f.2.|jointUSP.frame_b.f.3.|jointUSP.frame_b.t.1.|jointUSP.frame_b.t.2.|jointUSP.frame_b.t.3.|jointUSP.frame_ia.r_0.1.|jointUSP.frame_ia.r_0.2.|jointUSP.frame_ia.r_0.3.|jointUSP.frame_ia.f.1.|jointUSP.frame_ia.f.2.|jointUSP.frame_ia.f.3.|jointUSP.frame_ia.t.1.|jointUSP.frame_ia.t.2.|jointUSP.frame_ia.t.3.|jointUSP.frame_im.r_0.1.|jointUSP.frame_im.r_0.2.|jointUSP.frame_im.r_0.3.|jointUSP.frame_im.f.1.|jointUSP.frame_im.f.2.|jointUSP.frame_im.f.3.|jointUSP.frame_im.t.1.|jointUSP.frame_im.t.2.|jointUSP.frame_im.t.3.|jointUSP.prismatic.frame_b.r_0.1.|jointUSP.prismatic.frame_b.r_0.2.|jointUSP.prismatic.frame_b.r_0.3.|jointUSP.prismatic.frame_b.f.1.|jointUSP.prismatic.frame_b.f.2.|jointUSP.prismatic.frame_b.f.3.|jointUSP.prismatic.frame_b.t.1.|jointUSP.prismatic.frame_b.t.2.|jointUSP.prismatic.frame_b.t.3.|jointUSP.rod1.frame_b.r_0.1.|jointUSP.rod1.frame_b.r_0.2.|jointUSP.rod1.frame_b.r_0.3.|jointUSP.rod1.frame_b.f.1.|jointUSP.rod1.frame_b.f.2.|jointUSP.rod1.frame_b.f.3.|jointUSP.rod1.frame_b.t.1.|jointUSP.rod1.frame_b.t.2.|jointUSP.rod1.frame_b.t.3.|jointUSP.rod1.frame_ia.r_0.1.|jointUSP.rod1.frame_ia.r_0.2.|jointUSP.rod1.frame_ia.r_0.3.|jointUSP.rod1.frame_ia.f.1.|jointUSP.rod1.frame_ia.f.2.|jointUSP.rod1.frame_ia.f.3.|jointUSP.rod1.frame_ia.t.1.|jointUSP.rod1.frame_ia.t.2.|jointUSP.rod1.frame_ia.t.3.|jointUSP.rod2.frame_b.r_0.1.|jointUSP.rod2.frame_b.r_0.2.|jointUSP.rod2.frame_b.r_0.3.|jointUSP.rod2.frame_b.f.1.|jointUSP.rod2.frame_b.f.2.|jointUSP.rod2.frame_b.f.3.|jointUSP.rod2.frame_b.t.1.|jointUSP.rod2.frame_b.t.2.|jointUSP.rod2.frame_b.t.3.|jointUSP.relativePosition.frame_b.r_0.1.|jointUSP.relativePosition.frame_b.r_0.2.|jointUSP.relativePosition.frame_b.r_0.3.|jointUSP.relativePosition.frame_b.f.1.|jointUSP.relativePosition.frame_b.f.2.|jointUSP.relativePosition.frame_b.f.3.|jointUSP.relativePosition.frame_b.t.1.|jointUSP.relativePosition.frame_b.t.2.|jointUSP.relativePosition.frame_b.t.3.|jointUSP.relativePosition.relativePosition.frame_b.r_0.1.|jointUSP.relativePosition.relativePosition.frame_b.r_0.2.|jointUSP.relativePosition.relativePosition.frame_b.r_0.3.|jointUSP.relativePosition.relativePosition.frame_b.f.1.|jointUSP.relativePosition.relativePosition.frame_b.f.2.|jointUSP.relativePosition.relativePosition.frame_b.f.3.|jointUSP.relativePosition.relativePosition.frame_b.t.1.|jointUSP.relativePosition.relativePosition.frame_b.t.2.|jointUSP.relativePosition.relativePosition.frame_b.t.3.|jointUSP.position_b.1..y|jointUSP.position_b.2..y|jointUSP.position_b.3..y",fileNamePrefix="ModelicaTest_4.0.0_ModelicaTest.MultiBody.Joints.JointUSP")
translateModel(ModelicaTest.MultiBody.Joints.JointUSP,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|rRod1_ia.1.|rRod1_ia.2.|rRod1_ia.3.|rRod2_ib.1.|rRod2_ib.2.|rRod2_ib.3.|Fixed1.frame_b.r_0.1.|Fixed1.frame_b.r_0.2.|Fixed1.frame_b.r_0.3.|Fixed1.frame_b.f.1.|Fixed1.frame_b.f.2.|Fixed1.frame_b.f.3.|Fixed1.frame_b.t.1.|Fixed1.frame_b.t.2.|Fixed1.frame_b.t.3.|jointUSP.frame_b.r_0.1.|jointUSP.frame_b.r_0.2.|jointUSP.frame_b.r_0.3.|jointUSP.frame_b.f.1.|jointUSP.frame_b.f.2.|jointUSP.frame_b.f.3.|jointUSP.frame_b.t.1.|jointUSP.frame_b.t.2.|jointUSP.frame_b.t.3.|jointUSP.frame_ia.r_0.1.|jointUSP.frame_ia.r_0.2.|jointUSP.frame_ia.r_0.3.|jointUSP.frame_ia.f.1.|jointUSP.frame_ia.f.2.|jointUSP.frame_ia.f.3.|jointUSP.frame_ia.t.1.|jointUSP.frame_ia.t.2.|jointUSP.frame_ia.t.3.|jointUSP.frame_im.r_0.1.|jointUSP.frame_im.r_0.2.|jointUSP.frame_im.r_0.3.|jointUSP.frame_im.f.1.|jointUSP.frame_im.f.2.|jointUSP.frame_im.f.3.|jointUSP.frame_im.t.1.|jointUSP.frame_im.t.2.|jointUSP.frame_im.t.3.|jointUSP.prismatic.frame_b.r_0.1.|jointUSP.prismatic.frame_b.r_0.2.|jointUSP.prismatic.frame_b.r_0.3.|jointUSP.prismatic.frame_b.f.1.|jointUSP.prismatic.frame_b.f.2.|jointUSP.prismatic.frame_b.f.3.|jointUSP.prismatic.frame_b.t.1.|jointUSP.prismatic.frame_b.t.2.|jointUSP.prismatic.frame_b.t.3.|jointUSP.rod1.frame_b.r_0.1.|jointUSP.rod1.frame_b.r_0.2.|jointUSP.rod1.frame_b.r_0.3.|jointUSP.rod1.frame_b.f.1.|jointUSP.rod1.frame_b.f.2.|jointUSP.rod1.frame_b.f.3.|jointUSP.rod1.frame_b.t.1.|jointUSP.rod1.frame_b.t.2.|jointUSP.rod1.frame_b.t.3.|jointUSP.rod1.frame_ia.r_0.1.|jointUSP.rod1.frame_ia.r_0.2.|jointUSP.rod1.frame_ia.r_0.3.|jointUSP.rod1.frame_ia.f.1.|jointUSP.rod1.frame_ia.f.2.|jointUSP.rod1.frame_ia.f.3.|jointUSP.rod1.frame_ia.t.1.|jointUSP.rod1.frame_ia.t.2.|jointUSP.rod1.frame_ia.t.3.|jointUSP.rod2.frame_b.r_0.1.|jointUSP.rod2.frame_b.r_0.2.|jointUSP.rod2.frame_b.r_0.3.|jointUSP.rod2.frame_b.f.1.|jointUSP.rod2.frame_b.f.2.|jointUSP.rod2.frame_b.f.3.|jointUSP.rod2.frame_b.t.1.|jointUSP.rod2.frame_b.t.2.|jointUSP.rod2.frame_b.t.3.|jointUSP.relativePosition.frame_b.r_0.1.|jointUSP.relativePosition.frame_b.r_0.2.|jointUSP.relativePosition.frame_b.r_0.3.|jointUSP.relativePosition.frame_b.f.1.|jointUSP.relativePosition.frame_b.f.2.|jointUSP.relativePosition.frame_b.f.3.|jointUSP.relativePosition.frame_b.t.1.|jointUSP.relativePosition.frame_b.t.2.|jointUSP.relativePosition.frame_b.t.3.|jointUSP.relativePosition.relativePosition.frame_b.r_0.1.|jointUSP.relativePosition.relativePosition.frame_b.r_0.2.|jointUSP.relativePosition.relativePosition.frame_b.r_0.3.|jointUSP.relativePosition.relativePosition.frame_b.f.1.|jointUSP.relativePosition.relativePosition.frame_b.f.2.|jointUSP.relativePosition.relativePosition.frame_b.f.3.|jointUSP.relativePosition.relativePosition.frame_b.t.1.|jointUSP.relativePosition.relativePosition.frame_b.t.2.|jointUSP.relativePosition.relativePosition.frame_b.t.3.|jointUSP.position_b.1..y|jointUSP.position_b.2..y|jointUSP.position_b.3..y",fileNamePrefix="ModelicaTest_4.0.0_ModelicaTest.MultiBody.Joints.JointUSP") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.595e-05/2.595e-05, allocations: 2.281 kB / 0.4943 GB, free: 10.01 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUSP): time 0.01259/0.01262, allocations: 9.522 MB / 0.5036 GB, free: 452 kB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00833/0.02095, allocations: 3.252 MB / 0.5068 GB, free: 13.18 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002034/0.02298, allocations: 63.81 kB / 0.5069 GB, free: 13.11 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.006278/0.02926, allocations: 3.186 MB / 0.51 GB, free: 9.918 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002897/0.03216, allocations: 0.8203 MB / 0.5108 GB, free: 9.094 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001848/0.03401, allocations: 0.6589 MB / 0.5114 GB, free: 8.434 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.003886/0.03789, allocations: 2.498 MB / 0.5139 GB, free: 5.926 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002998/0.04089, allocations: 2.272 MB / 0.5161 GB, free: 3.613 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002097/0.04299, allocations: 1.223 MB / 0.5173 GB, free: 2.387 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001836/0.04483, allocations: 1.237 MB / 0.5185 GB, free: 1.145 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0003788/0.0452, allocations: 244 kB / 0.5187 GB, free: 0.9062 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001397/0.0466, allocations: 0.6865 MB / 0.5194 GB, free: 224 kB / 382.1 MB
Notification: Performance of combineBinaries: time 0.2886/0.3352, allocations: 3.867 MB / 0.5232 GB, free: 18.02 MB / 382.1 MB
Notification: Performance of replaceArrayConstructors: time 0.002532/0.3377, allocations: 2.29 MB / 0.5254 GB, free: 16.86 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001009/0.3387, allocations: 129.2 kB / 0.5255 GB, free: 16.86 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.0004431/0.3392, allocations: 57.31 kB / 0.5256 GB, free: 16.85 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: 1077 (517)
 * Number of variables: 1077 (341)
Notification: Performance of [SIM] Bindings: time 0.01534/0.3545, allocations: 10.71 MB / 0.5361 GB, free: 12.66 MB / 382.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002558/0.3571, allocations: 1.529 MB / 0.5375 GB, free: 11.73 MB / 382.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01074/0.3678, allocations: 8.553 MB / 0.5459 GB, free: 6.785 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.003156/0.371, allocations: 1.183 MB / 0.5471 GB, free: 6.266 MB / 382.1 MB
Notification: Performance of [SIM] Alias: time 0.01112/0.3821, allocations: 7.925 MB / 0.5548 GB, free: 1.652 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.002081/0.3842, allocations: 1.03 MB / 0.5558 GB, free: 1.184 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.001087/0.3852, allocations: 0.6965 MB / 0.5565 GB, free: 0.6836 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.001614/0.3869, allocations: 1.425 MB / 0.5579 GB, free: 15.65 MB / 398.1 MB
Notification: Performance of [SIM] Events: time 0.0005625/0.3874, allocations: 236.9 kB / 0.5581 GB, free: 15.51 MB / 398.1 MB
Notification: Performance of [SIM] Partitioning: time 0.004127/0.3915, allocations: 3.243 MB / 0.5613 GB, free: 13.54 MB / 398.1 MB
Error: Internal error NBResolveSingularities.indexReduction failed because slicing during index reduction is not yet supported.
  needed sliced dummy:		[STAT] (9) Real[3, 3] jointUSP.frame_im.R.T
	slice: {0, 2, 4, 6, 8}
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 3.3320989059866406]
Failed to read output from testmodel.py, exit status != 0:
['time', 'rRod1_ia[1]', 'rRod1_ia[2]', 'rRod1_ia[3]', 'rRod2_ib[1]', 'rRod2_ib[2]', 'rRod2_ib[3]', 'Fixed1.frame_b.r_0[1]', 'Fixed1.frame_b.r_0[2]', 'Fixed1.frame_b.r_0[3]', 'Fixed1.frame_b.f[1]', 'Fixed1.frame_b.f[2]', 'Fixed1.frame_b.f[3]', 'Fixed1.frame_b.t[1]', 'Fixed1.frame_b.t[2]', 'Fixed1.frame_b.t[3]', 'jointUSP.frame_b.r_0[1]', 'jointUSP.frame_b.r_0[2]', 'jointUSP.frame_b.r_0[3]', 'jointUSP.frame_b.f[1]', 'jointUSP.frame_b.f[2]', 'jointUSP.frame_b.f[3]', 'jointUSP.frame_b.t[1]', 'jointUSP.frame_b.t[2]', 'jointUSP.frame_b.t[3]', 'jointUSP.frame_ia.r_0[1]', 'jointUSP.frame_ia.r_0[2]', 'jointUSP.frame_ia.r_0[3]', 'jointUSP.frame_ia.f[1]', 'jointUSP.frame_ia.f[2]', 'jointUSP.frame_ia.f[3]', 'jointUSP.frame_ia.t[1]', 'jointUSP.frame_ia.t[2]', 'jointUSP.frame_ia.t[3]', 'jointUSP.frame_im.r_0[1]', 'jointUSP.frame_im.r_0[2]', 'jointUSP.frame_im.r_0[3]', 'jointUSP.frame_im.f[1]', 'jointUSP.frame_im.f[2]', 'jointUSP.frame_im.f[3]', 'jointUSP.frame_im.t[1]', 'jointUSP.frame_im.t[2]', 'jointUSP.frame_im.t[3]', 'jointUSP.prismatic.frame_b.r_0[1]', 'jointUSP.prismatic.frame_b.r_0[2]', 'jointUSP.prismatic.frame_b.r_0[3]', 'jointUSP.prismatic.frame_b.f[1]', 'jointUSP.prismatic.frame_b.f[2]', 'jointUSP.prismatic.frame_b.f[3]', 'jointUSP.prismatic.frame_b.t[1]', 'jointUSP.prismatic.frame_b.t[2]', 'jointUSP.prismatic.frame_b.t[3]', 'jointUSP.rod1.frame_b.r_0[1]', 'jointUSP.rod1.frame_b.r_0[2]', 'jointUSP.rod1.frame_b.r_0[3]', 'jointUSP.rod1.frame_b.f[1]', 'jointUSP.rod1.frame_b.f[2]', 'jointUSP.rod1.frame_b.f[3]', 'jointUSP.rod1.frame_b.t[1]', 'jointUSP.rod1.frame_b.t[2]', 'jointUSP.rod1.frame_b.t[3]', 'jointUSP.rod1.frame_ia.r_0[1]', 'jointUSP.rod1.frame_ia.r_0[2]', 'jointUSP.rod1.frame_ia.r_0[3]', 'jointUSP.rod1.frame_ia.f[1]', 'jointUSP.rod1.frame_ia.f[2]', 'jointUSP.rod1.frame_ia.f[3]', 'jointUSP.rod1.frame_ia.t[1]', 'jointUSP.rod1.frame_ia.t[2]', 'jointUSP.rod1.frame_ia.t[3]', 'jointUSP.rod2.frame_b.r_0[1]', 'jointUSP.rod2.frame_b.r_0[2]', 'jointUSP.rod2.frame_b.r_0[3]', 'jointUSP.rod2.frame_b.f[1]', 'jointUSP.rod2.frame_b.f[2]', 'jointUSP.rod2.frame_b.f[3]', 'jointUSP.rod2.frame_b.t[1]', 'jointUSP.rod2.frame_b.t[2]', 'jointUSP.rod2.frame_b.t[3]', 'jointUSP.relativePosition.frame_b.r_0[1]', 'jointUSP.relativePosition.frame_b.r_0[2]', 'jointUSP.relativePosition.frame_b.r_0[3]', 'jointUSP.relativePosition.frame_b.f[1]', 'jointUSP.relativePosition.frame_b.f[2]', 'jointUSP.relativePosition.frame_b.f[3]', 'jointUSP.relativePosition.frame_b.t[1]', 'jointUSP.relativePosition.frame_b.t[2]', 'jointUSP.relativePosition.frame_b.t[3]', 'jointUSP.relativePosition.relativePosition.frame_b.r_0[1]', 'jointUSP.relativePosition.relativePosition.frame_b.r_0[2]', 'jointUSP.relativePosition.relativePosition.frame_b.r_0[3]', 'jointUSP.relativePosition.relativePosition.frame_b.f[1]', 'jointUSP.relativePosition.relativePosition.frame_b.f[2]', 'jointUSP.relativePosition.relativePosition.frame_b.f[3]', 'jointUSP.relativePosition.relativePosition.frame_b.t[1]', 'jointUSP.relativePosition.relativePosition.frame_b.t[2]', 'jointUSP.relativePosition.relativePosition.frame_b.t[3]', 'jointUSP.position_b[1].y', 'jointUSP.position_b[2].y', 'jointUSP.position_b[3].y']
0.4342113350285217 0.448316931 0.105869761
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f71ec6eafb0>