Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.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.00156/0.00156, allocations: 114.5 kB / 21.18 MB, free: 0.7422 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.001654/0.001654, allocations: 212.1 kB / 24.48 MB, free: 4.191 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo): time 1.457/1.457, allocations: 230.4 MB / 258.1 MB, free: 8.141 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/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(Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|j1.phi|j1.w",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic")
translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|j1.phi|j1.w",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic") [Timeout 660]
"Warning: Requested package Modelica of version trunk, but this package was already loaded with version 4.1.0. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package Complex 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.
Warning: Requested package ModelicaServices 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.002057/0.002057, allocations: 71.83 kB / 378.7 MB, free: 2.887 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2135/0.2156, allocations: 53.31 MB / 432.1 MB, free: 7.398 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic): time 0.0156/0.2312, allocations: 13.54 MB / 445.6 MB, free: 9.797 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008817/0.24, allocations: 4.925 MB / 450.5 MB, free: 4.859 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0007829/0.2408, allocations: 91.69 kB / 450.6 MB, free: 4.77 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.007284/0.2481, allocations: 4.237 MB / 454.8 MB, free: 0.5195 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003981/0.2521, allocations: 2.163 MB / 457 MB, free: 14.35 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002032/0.2541, allocations: 1.111 MB / 458.1 MB, free: 13.23 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00719/0.2613, allocations: 6.059 MB / 464.2 MB, free: 7.164 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004387/0.2657, allocations: 3.774 MB / 468 MB, free: 3.324 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002854/0.2685, allocations: 2.166 MB / 470.1 MB, free: 1.152 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004239/0.2728, allocations: 3.081 MB / 473.2 MB, free: 14.06 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007958/0.2736, allocations: 0.4999 MB / 473.7 MB, free: 13.56 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002755/0.2763, allocations: 1.662 MB / 475.4 MB, free: 11.9 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002489/0.2788, allocations: 2.645 MB / 478 MB, free: 9.246 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002742/0.2816, allocations: 2.879 MB / 480.9 MB, free: 6.352 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01025/0.2918, allocations: 9.52 MB / 490.4 MB, free: 12.79 MB / 398.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.206e-06/0.2918, allocations: 4 kB / 490.4 MB, free: 12.79 MB / 398.1 MB
Notification: Performance of FrontEnd: time 1.683e-06/0.2918, allocations: 0 / 490.4 MB, free: 12.79 MB / 398.1 MB
Notification: Performance of Transformations before backend: time 0.0001696/0.292, allocations: 0 / 490.4 MB, free: 12.79 MB / 398.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1743
 * Number of variables: 1743
Notification: Performance of Generate backend data structure: time 0.01157/0.3036, allocations: 6.172 MB / 496.6 MB, free: 6.531 MB / 398.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.457e-05/0.3036, allocations: 8.031 kB / 496.6 MB, free: 6.523 MB / 398.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01146/0.3151, allocations: 2.571 MB / 499.2 MB, free: 3.945 MB / 398.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008518/0.3236, allocations: 3.77 MB / 0.4911 GB, free: 120 kB / 398.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002596/0.3238, allocations: 308.4 kB / 0.4914 GB, free: 15.81 MB / 414.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008432/0.3247, allocations: 447.5 kB / 0.4919 GB, free: 15.38 MB / 414.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02138/0.3461, allocations: 12.41 MB / 0.504 GB, free: 2.223 MB / 414.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001281/0.3462, allocations: 51.59 kB / 0.504 GB, free: 2.172 MB / 414.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004846/0.3467, allocations: 187.8 kB / 0.5042 GB, free: 1.988 MB / 414.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003372/0.35, allocations: 2.039 MB / 0.5062 GB, free: 15.95 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02096/0.371, allocations: 10.57 MB / 0.5165 GB, free: 5.363 MB / 430.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.2325/0.6035, allocations: 44.22 MB / 0.5597 GB, free: 70.68 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01142/0.6149, allocations: 4.695 MB / 0.5643 GB, free: 69.22 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001842/0.6167, allocations: 1.875 MB / 0.5661 GB, free: 68.45 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.008892/0.6256, allocations: 7.214 MB / 0.5732 GB, free: 64.19 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.361e-05/0.6257, allocations: 55.81 kB / 0.5732 GB, free: 64.14 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=179): time 3.887e-06/0.6257, allocations: 0 / 0.5732 GB, free: 64.14 MB / 446.1 MB
Notification: Performance of matching and sorting (n=223): time 0.05879/0.6845, allocations: 19.76 MB / 0.5925 GB, free: 47.56 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.501e-05/0.6845, allocations: 213.4 kB / 0.5927 GB, free: 47.31 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00411/0.6886, allocations: 4.469 MB / 0.5971 GB, free: 42.9 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.000133/0.6887, allocations: 53.7 kB / 0.5971 GB, free: 42.84 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001259/0.69, allocations: 3.652 MB / 0.6007 GB, free: 39.45 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004484/0.6904, allocations: 0.8695 MB / 0.6016 GB, free: 38.59 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001239/0.6917, allocations: 1.357 MB / 0.6029 GB, free: 37.23 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 4.11e-05/0.6917, allocations: 301.1 kB / 0.6032 GB, free: 36.93 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002625/0.6944, allocations: 2.375 MB / 0.6055 GB, free: 34.55 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003691/0.698, allocations: 6.167 MB / 0.6115 GB, free: 26.54 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007041/0.7051, allocations: 9.176 MB / 0.6205 GB, free: 15.5 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.268e-05/0.7051, allocations: 40 kB / 0.6205 GB, free: 15.46 MB / 446.1 MB
Notification: Performance of matching and sorting (n=868) (initialization): time 0.01294/0.7181, allocations: 9.553 MB / 0.6298 GB, free: 5.902 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 5.787e-05/0.7181, allocations: 71.92 kB / 0.6299 GB, free: 5.832 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.263e-05/0.7182, allocations: 132 kB / 0.63 GB, free: 5.703 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0037/0.7219, allocations: 1.366 MB / 0.6314 GB, free: 4.332 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003707/0.7256, allocations: 1.478 MB / 0.6328 GB, free: 2.852 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01187/0.7375, allocations: 8.468 MB / 0.6411 GB, free: 10.25 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004482/0.742, allocations: 363.6 kB / 0.6414 GB, free: 9.898 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005707/0.7425, allocations: 384 kB / 0.6418 GB, free: 9.523 MB / 462.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 605
 * Number of states: 0 ()
 * Number of discrete variables: 46 (jointSSP.prismatic.positiveBranch,b2.color[3],b2.color[2],b2.color[1],b2.body.sphereColor[3],b2.body.sphereColor[2],b2.body.sphereColor[1],jointSSP.rod2Color[3],jointSSP.rod2Color[2],jointSSP.rod2Color[1],jointSSP.sphereColor[3],jointSSP.sphereColor[2],jointSSP.sphereColor[1],jointSSP.rod1Color[3],jointSSP.rod1Color[2],jointSSP.rod1Color[1],jointSSP.rod1.massColor[3],jointSSP.rod1.massColor[2],jointSSP.rod1.massColor[1],jointSSP.boxColor[3],jointSSP.boxColor[2],jointSSP.boxColor[1],b3.color[3],b3.color[2],b3.color[1],b1.color[3],b1.color[2],b1.color[1],b1.body.sphereColor[3],b1.body.sphereColor[2],b1.body.sphereColor[1],j1.cylinderColor[3],j1.cylinderColor[2],j1.cylinderColor[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],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (804):
 * Single equations (assignments): 800
 * 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
   {(1,40,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.003823/0.7464, allocations: 2.493 MB / 0.6442 GB, free: 7.02 MB / 462.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007766/0.7471, allocations: 421.2 kB / 0.6446 GB, free: 6.605 MB / 462.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009405/0.7565, allocations: 4.106 MB / 0.6486 GB, free: 2.484 MB / 462.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.062e-05/0.7566, allocations: 11.95 kB / 0.6486 GB, free: 2.473 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.202e-06/0.7566, allocations: 1.875 kB / 0.6487 GB, free: 2.473 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001016/0.7576, allocations: 470.1 kB / 0.6491 GB, free: 1.992 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000467/0.7581, allocations: 35.88 kB / 0.6491 GB, free: 1.961 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001614/0.7597, allocations: 115.8 kB / 0.6492 GB, free: 1.848 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003132/0.76, allocations: 145.7 kB / 0.6494 GB, free: 1.703 MB / 462.1 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00966/0.7697, allocations: 4.558 MB / 0.6538 GB, free: 13.17 MB / 478.1 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.008218/0.7779, allocations: 5.807 MB / 0.6595 GB, free: 7.344 MB / 478.1 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002107/0.78, allocations: 1.414 MB / 0.6609 GB, free: 5.93 MB / 478.1 MB
Notification: Performance of sorting global known variables: time 0.004159/0.7841, allocations: 2.798 MB / 0.6636 GB, free: 3.129 MB / 478.1 MB
Notification: Performance of Backend: time 1.7e-07/0.7841, allocations: 0 / 0.6636 GB, free: 3.129 MB / 478.1 MB
Notification: Performance of simCode: created initialization part: time 0.009122/0.7933, allocations: 4.522 MB / 0.668 GB, free: 14.64 MB / 494.1 MB
Notification: Performance of SimCode: time 0.0169/0.8102, allocations: 11.2 MB / 0.679 GB, free: 3.379 MB / 494.1 MB
Notification: Performance of Templates: time 0.1032/0.9134, allocations: 56.82 MB / 0.7345 GB, free: 10.77 MB / 0.545 GB
"
[Timeout remaining time 659]
make -j1 -f Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.makefile [Timeout 660]
(rm -f Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.sim & ./Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.pipe 2>&1) [Timeout 480]
diffSimulationResults("Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/Modelica/Mechanics/MultiBody/Examples/Loops/Fourbar_analytic/Fourbar_analytic.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Loops.Fourbar_analytic.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: 6.580938580445945]