Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.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.00173/0.00173, allocations: 107.6 kB / 21.2 MB, free: 0.6641 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.002317/0.002317, allocations: 210.2 kB / 24.49 MB, free: 3.965 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.63/1.63, allocations: 230.6 MB / 258.2 MB, free: 7.816 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0+maint.om/package.mo): time 0.2601/0.2601, allocations: 46.97 MB / 361.6 MB, free: 4.051 MB / 286.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.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.1.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.1.0_ModelicaTest.MultiBody.Joints.JointUSP") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 3.448e-05/3.448e-05, allocations: 2.281 kB / 0.4973 GB, free: 7.273 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUSP): time 0.01346/0.0135, allocations: 9.531 MB / 0.5066 GB, free: 13.7 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.009793/0.02329, allocations: 3.236 MB / 0.5098 GB, free: 10.45 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002518/0.02581, allocations: 71.75 kB / 0.5098 GB, free: 10.38 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00669/0.0325, allocations: 3.186 MB / 0.5129 GB, free: 7.184 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00295/0.03545, allocations: 0.8242 MB / 0.5137 GB, free: 6.355 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001997/0.03745, allocations: 0.655 MB / 0.5144 GB, free: 5.699 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004275/0.04172, allocations: 2.489 MB / 0.5168 GB, free: 3.203 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003309/0.04503, allocations: 2.304 MB / 0.5191 GB, free: 0.8555 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.338/0.3831, allocations: 1.218 MB / 0.5203 GB, free: 16.5 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004584/0.3876, allocations: 1.36 MB / 0.5216 GB, free: 16.5 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002129/0.3898, allocations: 242.2 kB / 0.5218 GB, free: 16.5 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003944/0.3937, allocations: 0.6988 MB / 0.5225 GB, free: 16.5 MB / 382.1 MB
Notification: Performance of combineBinaries: time 0.005784/0.3995, allocations: 3.88 MB / 0.5263 GB, free: 15.17 MB / 382.1 MB
Notification: Performance of replaceArrayConstructors: time 0.002672/0.4022, allocations: 2.293 MB / 0.5285 GB, free: 14.01 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001202/0.4034, allocations: 128.1 kB / 0.5286 GB, free: 14.01 MB / 382.1 MB
Notification: Performance of FrontEnd: time 0.0005688/0.4039, allocations: 57.78 kB / 0.5287 GB, free: 14 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.01729/0.4212, allocations: 10.7 MB / 0.5392 GB, free: 9.004 MB / 382.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.002833/0.4241, allocations: 1.516 MB / 0.5406 GB, free: 8.078 MB / 382.1 MB
Notification: Performance of [SIM] Early Inline: time 0.01305/0.4371, allocations: 8.545 MB / 0.549 GB, free: 3.129 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.003663/0.4408, allocations: 1.357 MB / 0.5503 GB, free: 2.453 MB / 382.1 MB
Notification: Performance of [SIM] Alias: time 0.01352/0.4543, allocations: 7.749 MB / 0.5579 GB, free: 13.81 MB / 398.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.002382/0.4567, allocations: 1.158 MB / 0.559 GB, free: 13.22 MB / 398.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.001108/0.4578, allocations: 0.6986 MB / 0.5597 GB, free: 12.72 MB / 398.1 MB
Notification: Performance of [SIM] Detect States: time 0.001839/0.4596, allocations: 1.425 MB / 0.5611 GB, free: 11.64 MB / 398.1 MB
Notification: Performance of [SIM] Events: time 0.000477/0.4601, allocations: 235.5 kB / 0.5613 GB, free: 11.49 MB / 398.1 MB
Notification: Performance of [SIM] Partitioning: time 0.00408/0.4642, allocations: 3.23 MB / 0.5645 GB, free: 9.184 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 659]
[Calling sys.exit(0), Time elapsed: 3.6989801130257547]
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.5102318781428039 0.5266841019999999 0.118966158
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f9553266f20>