Running: ./testmodel.py --libraries=/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/./OMCompiler/build/lib/omlibrary/ --ompython_omhome=/usr Modelica_3.2.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic.conf.json
Using package ModelicaServices with version 3.2.1 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/build/lib/omlibrary/ModelicaServices 3.2.1/package.mo)
Using package Complex with version 3.2.1 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/build/lib/omlibrary/Complex 3.2.1.mo)
Using package Modelica with version 3.2.1 (/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/build/lib/omlibrary/Modelica 3.2.1/package.mo)
Running command: translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|Inertia.phi|Inertia.w",fileNamePrefix="Modelica_3.2.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic")
Notification: Performance of loadModel(Modelica): time 1.751/1.751, allocations: 171.2 MB / 186.7 MB, free: 1.035 MB / 154.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003431/0.0003432, allocations: 8 kB / 233.9 MB, free: 2.387 MB / 186.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.07812/0.07848, allocations: 38.78 MB / 272.6 MB, free: 11.53 MB / 234.7 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_analytic): time 0.3577/0.4363, allocations: 93.83 MB / 366.5 MB, free: 15.08 MB / 298.7 MB
Notification: Performance of NFInst.instExpressions: time 0.06463/0.5009, allocations: 21.65 MB / 388.1 MB, free: 9.379 MB / 314.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01988/0.5209, allocations: 490.7 kB / 388.6 MB, free: 8.898 MB / 314.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.01692/0.5378, allocations: 4.79 MB / 393.4 MB, free: 4.086 MB / 314.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.04997/0.5879, allocations: 13.34 MB / 406.7 MB, free: 6.715 MB / 330.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.009302/0.5972, allocations: 1.705 MB / 408.4 MB, free: 5.008 MB / 330.7 MB
Notification: Performance of NFFlatten.flatten: time 0.4192/1.016, allocations: 50.74 MB / 459.2 MB, free: 37.26 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.02217/1.039, allocations: 12.78 MB / 472 MB, free: 36.21 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006203/1.045, allocations: 2.669 MB / 474.6 MB, free: 36.21 MB / 362.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.007253/1.052, allocations: 3.581 MB / 478.2 MB, free: 36.2 MB / 362.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.002164/1.054, allocations: 339.2 kB / 478.5 MB, free: 36.2 MB / 362.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004805/1.059, allocations: 1.37 MB / 479.9 MB, free: 36.19 MB / 362.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.005773/1.065, allocations: 3.918 MB / 483.8 MB, free: 34.58 MB / 362.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.003473/1.069, allocations: 1.872 MB / 485.7 MB, free: 33.91 MB / 362.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.01708/1.086, allocations: 14.36 MB / 0.4883 GB, free: 21.44 MB / 362.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.579e-06/1.086, allocations: 3.281 kB / 0.4883 GB, free: 21.44 MB / 362.7 MB
Notification: Performance of FrontEnd: time 2.624e-06/1.086, allocations: 4 kB / 0.4883 GB, free: 21.43 MB / 362.7 MB
Notification: Performance of Transformations before backend: time 0.0004528/1.086, allocations: 0 / 0.4883 GB, free: 21.43 MB / 362.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2751
 * Number of variables: 2751
Notification: Performance of Generate backend data structure: time 0.03976/1.126, allocations: 10.19 MB / 0.4983 GB, free: 12.06 MB / 362.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.477e-05/1.126, allocations: 8.031 kB / 0.4983 GB, free: 12.05 MB / 362.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.6052/1.731, allocations: 30.06 MB / 0.5277 GB, free: 15.89 MB / 394.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03418/1.766, allocations: 10.2 MB / 0.5376 GB, free: 8.723 MB / 394.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007261/1.766, allocations: 0.5238 MB / 0.5381 GB, free: 8.43 MB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003847/1.77, allocations: 0.7264 MB / 0.5388 GB, free: 7.949 MB / 394.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06163/1.832, allocations: 19.43 MB / 0.5578 GB, free: 9.203 MB / 410.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005327/1.832, allocations: 93.88 kB / 0.5579 GB, free: 9.145 MB / 410.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003149/1.836, allocations: 331.5 kB / 0.5582 GB, free: 9.055 MB / 410.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00716/1.843, allocations: 2.759 MB / 0.5609 GB, free: 6.836 MB / 410.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05415/1.897, allocations: 15.81 MB / 0.5764 GB, free: 12.04 MB / 426.7 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.5904/2.487, allocations: 72.98 MB / 0.6476 GB, free: 129 MB / 426.8 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.03651/2.524, allocations: 8.083 MB / 0.6555 GB, free: 124.9 MB / 426.8 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.03278/2.557, allocations: 14.38 MB / 0.6696 GB, free: 114.9 MB / 426.8 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.114e-05/2.557, allocations: 70.53 kB / 0.6696 GB, free: 114.9 MB / 426.8 MB
Notification: Performance of pre-optimization done (n=308): time 7.203e-06/2.557, allocations: 0 / 0.6696 GB, free: 114.9 MB / 426.8 MB
Notification: Performance of matching and sorting (n=408): time 0.2873/2.844, allocations: 48.32 MB / 0.7168 GB, free: 66.98 MB / 426.8 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001167/2.844, allocations: 402.8 kB / 0.7172 GB, free: 66.5 MB / 426.8 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01606/2.86, allocations: 8.39 MB / 0.7254 GB, free: 58.12 MB / 426.8 MB
Notification: Performance of collectPreVariables (initialization): time 0.001077/2.862, allocations: 86.41 kB / 0.7255 GB, free: 58.03 MB / 426.8 MB
Notification: Performance of collectInitialEqns (initialization): time 0.005112/2.867, allocations: 5.818 MB / 0.7312 GB, free: 52.22 MB / 426.8 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002292/2.869, allocations: 1.477 MB / 0.7326 GB, free: 50.75 MB / 426.8 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001211/2.87, allocations: 188.5 kB / 0.7328 GB, free: 50.57 MB / 426.8 MB
Notification: Performance of setup shared object (initialization): time 0.0005338/2.871, allocations: 0.6867 MB / 0.7334 GB, free: 49.88 MB / 426.8 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.009942/2.881, allocations: 4.316 MB / 0.7377 GB, free: 45.55 MB / 426.8 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01317/2.894, allocations: 9.25 MB / 0.7467 GB, free: 33.94 MB / 426.8 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01311/2.907, allocations: 6.198 MB / 0.7527 GB, free: 27.73 MB / 426.8 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001201/2.907, allocations: 48 kB / 0.7528 GB, free: 27.68 MB / 426.8 MB
Notification: Performance of matching and sorting (n=1200) (initialization): time 0.05476/2.962, allocations: 17.61 MB / 0.77 GB, free: 10.03 MB / 426.8 MB
Notification: Performance of prepare postOptimizeDAE: time 0.000115/2.962, allocations: 80 kB / 0.7701 GB, free: 9.949 MB / 426.8 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001961/2.963, allocations: 172 kB / 0.7702 GB, free: 9.781 MB / 426.8 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02701/2.99, allocations: 5.031 MB / 0.7751 GB, free: 4.742 MB / 426.8 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01118/3.001, allocations: 1.942 MB / 0.777 GB, free: 2.797 MB / 426.8 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3262/3.327, allocations: 27.88 MB / 0.8043 GB, free: 147.3 MB / 426.8 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01122/3.338, allocations: 0.5449 MB / 0.8048 GB, free: 147.2 MB / 426.8 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001963/3.34, allocations: 0.5038 MB / 0.8053 GB, free: 147.1 MB / 426.8 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 778
 * Number of states: 0 ()
 * Number of discrete variables: 70 (jointRRP.jointUSP.prismatic.positiveBranch,jointRRP.jointUSP.rod1Color[3],jointRRP.jointUSP.rod1Color[2],jointRRP.jointUSP.rod1Color[1],jointRRP.jointUSP.cylinderColor[3],jointRRP.jointUSP.cylinderColor[2],jointRRP.jointUSP.cylinderColor[1],jointRRP.jointUSP.sphereColor[3],jointRRP.jointUSP.sphereColor[2],jointRRP.jointUSP.sphereColor[1],jointRRP.rodColor[3],jointRRP.rodColor[2],jointRRP.rodColor[1],jointRRP.cylinderColor[3],jointRRP.cylinderColor[2],jointRRP.cylinderColor[1],cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],Mid.color[3],Mid.color[2],Mid.color[1],Crank2.color[3],Crank2.color[2],Crank2.color[1],Crank2.body.sphereColor[3],Crank2.body.sphereColor[2],Crank2.body.sphereColor[1],Crank1.color[3],Crank1.color[2],Crank1.color[1],Crank1.body.sphereColor[3],Crank1.body.sphereColor[2],Crank1.body.sphereColor[1],Crank3.color[3],Crank3.color[2],Crank3.color[1],Crank3.body.sphereColor[3],Crank3.body.sphereColor[2],Crank3.body.sphereColor[1],Crank4.color[3],Crank4.color[2],Crank4.color[1],Crank4.body.sphereColor[3],Crank4.body.sphereColor[2],Crank4.body.sphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],Bearing.cylinderColor[3],Bearing.cylinderColor[2],Bearing.cylinderColor[1],Rod2.color[3],Rod2.color[2],Rod2.color[1],Rod2.body.sphereColor[3],Rod2.body.sphereColor[2],Rod2.body.sphereColor[1],Piston.color[3],Piston.color[2],Piston.color[1],Piston.body.sphereColor[3],Piston.body.sphereColor[2],Piston.body.sphereColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1048):
 * Single equations (assignments): 1043
 * Array equations: 4
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(3,100.0%) 124}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.01017/3.351, allocations: 4.082 MB / 0.8093 GB, free: 145.5 MB / 426.8 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002002/3.353, allocations: 0.7107 MB / 0.81 GB, free: 145.5 MB / 426.8 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04237/3.395, allocations: 11.41 MB / 0.8211 GB, free: 141.4 MB / 426.8 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.566e-05/3.395, allocations: 15.47 kB / 0.8211 GB, free: 141.4 MB / 426.8 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.553e-05/3.395, allocations: 4.688 kB / 0.8211 GB, free: 141.4 MB / 426.8 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.005341/3.4, allocations: 1.469 MB / 0.8226 GB, free: 140.9 MB / 426.8 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00284/3.403, allocations: 71.28 kB / 0.8226 GB, free: 140.9 MB / 426.8 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.007228/3.411, allocations: 258.3 kB / 0.8229 GB, free: 140.9 MB / 426.8 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001693/3.412, allocations: 335.8 kB / 0.8232 GB, free: 140.8 MB / 426.8 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.05904/3.471, allocations: 11.25 MB / 0.8342 GB, free: 132.2 MB / 426.8 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.04271/3.514, allocations: 12.91 MB / 0.8468 GB, free: 119.2 MB / 426.8 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01139/3.526, allocations: 3.668 MB / 0.8504 GB, free: 115.5 MB / 426.8 MB
Notification: Performance of sorting global known variables: time 0.01693/3.543, allocations: 5.498 MB / 0.8558 GB, free: 110.1 MB / 426.8 MB
Notification: Performance of Backend: time 1.092e-06/3.543, allocations: 0 / 0.8558 GB, free: 110.1 MB / 426.8 MB
Notification: Performance of simCode: created initialization part: time 0.02516/3.568, allocations: 7.915 MB / 0.8635 GB, free: 102.3 MB / 426.8 MB
Notification: Performance of SimCode: time 0.04811/3.616, allocations: 23.02 MB / 0.886 GB, free: 79.58 MB / 426.8 MB
Notification: Performance of Templates: time 0.5301/4.146, allocations: 106.8 MB / 0.9903 GB, free: 124.6 MB / 426.8 MB