Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false) [Timeout 90] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 90] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 90] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 90] 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.FourbarVariants.SphericalSpherical,tolerance=1e-06,outputFormat="mat",numberOfIntervals=500,variableFilter="time|j1.phi|j1.w",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical") translateModel(ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical,tolerance=1e-06,outputFormat="mat",numberOfIntervals=500,variableFilter="time|j1.phi|j1.w",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical") [Timeout 660] Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.00134/0.00134, allocations: 122.2 kB / 17.79 MB, free: 5.273 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001323/0.001323, allocations: 202 kB / 18.74 MB, free: 4.332 MB / 14.72 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.534/1.534, allocations: 227.4 MB / 247 MB, free: 10.45 MB / 206.1 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2338/0.2338, allocations: 46.13 MB / 344.3 MB, free: 11.46 MB / 286.1 MB 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.002312/0.002312, allocations: 79.86 kB / 481.4 MB, free: 4.031 MB / 350.1 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3929/0.3952, allocations: 62.44 MB / 0.5311 GB, free: 13.57 MB / 398.1 MB Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical): time 0.01584/0.4111, allocations: 12.03 MB / 0.5428 GB, free: 4.117 MB / 398.1 MB Notification: Performance of NFInst.instExpressions: time 0.01274/0.4239, allocations: 4.308 MB / 0.547 GB, free: 0.7461 MB / 398.1 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003482/0.4275, allocations: 76.34 kB / 0.5471 GB, free: 0.7305 MB / 398.1 MB Notification: Performance of NFTyping.typeComponents: time 0.006702/0.4342, allocations: 2.723 MB / 0.5498 GB, free: 14.49 MB / 414.1 MB Notification: Performance of NFTyping.typeBindings: time 0.008234/0.4425, allocations: 1.983 MB / 0.5517 GB, free: 12.5 MB / 414.1 MB Notification: Performance of NFTyping.typeClassSections: time 0.003174/0.4457, allocations: 0.9047 MB / 0.5526 GB, free: 11.59 MB / 414.1 MB Notification: Performance of NFFlatten.flatten: time 0.01067/0.4564, allocations: 5.669 MB / 0.5581 GB, free: 5.914 MB / 414.1 MB Notification: Performance of NFFlatten.resolveConnections: time 0.006033/0.4625, allocations: 3.363 MB / 0.5614 GB, free: 2.508 MB / 414.1 MB Notification: Performance of NFEvalConstants.evaluate: time 0.004284/0.4668, allocations: 1.713 MB / 0.5631 GB, free: 0.7891 MB / 414.1 MB Notification: Performance of NFSimplifyModel.simplify: time 0.005302/0.4721, allocations: 2.508 MB / 0.5655 GB, free: 14.27 MB / 430.1 MB Notification: Performance of NFPackage.collectConstants: time 0.001837/0.474, allocations: 387.9 kB / 0.5659 GB, free: 13.89 MB / 430.1 MB Notification: Performance of NFFlatten.collectFunctions: time 0.004196/0.4782, allocations: 1.393 MB / 0.5672 GB, free: 12.5 MB / 430.1 MB Notification: Performance of NFScalarize.scalarize: time 0.004002/0.4823, allocations: 2.174 MB / 0.5694 GB, free: 10.32 MB / 430.1 MB Notification: Performance of NFVerifyModel.verify: time 0.005943/0.4882, allocations: 2.42 MB / 0.5717 GB, free: 7.887 MB / 430.1 MB Notification: Performance of NFConvertDAE.convert: time 0.01393/0.5022, allocations: 7.88 MB / 0.5794 GB, free: 15.98 MB / 446.1 MB Notification: Performance of FrontEnd - DAE generated: time 7.394e-06/0.5022, allocations: 0 / 0.5794 GB, free: 15.98 MB / 446.1 MB Notification: Performance of FrontEnd: time 2.314e-06/0.5023, allocations: 0 / 0.5794 GB, free: 15.98 MB / 446.1 MB Notification: Performance of Transformations before backend: time 0.0002743/0.5025, allocations: 0 / 0.5794 GB, free: 15.98 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: 1538 * Number of variables: 1538 Notification: Performance of Generate backend data structure: time 0.01564/0.5182, allocations: 5.634 MB / 0.5849 GB, free: 10.26 MB / 446.1 MB Notification: Performance of prepare preOptimizeDAE: time 6.183e-05/0.5183, allocations: 8.031 kB / 0.5849 GB, free: 10.25 MB / 446.1 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01183/0.5301, allocations: 2.498 MB / 0.5874 GB, free: 7.746 MB / 446.1 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.009011/0.5392, allocations: 3.381 MB / 0.5907 GB, free: 4.309 MB / 446.1 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000251/0.5395, allocations: 238.5 kB / 0.5909 GB, free: 4.074 MB / 446.1 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.001291/0.5408, allocations: 343.7 kB / 0.5912 GB, free: 3.738 MB / 446.1 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.02599/0.5668, allocations: 10.87 MB / 0.6019 GB, free: 8.121 MB / 462.1 MB Notification: Performance of preOpt findStateOrder (simulation): time 0.0003141/0.5671, allocations: 55.53 kB / 0.6019 GB, free: 8.066 MB / 462.1 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001669/0.5688, allocations: 159.8 kB / 0.6021 GB, free: 7.91 MB / 462.1 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004054/0.5729, allocations: 2.002 MB / 0.604 GB, free: 5.902 MB / 462.1 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02407/0.597, allocations: 9.44 MB / 0.6132 GB, free: 12.45 MB / 478.1 MB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06576/0.6628, allocations: 41.12 MB / 0.6534 GB, free: 2.109 MB / 0.4981 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0169/0.6797, allocations: 4.267 MB / 0.6575 GB, free: 13.7 MB / 0.5137 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.004473/0.6843, allocations: 1.928 MB / 0.6594 GB, free: 11.76 MB / 0.5137 GB Notification: Performance of preOpt evalFunc (simulation): time 0.01747/0.7018, allocations: 7.615 MB / 0.6669 GB, free: 3.77 MB / 0.5137 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.548e-05/0.7019, allocations: 55.55 kB / 0.6669 GB, free: 3.703 MB / 0.5137 GB Notification: Performance of pre-optimization done (n=180): time 6.182e-06/0.7019, allocations: 0 / 0.6669 GB, free: 3.703 MB / 0.5137 GB Notification: Performance of matching and sorting (n=236): time 0.1798/0.8818, allocations: 41.17 MB / 0.7071 GB, free: 10.46 MB / 0.5606 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001536/0.882, allocations: 205.9 kB / 0.7073 GB, free: 10.22 MB / 0.5606 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008439/0.8904, allocations: 4.31 MB / 0.7115 GB, free: 5.883 MB / 0.5606 GB Notification: Performance of collectPreVariables (initialization): time 0.0006966/0.8911, allocations: 61.7 kB / 0.7116 GB, free: 5.816 MB / 0.5606 GB Notification: Performance of collectInitialEqns (initialization): time 0.003297/0.8945, allocations: 3.294 MB / 0.7148 GB, free: 2.492 MB / 0.5606 GB Notification: Performance of collectInitialBindings (initialization): time 0.001664/0.8961, allocations: 0.9185 MB / 0.7157 GB, free: 1.566 MB / 0.5606 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.003343/0.8995, allocations: 1.361 MB / 0.717 GB, free: 200 kB / 0.5606 GB Notification: Performance of setup shared object (initialization): time 0.3069/1.206, allocations: 304.9 kB / 0.7173 GB, free: 165 MB / 0.5606 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.006822/1.213, allocations: 3.626 MB / 0.7209 GB, free: 164.3 MB / 0.5606 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.008057/1.221, allocations: 7.293 MB / 0.728 GB, free: 158.1 MB / 0.5606 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01219/1.234, allocations: 11.62 MB / 0.7393 GB, free: 149.5 MB / 0.5606 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001994/1.234, allocations: 39 kB / 0.7394 GB, free: 149.5 MB / 0.5606 GB Notification: Performance of matching and sorting (n=856) (initialization): time 0.03582/1.27, allocations: 16.22 MB / 0.7552 GB, free: 139.8 MB / 0.5606 GB Notification: Performance of prepare postOptimizeDAE: time 8.686e-05/1.27, allocations: 60 kB / 0.7553 GB, free: 139.7 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001171/1.27, allocations: 128 kB / 0.7554 GB, free: 139.6 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02185/1.292, allocations: 6.224 MB / 0.7615 GB, free: 133.4 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005054/1.297, allocations: 1.489 MB / 0.7629 GB, free: 131.9 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03102/1.328, allocations: 27.64 MB / 0.7899 GB, free: 104.1 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006682/1.335, allocations: 409.8 kB / 0.7903 GB, free: 103.7 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001057/1.336, allocations: 384 kB / 0.7907 GB, free: 103.3 MB / 0.5606 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 582 * Number of states: 0 () * Number of discrete variables: 42 (b3.color[3],b3.color[2],b3.color[1],sphericalSpherical.massColor[3],sphericalSpherical.massColor[2],sphericalSpherical.massColor[1],sphericalSpherical.sphereColor[3],sphericalSpherical.sphereColor[2],sphericalSpherical.sphereColor[1],sphericalSpherical.rodColor[3],sphericalSpherical.rodColor[2],sphericalSpherical.rodColor[1],b2.color[3],b2.color[2],b2.color[1],b2.body.sphereColor[3],b2.body.sphereColor[2],b2.body.sphereColor[1],b1.color[3],b1.color[2],b1.color[1],b1.body.sphereColor[3],b1.body.sphereColor[2],b1.body.sphereColor[1],j2.boxColor[3],j2.boxColor[2],j2.boxColor[1],j1.cylinderColor[3],j1.cylinderColor[2],j1.cylinderColor[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 (779): * Single equations (assignments): 774 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 1 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 1 system {1} * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(3,51,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.003683/1.339, allocations: 2.234 MB / 0.7929 GB, free: 101.1 MB / 0.5606 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008075/1.34, allocations: 411.2 kB / 0.7933 GB, free: 101 MB / 0.5606 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02611/1.366, allocations: 11.29 MB / 0.8043 GB, free: 89.64 MB / 0.5606 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.395e-05/1.366, allocations: 27.83 kB / 0.8043 GB, free: 89.62 MB / 0.5606 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.803e-05/1.366, allocations: 12 kB / 0.8043 GB, free: 89.61 MB / 0.5606 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.074e-05/1.366, allocations: 7.969 kB / 0.8043 GB, free: 89.6 MB / 0.5606 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03786/1.404, allocations: 20.65 MB / 0.8245 GB, free: 68.59 MB / 0.5606 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.608e-05/1.404, allocations: 8 kB / 0.8245 GB, free: 68.58 MB / 0.5606 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00199/1.406, allocations: 388.7 kB / 0.8249 GB, free: 68.2 MB / 0.5606 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.01911/1.426, allocations: 5.308 MB / 0.8301 GB, free: 62.88 MB / 0.5606 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005344/1.426, allocations: 47.75 kB / 0.8301 GB, free: 62.84 MB / 0.5606 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0241/1.45, allocations: 22.7 MB / 0.8523 GB, free: 39.95 MB / 0.5606 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.46e-06/1.45, allocations: 7.938 kB / 0.8523 GB, free: 39.94 MB / 0.5606 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02881/1.479, allocations: 14.62 MB / 0.8666 GB, free: 25.23 MB / 0.5606 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001299/1.48, allocations: 469.3 kB / 0.867 GB, free: 24.75 MB / 0.5606 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007633/1.481, allocations: 43.98 kB / 0.8671 GB, free: 24.71 MB / 0.5606 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002272/1.483, allocations: 182.5 kB / 0.8672 GB, free: 24.54 MB / 0.5606 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006665/1.484, allocations: 161 kB / 0.8674 GB, free: 24.38 MB / 0.5606 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002077/1.484, allocations: 67.89 kB / 0.8674 GB, free: 24.32 MB / 0.5606 GB Notification: Performance of sorting global known variables: time 0.003346/1.488, allocations: 2.701 MB / 0.8701 GB, free: 21.61 MB / 0.5606 GB Notification: Performance of sort global known variables: time 1.694e-06/1.488, allocations: 0 / 0.8701 GB, free: 21.61 MB / 0.5606 GB Notification: Performance of remove unused functions: time 0.006565/1.494, allocations: 1.356 MB / 0.8714 GB, free: 20.26 MB / 0.5606 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 23 * Number of states: 2 (j1.phi,j1.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): 162 * Array equations: 3 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 1 * Torn equation systems: 1 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 1 system {1} * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 1 system {(3,45,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.0005913/1.495, allocations: 186.7 kB / 0.8716 GB, free: 20.09 MB / 0.5606 GB Notification: Performance of simCode: created initialization part: time 0.01061/1.506, allocations: 8.346 MB / 0.8797 GB, free: 11.75 MB / 0.5606 GB Notification: Performance of simCode: created event and clocks part: time 8.236e-06/1.506, allocations: 8.125 kB / 0.8797 GB, free: 11.74 MB / 0.5606 GB Notification: Performance of simCode: created simulation system equations: time 0.003939/1.51, allocations: 3.212 MB / 0.8829 GB, free: 8.516 MB / 0.5606 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003204/1.513, allocations: 0.4979 MB / 0.8834 GB, free: 8.023 MB / 0.5606 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01845/1.531, allocations: 11.97 MB / 0.8951 GB, free: 11.96 MB / 0.5762 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00442/1.536, allocations: 3.508 MB / 0.8985 GB, free: 8.422 MB / 0.5762 GB Notification: Performance of simCode: alias equations: time 0.005054/1.541, allocations: 1.952 MB / 0.9004 GB, free: 6.465 MB / 0.5762 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001574/1.542, allocations: 267.4 kB / 0.9006 GB, free: 6.203 MB / 0.5762 GB Notification: Performance of SimCode: time 2.024e-06/1.542, allocations: 0 / 0.9006 GB, free: 6.203 MB / 0.5762 GB Notification: Performance of Templates: time 0.4352/1.978, allocations: 66.67 MB / 0.9658 GB, free: 210.6 MB / 0.6231 GB make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.makefile [Timeout 660] (rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.pipe 2>&1) [Timeout 480] diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/FourbarVariants/SphericalSpherical/SphericalSpherical.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.FourbarVariants.SphericalSpherical.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660] Reference file matches