Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.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.001326/0.001326, allocations: 109 kB / 21.23 MB, free: 0.6641 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.001407/0.001407, allocations: 213.9 kB / 24.54 MB, free: 4.02 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.511/1.511, allocations: 230.3 MB / 258 MB, free: 8.188 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.238/0.238, allocations: 46.93 MB / 361.4 MB, free: 4.398 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-beta.om/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.Prismatic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|prismatic.s|prismatic.v",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic")
translateModel(ModelicaTest.MultiBody.Joints.Prismatic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|prismatic.s|prismatic.v",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic") [Timeout 660]
"Warning: Requested package Modelica of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.002383/0.002383, allocations: 87.84 kB / 0.4972 GB, free: 7.504 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3783/0.3807, allocations: 66.03 MB / 0.5617 GB, free: 4.535 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.Prismatic): time 0.0147/0.3954, allocations: 12.34 MB / 0.5737 GB, free: 8.129 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00891/0.4043, allocations: 3.902 MB / 0.5775 GB, free: 4.215 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002311/0.4066, allocations: 75.75 kB / 0.5776 GB, free: 4.141 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003401/0.41, allocations: 1.343 MB / 0.5789 GB, free: 2.793 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005897/0.4159, allocations: 1.771 MB / 0.5806 GB, free: 1.016 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001511/0.4174, allocations: 419.2 kB / 0.581 GB, free: 0.6055 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006612/0.424, allocations: 3.602 MB / 0.5846 GB, free: 13 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003372/0.4274, allocations: 1.614 MB / 0.5861 GB, free: 11.35 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002846/0.4302, allocations: 1.21 MB / 0.5873 GB, free: 10.14 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003905/0.4341, allocations: 1.927 MB / 0.5892 GB, free: 8.203 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001291/0.4354, allocations: 240 kB / 0.5894 GB, free: 7.969 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002703/0.4381, allocations: 0.8308 MB / 0.5902 GB, free: 7.137 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002808/0.441, allocations: 1.604 MB / 0.5918 GB, free: 5.527 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004674/0.4456, allocations: 1.867 MB / 0.5936 GB, free: 3.648 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01152/0.4571, allocations: 6.095 MB / 0.5996 GB, free: 13.53 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 1.053e-05/0.4572, allocations: 0 / 0.5996 GB, free: 13.53 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.435e-06/0.4572, allocations: 4 kB / 0.5996 GB, free: 13.53 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0003628/0.4575, allocations: 0 / 0.5996 GB, free: 13.53 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1514
 * Number of variables: 1514
Notification: Performance of Generate backend data structure: time 0.01182/0.4693, allocations: 4.061 MB / 0.6035 GB, free: 9.41 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.332e-05/0.4694, allocations: 12.03 kB / 0.6036 GB, free: 9.398 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01042/0.4798, allocations: 3.094 MB / 0.6066 GB, free: 6.297 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00533/0.4851, allocations: 1.845 MB / 0.6084 GB, free: 4.414 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002216/0.4854, allocations: 197 kB / 0.6086 GB, free: 4.219 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007709/0.4861, allocations: 247.8 kB / 0.6088 GB, free: 3.977 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01922/0.5054, allocations: 10.18 MB / 0.6187 GB, free: 9.363 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001101/0.5055, allocations: 27.77 kB / 0.6188 GB, free: 9.336 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003886/0.5059, allocations: 119.8 kB / 0.6189 GB, free: 9.219 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004072/0.5099, allocations: 2.459 MB / 0.6213 GB, free: 6.758 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02052/0.5304, allocations: 9.353 MB / 0.6304 GB, free: 13.39 MB / 478.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05147/0.5819, allocations: 31.41 MB / 0.6611 GB, free: 13.14 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.005738/0.5877, allocations: 2.342 MB / 0.6634 GB, free: 10.81 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001614/0.5893, allocations: 0.9939 MB / 0.6643 GB, free: 9.812 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0008408/0.5901, allocations: 440.4 kB / 0.6648 GB, free: 9.383 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.606e-05/0.5902, allocations: 43.88 kB / 0.6648 GB, free: 9.332 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=185): time 4.279e-06/0.5902, allocations: 0 / 0.6648 GB, free: 9.332 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=191): time 0.02021/0.6104, allocations: 8.951 MB / 0.6736 GB, free: 356 kB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.119e-05/0.6104, allocations: 68.72 kB / 0.6736 GB, free: 276 kB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003855/0.6143, allocations: 2.607 MB / 0.6762 GB, free: 13.64 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001543/0.6144, allocations: 45.7 kB / 0.6762 GB, free: 13.59 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002233/0.6167, allocations: 2.776 MB / 0.6789 GB, free: 10.79 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007866/0.6175, allocations: 0.7363 MB / 0.6796 GB, free: 10.04 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001291/0.6188, allocations: 0.9809 MB / 0.6806 GB, free: 9.051 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.000171/0.6189, allocations: 305.1 kB / 0.6809 GB, free: 8.75 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00256/0.6215, allocations: 1.548 MB / 0.6824 GB, free: 7.195 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005091/0.6266, allocations: 4.387 MB / 0.6867 GB, free: 1.398 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009052/0.6356, allocations: 6.571 MB / 0.6931 GB, free: 9.426 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.139e-05/0.6357, allocations: 28 kB / 0.6931 GB, free: 9.398 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=648) (initialization): time 0.01373/0.6495, allocations: 5.864 MB / 0.6989 GB, free: 3.531 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 9.049e-05/0.6495, allocations: 50.34 kB / 0.6989 GB, free: 3.484 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001415/0.6497, allocations: 100 kB / 0.699 GB, free: 3.387 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004019/0.6501, allocations: 172.8 kB / 0.6992 GB, free: 3.215 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003536/0.6536, allocations: 1.068 MB / 0.7002 GB, free: 2.145 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001921/0.6555, allocations: 1.693 MB / 0.7019 GB, free: 340 kB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00324/0.6588, allocations: 211.9 kB / 0.7021 GB, free: 128 kB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006339/0.6594, allocations: 300 kB / 0.7023 GB, free: 15.83 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 453
 * Number of states: 0 ()
 * Number of discrete variables: 29 (axis.arrowLine.twoHeadedArrow,axis.headAtOrigin,frame_a1.color_x[3],frame_a1.color_x[2],frame_a1.color_x[1],frame_a.color_x[3],frame_a.color_x[2],frame_a.color_x[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],prismatic.boxColor[3],prismatic.boxColor[2],prismatic.boxColor[1],axis.color[3],axis.color[2],axis.color[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (596):
 * Single equations (assignments): 589
 * Array equations: 6
 * 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
   {(1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.004316/0.6637, allocations: 1.985 MB / 0.7043 GB, free: 13.84 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005979/0.6643, allocations: 254.3 kB / 0.7045 GB, free: 13.59 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004194/0.6685, allocations: 2.541 MB / 0.707 GB, free: 11.02 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.06e-05/0.6686, allocations: 23.94 kB / 0.707 GB, free: 10.99 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.25e-06/0.6686, allocations: 0 / 0.707 GB, free: 10.99 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.232e-05/0.6686, allocations: 3.984 kB / 0.707 GB, free: 10.99 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01363/0.6822, allocations: 7.917 MB / 0.7148 GB, free: 2.883 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.669e-05/0.6822, allocations: 8 kB / 0.7148 GB, free: 2.875 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009914/0.6832, allocations: 248.5 kB / 0.715 GB, free: 2.633 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0001842/0.6834, allocations: 64.89 kB / 0.7151 GB, free: 2.566 MB / 0.545 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.437e-05/0.6835, allocations: 40 kB / 0.7151 GB, free: 2.527 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001522/0.685, allocations: 1.522 MB / 0.7166 GB, free: 0.8867 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.129e-05/0.685, allocations: 11.94 kB / 0.7166 GB, free: 0.875 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005584/0.6906, allocations: 4.032 MB / 0.7205 GB, free: 12.7 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006609/0.6913, allocations: 234.2 kB / 0.7208 GB, free: 12.47 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002503/0.6915, allocations: 39.98 kB / 0.7208 GB, free: 12.43 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007636/0.6923, allocations: 51.95 kB / 0.7209 GB, free: 12.38 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002176/0.6925, allocations: 136.5 kB / 0.721 GB, free: 12.25 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001189/0.6926, allocations: 63.98 kB / 0.721 GB, free: 12.18 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.002451/0.6951, allocations: 1.599 MB / 0.7226 GB, free: 10.57 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1e-07/0.6951, allocations: 4 kB / 0.7226 GB, free: 10.57 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.003663/0.6987, allocations: 0.7734 MB / 0.7234 GB, free: 9.797 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 42
 * Number of states: 2 (prismatic.s,prismatic.v)
 * 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 (139):
 * Single equations (assignments): 132
 * Array equations: 6
 * 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
   {(1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004153/0.6991, allocations: 179.5 kB / 0.7235 GB, free: 9.609 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.006327/0.7055, allocations: 3.728 MB / 0.7272 GB, free: 5.883 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 7.444e-06/0.7055, allocations: 3.938 kB / 0.7272 GB, free: 5.879 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.001698/0.7072, allocations: 1.171 MB / 0.7283 GB, free: 4.703 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001767/0.7089, allocations: 344.3 kB / 0.7287 GB, free: 4.367 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3203/1.029, allocations: 8.645 MB / 0.7371 GB, free: 153.6 MB / 0.5606 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003443/1.033, allocations: 3.135 MB / 0.7402 GB, free: 152.8 MB / 0.5606 GB
Notification: Performance of simCode: alias equations: time 0.003203/1.036, allocations: 1.708 MB / 0.7418 GB, free: 152.8 MB / 0.5606 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004428/1.036, allocations: 187.4 kB / 0.742 GB, free: 152.7 MB / 0.5606 GB
Notification: Performance of SimCode: time 1.132e-06/1.036, allocations: 2.719 kB / 0.742 GB, free: 152.7 MB / 0.5606 GB
Notification: Performance of Templates: time 0.04394/1.08, allocations: 24.38 MB / 0.7658 GB, free: 133.2 MB / 0.5606 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Joints/Prismatic/Prismatic.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Prismatic.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: 7.0264681074768305]