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.001407/0.001407, allocations: 119.1 kB / 20.45 MB, free: 1.176 MB / 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.001558/0.001558, allocations: 207.9 kB / 23.73 MB, free: 4.391 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.508/1.508, allocations: 230.5 MB / 257.4 MB, free: 7.754 MB / 206.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.2433/0.2433, allocations: 46.95 MB / 360.8 MB, free: 3.938 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: "" <> buildModelFMU(ModelicaTest.MultiBody.Joints.JointUSP2,fileNamePrefix="ModelicaTest_trunk_ModelicaTest_MultiBody_Joints_JointUSP2",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Joints.JointUSP2,fileNamePrefix="ModelicaTest_trunk_ModelicaTest_MultiBody_Joints_JointUSP2",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002583/0.002583, allocations: 83.81 kB / 0.4962 GB, free: 7.555 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4104/0.413, allocations: 66.1 MB / 0.5607 GB, free: 4.004 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUSP2): time 0.01715/0.4302, allocations: 11.57 MB / 0.572 GB, free: 8.371 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01231/0.4425, allocations: 4.627 MB / 0.5765 GB, free: 3.734 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00339/0.4459, allocations: 95.56 kB / 0.5766 GB, free: 3.641 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.009113/0.455, allocations: 3.867 MB / 0.5804 GB, free: 15.76 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007143/0.4621, allocations: 2.233 MB / 0.5826 GB, free: 13.52 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003405/0.4655, allocations: 1.193 MB / 0.5837 GB, free: 12.32 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.009976/0.4755, allocations: 5.822 MB / 0.5894 GB, free: 6.492 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.006477/0.482, allocations: 4.014 MB / 0.5933 GB, free: 2.41 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004399/0.4864, allocations: 2.2 MB / 0.5955 GB, free: 208 kB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006141/0.4925, allocations: 2.991 MB / 0.5984 GB, free: 13.2 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0014/0.4939, allocations: 0.5039 MB / 0.5989 GB, free: 12.7 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003677/0.4976, allocations: 1.533 MB / 0.6004 GB, free: 11.16 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003346/0.5009, allocations: 2.715 MB / 0.6031 GB, free: 8.441 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003618/0.5046, allocations: 2.319 MB / 0.6053 GB, free: 6.109 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.0154/0.52, allocations: 10.15 MB / 0.6152 GB, free: 11.92 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 1.409e-05/0.52, allocations: 0 / 0.6152 GB, free: 11.92 MB / 462.1 MB
Notification: Performance of FrontEnd: time 2.735e-06/0.52, allocations: 0 / 0.6152 GB, free: 11.92 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.000417/0.5204, allocations: 0 / 0.6152 GB, free: 11.92 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.0253/0.5457, allocations: 6.738 MB / 0.6218 GB, free: 5.109 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.561e-05/0.5458, allocations: 12.03 kB / 0.6218 GB, free: 5.098 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002391/0.5481, allocations: 0.9177 MB / 0.6227 GB, free: 4.168 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.09702/0.6452, allocations: 7.448 MB / 0.63 GB, free: 12.71 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01544/0.6606, allocations: 4.083 MB / 0.634 GB, free: 8.566 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006305/0.6612, allocations: 375.6 kB / 0.6343 GB, free: 8.184 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002156/0.6634, allocations: 443.7 kB / 0.6348 GB, free: 7.75 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03768/0.7011, allocations: 13.88 MB / 0.6483 GB, free: 8.992 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001953/0.7013, allocations: 51.58 kB / 0.6484 GB, free: 8.941 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008129/0.7021, allocations: 203.8 kB / 0.6486 GB, free: 8.742 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004175/0.7063, allocations: 2.406 MB / 0.6509 GB, free: 6.332 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02795/0.7342, allocations: 11.6 MB / 0.6622 GB, free: 10.72 MB / 0.4981 GB
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.06977/0.804, allocations: 48.32 MB / 0.7094 GB, free: 9.094 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0127/0.8167, allocations: 4.038 MB / 0.7134 GB, free: 5.008 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004106/0.8208, allocations: 2.588 MB / 0.7159 GB, free: 2.402 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01903/0.8398, allocations: 9.758 MB / 0.7254 GB, free: 8.598 MB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.785e-05/0.8399, allocations: 59.8 kB / 0.7255 GB, free: 8.531 MB / 0.5606 GB
Notification: Performance of pre-optimization done (n=191): time 4.98e-06/0.8399, allocations: 0 / 0.7255 GB, free: 8.531 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=242): time 0.4471/1.287, allocations: 42.38 MB / 0.7669 GB, free: 135.4 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.011e-05/1.287, allocations: 235.5 kB / 0.7671 GB, free: 135.1 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005463/1.292, allocations: 5.053 MB / 0.772 GB, free: 130.2 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006035/1.293, allocations: 61.7 kB / 0.7721 GB, free: 130.2 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001989/1.295, allocations: 4.055 MB / 0.7761 GB, free: 126.4 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001006/1.296, allocations: 0.9518 MB / 0.777 GB, free: 125.5 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001802/1.298, allocations: 1.472 MB / 0.7784 GB, free: 124 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 9.167e-05/1.298, allocations: 301.1 kB / 0.7787 GB, free: 123.7 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003795/1.302, allocations: 3.262 MB / 0.7819 GB, free: 120.5 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005219/1.307, allocations: 7.612 MB / 0.7893 GB, free: 110.7 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01043/1.317, allocations: 11.6 MB / 0.8007 GB, free: 97.04 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001732/1.318, allocations: 44 kB / 0.8007 GB, free: 96.99 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=940) (initialization): time 0.02587/1.343, allocations: 13.84 MB / 0.8142 GB, free: 83.13 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001018/1.344, allocations: 68 kB / 0.8143 GB, free: 83.07 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001671/1.344, allocations: 152.9 kB / 0.8144 GB, free: 82.92 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0107/1.354, allocations: 3.766 MB / 0.8181 GB, free: 79.14 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004939/1.359, allocations: 1.602 MB / 0.8197 GB, free: 77.54 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02367/1.383, allocations: 18.88 MB / 0.8381 GB, free: 58.55 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00779/1.391, allocations: 447.5 kB / 0.8385 GB, free: 58.11 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001268/1.392, allocations: 439.9 kB / 0.8389 GB, free: 57.68 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 687
 * 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 (857):
 * Single equations (assignments): 853
 * 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.004333/1.396, allocations: 2.649 MB / 0.8415 GB, free: 55.02 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009053/1.397, allocations: 461 kB / 0.842 GB, free: 54.56 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01588/1.413, allocations: 8.091 MB / 0.8499 GB, free: 46.45 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.735e-05/1.413, allocations: 31.83 kB / 0.8499 GB, free: 46.42 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.817e-05/1.413, allocations: 12 kB / 0.8499 GB, free: 46.41 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.135e-05/1.413, allocations: 15.95 kB / 0.8499 GB, free: 46.39 MB / 0.5606 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0288/1.442, allocations: 16.63 MB / 0.8662 GB, free: 29.74 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.057e-05/1.442, allocations: 4 kB / 0.8662 GB, free: 29.73 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001921/1.444, allocations: 391.4 kB / 0.8665 GB, free: 29.35 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01078/1.455, allocations: 3.831 MB / 0.8703 GB, free: 25.51 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0006916/1.456, allocations: 43.98 kB / 0.8703 GB, free: 25.47 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02118/1.477, allocations: 17.26 MB / 0.8872 GB, free: 8.09 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.586e-06/1.477, allocations: 4 kB / 0.8872 GB, free: 8.086 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02304/1.5, allocations: 12.52 MB / 0.8994 GB, free: 11.53 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001697/1.501, allocations: 412.8 kB / 0.8998 GB, free: 11.12 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001139/1.503, allocations: 32 kB / 0.8998 GB, free: 11.09 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002733/1.505, allocations: 210.6 kB / 0.9 GB, free: 10.89 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006188/1.506, allocations: 158.1 kB / 0.9002 GB, free: 10.73 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002065/1.506, allocations: 64 kB / 0.9003 GB, free: 10.67 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.00559/1.512, allocations: 2.998 MB / 0.9032 GB, free: 7.68 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 2.2e-07/1.512, allocations: 0 / 0.9032 GB, free: 7.68 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.00786/1.52, allocations: 1.426 MB / 0.9046 GB, free: 6.258 MB / 0.5762 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 (167):
 * Single equations (assignments): 163
 * 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.02713/1.547, allocations: 13.23 MB / 0.9175 GB, free: 8.988 MB / 0.5919 GB
Notification: Performance of simCode: created initialization part: time 0.01432/1.561, allocations: 7.59 MB / 0.9249 GB, free: 1.418 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 7.865e-06/1.561, allocations: 0 / 0.9249 GB, free: 1.418 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.005371/1.566, allocations: 2.736 MB / 0.9276 GB, free: 14.66 MB / 0.6075 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004949/1.571, allocations: 0.5346 MB / 0.9281 GB, free: 14.13 MB / 0.6075 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.4445/2.016, allocations: 66.04 MB / 0.9926 GB, free: 223 MB / 0.6544 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004033/2.02, allocations: 3.577 MB / 0.9961 GB, free: 222 MB / 0.6544 GB
Notification: Performance of simCode: alias equations: time 0.004458/2.024, allocations: 2.173 MB / 0.9982 GB, free: 221.4 MB / 0.6544 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002866/2.027, allocations: 2.481 MB / 1.001 GB, free: 219.1 MB / 0.6544 GB
Notification: Performance of SimCode: time 1.212e-06/2.027, allocations: 2.812 kB / 1.001 GB, free: 219.1 MB / 0.6544 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.15/2.177, allocations: 104.7 MB / 1.103 GB, free: 115.2 MB / 0.6544 GB
Notification: Performance of buildModelFMU: Generate platform static: time 18.32/20.5, allocations: 8.062 kB / 1.103 GB, free: 115.2 MB / 0.6544 GB
"
[Timeout remaining time 639]
(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 & python3 -m fmpy simulate  --output-file ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2_res.csv --start-time 0 --stop-time 4 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00079952 ModelicaTest_trunk_ModelicaTest_MultiBody_Joints_JointUSP2.fmu > ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2.pipe 2>&1) [Timeout 52.5]
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.MultiBody.Joints.JointUSP2_res.csv","/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]
""

Variables in the reference:time,revolute.phi,revolute.w

Variables in the result:time,revolute.phi,revolute.w
[Calling sys.exit(0), Time elapsed: 23.84940241742879]