Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false)
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.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(ModelicaTest.MultiBody.Joints.JointUSP2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|revolute.phi|revolute.w",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2")
translateModel(ModelicaTest.MultiBody.Joints.JointUSP2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|revolute.phi|revolute.w",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.00119/0.00119, allocations: 105.3 kB / 17.03 MB, free: 6.129 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001064/0.001064, allocations: 194.9 kB / 17.96 MB, free: 5.215 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.664/1.664, allocations: 205.1 MB / 223.8 MB, free: 12.25 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.2442/0.2442, allocations: 43.53 MB / 314.7 MB, free: 476 kB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.00157/0.001571, allocations: 70.86 kB / 384.2 MB, free: 56.41 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1412/0.1428, allocations: 57.79 MB / 442 MB, free: 6.137 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUSP2): time 0.01337/0.1562, allocations: 13.32 MB / 455.3 MB, free: 8.754 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01228/0.1686, allocations: 4.83 MB / 460.1 MB, free: 3.91 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004119/0.1728, allocations: 83.75 kB / 460.2 MB, free: 3.828 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.008182/0.181, allocations: 3.991 MB / 464.2 MB, free: 15.82 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.009024/0.1902, allocations: 2.202 MB / 466.4 MB, free: 13.61 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004125/0.1943, allocations: 1.189 MB / 467.6 MB, free: 12.42 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.009816/0.2041, allocations: 5.567 MB / 473.1 MB, free: 6.844 MB / 350.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.006968/0.2112, allocations: 5.178 MB / 478.3 MB, free: 1.594 MB / 350.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004861/0.2161, allocations: 2.189 MB / 480.5 MB, free: 15.39 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005705/0.2218, allocations: 2.975 MB / 483.5 MB, free: 12.41 MB / 366.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002037/0.2239, allocations: 0.5039 MB / 484 MB, free: 11.91 MB / 366.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005366/0.2293, allocations: 1.646 MB / 485.6 MB, free: 10.26 MB / 366.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.005024/0.2343, allocations: 2.661 MB / 488.3 MB, free: 7.594 MB / 366.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.005453/0.2398, allocations: 3.026 MB / 491.3 MB, free: 4.551 MB / 366.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01467/0.2545, allocations: 9.956 MB / 0.4895 GB, free: 10.56 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 1.052e-05/0.2546, allocations: 0 / 0.4895 GB, free: 10.56 MB / 382.1 MB
Notification: Performance of FrontEnd: time 3.456e-06/0.2546, allocations: 0 / 0.4895 GB, free: 10.56 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 0.0004539/0.2551, allocations: 0 / 0.4895 GB, free: 10.56 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: 1932
 * Number of variables: 1932
Notification: Performance of Generate backend data structure: time 0.02181/0.2769, allocations: 6.519 MB / 0.4959 GB, free: 3.965 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.441e-05/0.277, allocations: 15.89 kB / 0.4959 GB, free: 3.949 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01644/0.2934, allocations: 2.719 MB / 0.4986 GB, free: 1.219 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01005/0.3035, allocations: 3.798 MB / 0.5023 GB, free: 13.36 MB / 398.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003467/0.3039, allocations: 354.2 kB / 0.5026 GB, free: 13 MB / 398.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00148/0.3054, allocations: 427.7 kB / 0.503 GB, free: 12.59 MB / 398.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03012/0.3355, allocations: 13.85 MB / 0.5165 GB, free: 13.88 MB / 414.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002283/0.3358, allocations: 55.53 kB / 0.5166 GB, free: 13.83 MB / 414.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007448/0.3366, allocations: 195.8 kB / 0.5168 GB, free: 13.64 MB / 414.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004518/0.3411, allocations: 2.414 MB / 0.5191 GB, free: 11.22 MB / 414.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3615/0.7027, allocations: 11.75 MB / 0.5306 GB, free: 78.21 MB / 414.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.0792/0.7819, allocations: 51.55 MB / 0.581 GB, free: 43.01 MB / 414.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01143/0.7934, allocations: 4.742 MB / 0.5856 GB, free: 40.36 MB / 414.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003427/0.7968, allocations: 2.949 MB / 0.5885 GB, free: 37.96 MB / 414.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01776/0.8146, allocations: 12.86 MB / 0.601 GB, free: 24.62 MB / 414.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.885e-05/0.8147, allocations: 55.81 kB / 0.6011 GB, free: 24.56 MB / 414.1 MB
Notification: Performance of pre-optimization done (n=197): time 4.93e-06/0.8147, allocations: 0 / 0.6011 GB, free: 24.56 MB / 414.1 MB
Notification: Performance of matching and sorting (n=248): time 0.1439/0.9586, allocations: 43.43 MB / 0.6435 GB, free: 13.02 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001435/0.9588, allocations: 231.6 kB / 0.6437 GB, free: 12.74 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008394/0.9672, allocations: 4.79 MB / 0.6484 GB, free: 7.941 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001017/0.9683, allocations: 61.7 kB / 0.6484 GB, free: 7.875 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.003631/0.9719, allocations: 3.845 MB / 0.6522 GB, free: 4.023 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001647/0.9736, allocations: 0.9331 MB / 0.6531 GB, free: 3.078 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002469/0.9761, allocations: 1.511 MB / 0.6546 GB, free: 1.559 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001497/0.9763, allocations: 305.1 kB / 0.6549 GB, free: 1.258 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00562/0.9819, allocations: 3.446 MB / 0.6582 GB, free: 13.81 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.007908/0.9898, allocations: 7.705 MB / 0.6658 GB, free: 4.016 MB / 462.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01515/1.005, allocations: 11.87 MB / 0.6774 GB, free: 6.062 MB / 478.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001518/1.005, allocations: 47.94 kB / 0.6774 GB, free: 6.016 MB / 478.1 MB
Notification: Performance of matching and sorting (n=967) (initialization): time 0.03527/1.04, allocations: 14.47 MB / 0.6915 GB, free: 7.52 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0002123/1.041, allocations: 76.2 kB / 0.6916 GB, free: 7.445 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002851/1.041, allocations: 152 kB / 0.6918 GB, free: 7.297 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01257/1.054, allocations: 4.013 MB / 0.6957 GB, free: 3.273 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006025/1.06, allocations: 1.634 MB / 0.6973 GB, free: 1.637 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02967/1.089, allocations: 19.86 MB / 0.7167 GB, free: 13.62 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01172/1.101, allocations: 459.3 kB / 0.7171 GB, free: 13.17 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00212/1.103, allocations: 436 kB / 0.7175 GB, free: 12.75 MB / 0.5137 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 (884):
 * Single equations (assignments): 880
 * 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.009366/1.113, allocations: 2.665 MB / 0.7201 GB, free: 10.07 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001828/1.115, allocations: 434.3 kB / 0.7205 GB, free: 9.637 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02636/1.141, allocations: 8.633 MB / 0.729 GB, free: 0.9805 MB / 0.5137 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.742e-05/1.141, allocations: 31.98 kB / 0.729 GB, free: 0.9492 MB / 0.5137 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.013e-05/1.141, allocations: 12 kB / 0.729 GB, free: 0.9375 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.541e-05/1.141, allocations: 15.95 kB / 0.729 GB, free: 0.9219 MB / 0.5137 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.2979/1.439, allocations: 17.84 MB / 0.7464 GB, free: 175.6 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.011e-05/1.439, allocations: 9 kB / 0.7464 GB, free: 175.6 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002079/1.441, allocations: 397.4 kB / 0.7468 GB, free: 175.6 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01116/1.452, allocations: 4.065 MB / 0.7508 GB, free: 174.9 MB / 0.5137 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005628/1.453, allocations: 45.67 kB / 0.7508 GB, free: 174.9 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02237/1.475, allocations: 18.21 MB / 0.7686 GB, free: 164.5 MB / 0.5137 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.699e-06/1.476, allocations: 1.172 kB / 0.7686 GB, free: 164.5 MB / 0.5137 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02224/1.498, allocations: 13.23 MB / 0.7815 GB, free: 151.5 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00332/1.501, allocations: 0.9603 MB / 0.7825 GB, free: 150.7 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001066/1.502, allocations: 36.55 kB / 0.7825 GB, free: 150.7 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002622/1.505, allocations: 222.2 kB / 0.7827 GB, free: 150.5 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006383/1.505, allocations: 150.1 kB / 0.7829 GB, free: 150.3 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002058/1.506, allocations: 63.98 kB / 0.7829 GB, free: 150.3 MB / 0.5137 GB
Notification: Performance of sorting global known variables: time 0.004515/1.51, allocations: 2.997 MB / 0.7859 GB, free: 147.3 MB / 0.5137 GB
Notification: Performance of sort global known variables: time 4.71e-07/1.51, allocations: 0 / 0.7859 GB, free: 147.3 MB / 0.5137 GB
Notification: Performance of remove unused functions: time 0.008297/1.519, allocations: 1.496 MB / 0.7873 GB, free: 145.8 MB / 0.5137 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.0008253/1.519, allocations: 229.3 kB / 0.7875 GB, free: 145.6 MB / 0.5137 GB
Notification: Performance of simCode: created initialization part: time 0.01093/1.53, allocations: 8.276 MB / 0.7956 GB, free: 137.4 MB / 0.5137 GB
Notification: Performance of simCode: created event and clocks part: time 7.824e-06/1.53, allocations: 11.56 kB / 0.7956 GB, free: 137.4 MB / 0.5137 GB
Notification: Performance of simCode: created simulation system equations: time 0.003937/1.534, allocations: 3.1 MB / 0.7987 GB, free: 134.3 MB / 0.5137 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003325/1.538, allocations: 0.5276 MB / 0.7992 GB, free: 133.8 MB / 0.5137 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02085/1.559, allocations: 12.9 MB / 0.8118 GB, free: 121 MB / 0.5137 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003838/1.562, allocations: 4.101 MB / 0.8158 GB, free: 116.9 MB / 0.5137 GB
Notification: Performance of simCode: alias equations: time 0.004377/1.567, allocations: 2.2 MB / 0.8179 GB, free: 114.7 MB / 0.5137 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008356/1.568, allocations: 283.4 kB / 0.8182 GB, free: 114.4 MB / 0.5137 GB
Notification: Performance of SimCode: time 1.493e-06/1.568, allocations: 0 / 0.8182 GB, free: 114.4 MB / 0.5137 GB
Notification: Performance of Templates: time 0.08838/1.656, allocations: 58.75 MB / 0.8756 GB, free: 55.86 MB / 0.5137 GB
make -j1 -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.makefile
(rm -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.sim & ./ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2  -abortSlowSimulation -alarm=480 -s cvode -emit_protected -lv LOG_STATS > ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe 2>&1)
diffSimulationResults("ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/ModelicaTest/MultiBody/Joints/JointUSP2/JointUSP2.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches