Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001432/0.001432, allocations: 75.75 kB / 21.25 MB, free: 396 kB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001514/0.001514, allocations: 159.7 kB / 24.49 MB, free: 3.453 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.538/1.538, allocations: 177.1 MB / 204.7 MB, free: 8.91 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2282/0.2282, allocations: 34.26 MB / 295.4 MB, free: 14.5 MB / 286.1 MB
"
[Timeout remaining time 179]
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo)
Using package Modelica with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: translateModel(ModelicaTest.MultiBody.Joints.JointUSP2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5003,variableFilter="time|revolute.phi|revolute.w",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2")
translateModel(ModelicaTest.MultiBody.Joints.JointUSP2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5003,variableFilter="time|revolute.phi|revolute.w",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.003592/0.003592, allocations: 83.86 kB / 443.2 MB, free: 6.52 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4112/0.4148, allocations: 66.05 MB / 0.4973 GB, free: 29.81 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUSP2): time 0.01094/0.4257, allocations: 11.6 MB / 0.5086 GB, free: 22.25 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008703/0.4344, allocations: 4.614 MB / 0.5131 GB, free: 19.3 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002637/0.4371, allocations: 95.47 kB / 0.5132 GB, free: 19.27 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.007878/0.4449, allocations: 3.869 MB / 0.517 GB, free: 17.63 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007023/0.452, allocations: 2.241 MB / 0.5192 GB, free: 16.57 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003047/0.455, allocations: 1.187 MB / 0.5203 GB, free: 16.08 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.0109/0.4659, allocations: 5.829 MB / 0.526 GB, free: 13.88 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.006962/0.4729, allocations: 4.003 MB / 0.5299 GB, free: 12.03 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005566/0.4784, allocations: 2.205 MB / 0.5321 GB, free: 11.09 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006731/0.4852, allocations: 2.997 MB / 0.535 GB, free: 9.906 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001683/0.4868, allocations: 0.4994 MB / 0.5355 GB, free: 9.906 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003906/0.4907, allocations: 1.536 MB / 0.537 GB, free: 9.367 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003246/0.494, allocations: 2.707 MB / 0.5396 GB, free: 7.746 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003623/0.4976, allocations: 2.248 MB / 0.5418 GB, free: 6.207 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01324/0.5109, allocations: 9.965 MB / 0.5516 GB, free: 13.86 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.28e-06/0.5109, allocations: 0 / 0.5516 GB, free: 13.86 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.433e-06/0.5109, allocations: 0 / 0.5516 GB, free: 13.86 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0003688/0.5112, allocations: 0 / 0.5516 GB, free: 13.86 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1932
 * Number of variables: 1932
Notification: Performance of Generate backend data structure: time 0.01949/0.5307, allocations: 6.597 MB / 0.558 GB, free: 7.191 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.639e-05/0.5308, allocations: 12.03 kB / 0.558 GB, free: 7.18 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01988/0.5507, allocations: 2.856 MB / 0.5608 GB, free: 4.316 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01082/0.5615, allocations: 3.587 MB / 0.5643 GB, free: 0.6719 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005951/0.5621, allocations: 375.9 kB / 0.5647 GB, free: 296 kB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002254/0.5643, allocations: 427.8 kB / 0.5651 GB, free: 15.87 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03895/0.6033, allocations: 13.98 MB / 0.5787 GB, free: 1.012 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004/0.6037, allocations: 59.56 kB / 0.5788 GB, free: 0.9531 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002182/0.6058, allocations: 199.8 kB / 0.579 GB, free: 0.7578 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004945/0.6108, allocations: 2.417 MB / 0.5813 GB, free: 14.34 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03196/0.6428, allocations: 11.77 MB / 0.5928 GB, free: 2.555 MB / 494.1 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.08798/0.7307, allocations: 47.75 MB / 0.6395 GB, free: 1.531 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0149/0.7456, allocations: 4.303 MB / 0.6437 GB, free: 13.18 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00513/0.7508, allocations: 2.758 MB / 0.6464 GB, free: 10.4 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02616/0.7769, allocations: 10.22 MB / 0.6563 GB, free: 144 kB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.105e-05/0.777, allocations: 59.8 kB / 0.6564 GB, free: 76 kB / 0.545 GB
Notification: Performance of pre-optimization done (n=197): time 6.832e-06/0.777, allocations: 3.938 kB / 0.6564 GB, free: 72 kB / 0.545 GB
Notification: Performance of matching and sorting (n=248): time 0.4793/1.256, allocations: 44.67 MB / 0.7 GB, free: 145.2 MB / 0.5763 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.881e-05/1.256, allocations: 232 kB / 0.7002 GB, free: 144.9 MB / 0.5763 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005907/1.262, allocations: 4.497 MB / 0.7046 GB, free: 143.3 MB / 0.5763 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004822/1.263, allocations: 73.61 kB / 0.7047 GB, free: 143.3 MB / 0.5763 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002162/1.265, allocations: 4.012 MB / 0.7086 GB, free: 140.5 MB / 0.5763 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0009807/1.266, allocations: 0.9814 MB / 0.7096 GB, free: 139.8 MB / 0.5763 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002095/1.268, allocations: 1.511 MB / 0.711 GB, free: 139.1 MB / 0.5763 GB
Notification: Performance of setup shared object (initialization): time 4.848e-05/1.268, allocations: 301.1 kB / 0.7113 GB, free: 138.8 MB / 0.5763 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005335/1.273, allocations: 3.453 MB / 0.7147 GB, free: 137.6 MB / 0.5763 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00733/1.281, allocations: 7.758 MB / 0.7223 GB, free: 130.6 MB / 0.5763 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0126/1.293, allocations: 11.88 MB / 0.7339 GB, free: 121.6 MB / 0.5763 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001707/1.294, allocations: 43.22 kB / 0.7339 GB, free: 121.6 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=963) (initialization): time 0.02831/1.322, allocations: 14.42 MB / 0.748 GB, free: 116.7 MB / 0.5763 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001888/1.322, allocations: 72.78 kB / 0.7481 GB, free: 116.7 MB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002388/1.322, allocations: 145.6 kB / 0.7482 GB, free: 116.7 MB / 0.5763 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01125/1.334, allocations: 4.019 MB / 0.7521 GB, free: 116 MB / 0.5763 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00494/1.338, allocations: 1.632 MB / 0.7537 GB, free: 115.8 MB / 0.5763 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02271/1.361, allocations: 19.5 MB / 0.7728 GB, free: 97.33 MB / 0.5763 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006251/1.367, allocations: 487.4 kB / 0.7732 GB, free: 96.85 MB / 0.5763 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001103/1.369, allocations: 439.1 kB / 0.7737 GB, free: 96.43 MB / 0.5763 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 679
 * Number of states: 0 ()
 * Number of discrete variables: 37 (jointUSP.prismatic.positiveBranch,bodyBox.color[3],bodyBox.color[2],bodyBox.color[1],bodyBox.body.sphereColor[3],bodyBox.body.sphereColor[2],bodyBox.body.sphereColor[1],revolute.cylinderColor[3],revolute.cylinderColor[2],revolute.cylinderColor[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],jointUSP.rod1Color[3],jointUSP.rod1Color[2],jointUSP.rod1Color[1],jointUSP.cylinderColor[3],jointUSP.cylinderColor[2],jointUSP.cylinderColor[1],jointUSP.sphereColor[3],jointUSP.sphereColor[2],jointUSP.sphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (880):
 * Single equations (assignments): 876
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(2,58,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.004439/1.373, allocations: 2.655 MB / 0.7762 GB, free: 93.77 MB / 0.5763 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001018/1.374, allocations: 409.3 kB / 0.7766 GB, free: 93.36 MB / 0.5763 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01683/1.391, allocations: 8.573 MB / 0.785 GB, free: 84.77 MB / 0.5763 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.358e-05/1.391, allocations: 24 kB / 0.785 GB, free: 84.74 MB / 0.5763 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.263e-05/1.391, allocations: 12 kB / 0.785 GB, free: 84.73 MB / 0.5763 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.001e-05/1.391, allocations: 15.95 kB / 0.7851 GB, free: 84.71 MB / 0.5763 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03077/1.422, allocations: 16.17 MB / 0.8009 GB, free: 68.57 MB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.187e-05/1.422, allocations: 4 kB / 0.8009 GB, free: 68.57 MB / 0.5763 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001935/1.424, allocations: 399.4 kB / 0.8012 GB, free: 68.18 MB / 0.5763 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0114/1.435, allocations: 4.071 MB / 0.8052 GB, free: 64.1 MB / 0.5763 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0003691/1.435, allocations: 43.98 kB / 0.8053 GB, free: 64.05 MB / 0.5763 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02215/1.458, allocations: 17.91 MB / 0.8227 GB, free: 46.04 MB / 0.5763 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.827e-06/1.458, allocations: 8 kB / 0.8228 GB, free: 46.04 MB / 0.5763 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02213/1.48, allocations: 13.17 MB / 0.8356 GB, free: 32.84 MB / 0.5763 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001376/1.481, allocations: 361.2 kB / 0.836 GB, free: 32.48 MB / 0.5763 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001198/1.482, allocations: 43.94 kB / 0.836 GB, free: 32.44 MB / 0.5763 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002931/1.485, allocations: 223.6 kB / 0.8362 GB, free: 32.22 MB / 0.5763 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006574/1.486, allocations: 161.4 kB / 0.8364 GB, free: 32.07 MB / 0.5763 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002666/1.486, allocations: 63.98 kB / 0.8364 GB, free: 32 MB / 0.5763 GB
Notification: Performance of sorting global known variables: time 0.004223/1.49, allocations: 2.609 MB / 0.839 GB, free: 29.39 MB / 0.5763 GB
Notification: Performance of sort global known variables: time 1e-07/1.49, allocations: 0 / 0.839 GB, free: 29.39 MB / 0.5763 GB
Notification: Performance of remove unused functions: time 0.007033/1.497, allocations: 1.461 MB / 0.8404 GB, free: 27.93 MB / 0.5763 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 2 (revolute.phi,revolute.w)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (173):
 * Single equations (assignments): 169
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(2,52,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0006855/1.498, allocations: 218.1 kB / 0.8406 GB, free: 27.71 MB / 0.5763 GB
Notification: Performance of simCode: created initialization part: time 0.01144/1.509, allocations: 7.909 MB / 0.8483 GB, free: 19.82 MB / 0.5763 GB
Notification: Performance of simCode: created event and clocks part: time 7.995e-06/1.51, allocations: 0 / 0.8483 GB, free: 19.82 MB / 0.5763 GB
Notification: Performance of simCode: created simulation system equations: time 0.004079/1.514, allocations: 2.865 MB / 0.8511 GB, free: 16.94 MB / 0.5763 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004392/1.518, allocations: 470.4 kB / 0.8516 GB, free: 16.49 MB / 0.5763 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02394/1.542, allocations: 13.12 MB / 0.8644 GB, free: 3.324 MB / 0.5763 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007836/1.55, allocations: 3.575 MB / 0.8679 GB, free: 15.72 MB / 0.5919 GB
Notification: Performance of simCode: alias equations: time 0.006234/1.556, allocations: 2.127 MB / 0.87 GB, free: 13.59 MB / 0.5919 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001236/1.557, allocations: 268.5 kB / 0.8702 GB, free: 13.33 MB / 0.5919 GB
Notification: Performance of SimCode: time 1.263e-06/1.557, allocations: 0 / 0.8702 GB, free: 13.33 MB / 0.5919 GB
Notification: Performance of Templates: time 0.1113/1.669, allocations: 57.48 MB / 0.9263 GB, free: 4.188 MB / 0.6388 GB
"
[Timeout remaining time 658]
make -j1 -f ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.makefile [Timeout 660]
(rm -f ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.sim & ./ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2  -abortSlowSimulation -alarm=480 -s ida -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Joints/JointUSP2/JointUSP2.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 660]
Reference file matches
[Calling sys.exit(0), Time elapsed: 8.577991549973376]