Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUPS.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.001566/0.001566, allocations: 96.42 kB / 20.51 MB, free: 1.152 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.002162/0.002162, allocations: 217.3 kB / 23.82 MB, free: 4.344 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.676/1.676, allocations: 207.7 MB / 234.7 MB, free: 9.773 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.238/0.238, allocations: 44.27 MB / 331.2 MB, free: 9.27 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.Joints.JointUPS,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.|jointUPS.frame_b.r_0.1.|jointUPS.frame_b.r_0.2.|jointUPS.frame_b.r_0.3.|jointUPS.frame_b.f.1.|jointUPS.frame_b.f.2.|jointUPS.frame_b.f.3.|jointUPS.frame_b.t.1.|jointUPS.frame_b.t.2.|jointUPS.frame_b.t.3.|jointUPS.frame_ia.r_0.1.|jointUPS.frame_ia.r_0.2.|jointUPS.frame_ia.r_0.3.|jointUPS.frame_ia.f.1.|jointUPS.frame_ia.f.2.|jointUPS.frame_ia.f.3.|jointUPS.frame_ia.t.1.|jointUPS.frame_ia.t.2.|jointUPS.frame_ia.t.3.",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUPS")
translateModel(ModelicaTest.MultiBody.Joints.JointUPS,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.|jointUPS.frame_b.r_0.1.|jointUPS.frame_b.r_0.2.|jointUPS.frame_b.r_0.3.|jointUPS.frame_b.f.1.|jointUPS.frame_b.f.2.|jointUPS.frame_b.f.3.|jointUPS.frame_b.t.1.|jointUPS.frame_b.t.2.|jointUPS.frame_b.t.3.|jointUPS.frame_ia.r_0.1.|jointUPS.frame_ia.r_0.2.|jointUPS.frame_ia.r_0.3.|jointUPS.frame_ia.f.1.|jointUPS.frame_ia.f.2.|jointUPS.frame_ia.f.3.|jointUPS.frame_ia.t.1.|jointUPS.frame_ia.t.2.|jointUPS.frame_ia.t.3.",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUPS") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.56e-05/2.56e-05, allocations: 3.688 kB / 468.8 MB, free: 3.84 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUPS): time 0.01087/0.01089, allocations: 8.057 MB / 476.9 MB, free: 11.75 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.005883/0.01677, allocations: 2.585 MB / 479.5 MB, free: 9.148 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005466/0.01732, allocations: 47.88 kB / 479.5 MB, free: 9.102 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002557/0.01988, allocations: 1.273 MB / 480.8 MB, free: 7.824 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001124/0.021, allocations: 0.5791 MB / 481.4 MB, free: 7.242 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0008671/0.02187, allocations: 415.1 kB / 481.8 MB, free: 6.836 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.001821/0.02369, allocations: 1.534 MB / 483.3 MB, free: 5.301 MB / 350.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001302/0.02499, allocations: 0.9173 MB / 484.2 MB, free: 4.363 MB / 350.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0007525/0.02574, allocations: 0.6224 MB / 484.8 MB, free: 3.738 MB / 350.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009301/0.02667, allocations: 0.6805 MB / 485.5 MB, free: 3.055 MB / 350.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001456/0.02682, allocations: 84 kB / 485.6 MB, free: 2.973 MB / 350.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007459/0.02757, allocations: 463.1 kB / 486.1 MB, free: 2.52 MB / 350.1 MB
Notification: Performance of combineBinaries: time 0.002117/0.02968, allocations: 2.338 MB / 488.4 MB, free: 164 kB / 350.1 MB
Notification: Performance of replaceArrayConstructors: time 0.001228/0.03091, allocations: 1.514 MB / 489.9 MB, free: 14.63 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.000141/0.03105, allocations: 68 kB / 490 MB, free: 14.56 MB / 366.1 MB
Notification: Performance of FrontEnd: time 0.0001344/0.03119, allocations: 51.5 kB / 490 MB, free: 14.51 MB / 366.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 697 (281)
 * Number of variables: 697 (243)
Notification: Performance of [SIM] Bindings: time 0.006928/0.03811, allocations: 6.307 MB / 496.3 MB, free: 7.957 MB / 366.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0009119/0.03903, allocations: 0.7143 MB / 497.1 MB, free: 7.199 MB / 366.1 MB
Notification: Performance of [SIM] Early Inline: time 0.004971/0.044, allocations: 4.468 MB / 0.4898 GB, free: 2.664 MB / 366.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001205/0.0452, allocations: 0.6622 MB / 0.4904 GB, free: 1.91 MB / 366.1 MB
Notification: Performance of [SIM] Alias: time 0.007632/0.05283, allocations: 5.366 MB / 0.4957 GB, free: 11.94 MB / 382.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0008739/0.05371, allocations: 0.5462 MB / 0.4962 GB, free: 11.32 MB / 382.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0005038/0.05421, allocations: 342 kB / 0.4965 GB, free: 10.93 MB / 382.1 MB
Notification: Performance of [SIM] Detect States: time 0.0008152/0.05503, allocations: 0.6643 MB / 0.4972 GB, free: 10.2 MB / 382.1 MB
Notification: Performance of [SIM] Events: time 0.0002827/0.05531, allocations: 165.5 kB / 0.4973 GB, free: 10.02 MB / 382.1 MB
Notification: Performance of [SIM] Partitioning: time 0.002104/0.05741, allocations: 1.701 MB / 0.499 GB, free: 8.047 MB / 382.1 MB
Notification: Performance of [SIM] Causalize: time 0.01918/0.0766, allocations: 12.39 MB / 0.5111 GB, free: 11.25 MB / 398.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.003858/0.08046, allocations: 3.248 MB / 0.5143 GB, free: 8.004 MB / 398.1 MB
Notification: Performance of [INI] Simplify: time 0.003868/0.08432, allocations: 1.964 MB / 0.5162 GB, free: 5.867 MB / 398.1 MB
Notification: Performance of [INI] Inline: time 0.00562/0.08994, allocations: 5.29 MB / 0.5213 GB, free: 0.5586 MB / 398.1 MB
Notification: Performance of [INI] Partitioning: time 0.0002138/0.09016, allocations: 94.05 kB / 0.5214 GB, free: 456 kB / 398.1 MB
Notification: Performance of [INI] Cleanup: time 0.0008081/0.09096, allocations: 0.6986 MB / 0.5221 GB, free: 15.75 MB / 414.1 MB
Notification: Performance of [INI] Causalize: time 0.01215/0.1031, allocations: 8.441 MB / 0.5304 GB, free: 7.16 MB / 414.1 MB
Notification: Performance of [INI] Tearing: time 0.0001509/0.1033, allocations: 192 kB / 0.5305 GB, free: 6.973 MB / 414.1 MB
Notification: Performance of [SIM] Initialization: time 1.714e-06/0.1033, allocations: 0 / 0.5305 GB, free: 6.973 MB / 414.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.00187/0.1051, allocations: 1.136 MB / 0.5316 GB, free: 5.574 MB / 414.1 MB
Notification: Performance of [SIM] Remove Dummies: time 2.619e-05/0.1052, allocations: 17.88 kB / 0.5317 GB, free: 5.555 MB / 414.1 MB
Notification: Performance of [SIM] Tearing: time 0.0002285/0.1054, allocations: 319.8 kB / 0.532 GB, free: 5.242 MB / 414.1 MB
Notification: Performance of [SIM] Categorize: time 6.72e-05/0.1055, allocations: 15.86 kB / 0.532 GB, free: 5.227 MB / 414.1 MB
Notification: Performance of [SIM] Solve: time 0.006301/0.1118, allocations: 3.682 MB / 0.5356 GB, free: 1.535 MB / 414.1 MB
Error: Internal error NBJacobian.SparsityColoring.PartialD2ColoringAlgC.getIndices failed because cref jointUPS.frame_ia.f[1] is neither a seed nor a partial candidate!
"
[Timeout remaining time 660]
[Calling sys.exit(0), Time elapsed: 3.434460095013492]
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]', 'jointUPS.frame_b.r_0[1]', 'jointUPS.frame_b.r_0[2]', 'jointUPS.frame_b.r_0[3]', 'jointUPS.frame_b.f[1]', 'jointUPS.frame_b.f[2]', 'jointUPS.frame_b.f[3]', 'jointUPS.frame_b.t[1]', 'jointUPS.frame_b.t[2]', 'jointUPS.frame_b.t[3]', 'jointUPS.frame_ia.r_0[1]', 'jointUPS.frame_ia.r_0[2]', 'jointUPS.frame_ia.r_0[3]', 'jointUPS.frame_ia.f[1]', 'jointUPS.frame_ia.f[2]', 'jointUPS.frame_ia.f[3]', 'jointUPS.frame_ia.t[1]', 'jointUPS.frame_ia.t[2]', 'jointUPS.frame_ia.t[3]']
0.3930291809956543 0.40618940299999995 0.372172362
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f37dfaf2fb0>