Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a")
translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001432/0.001432, allocations: 106.2 kB / 16.51 MB, free: 6.707 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001674/0.001674, allocations: 186.5 kB / 17.42 MB, free: 6.07 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.438/1.438, allocations: 205.1 MB / 223.3 MB, free: 12.48 MB / 190.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.00121/0.001211, allocations: 55.92 kB / 279.3 MB, free: 3.59 MB / 222.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.08677/0.08799, allocations: 46.62 MB / 326 MB, free: 4.898 MB / 270.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a): time 0.2818/0.3698, allocations: 103.4 MB / 429.4 MB, free: 12.62 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.04723/0.4171, allocations: 19.94 MB / 449.3 MB, free: 10.72 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01926/0.4364, allocations: 474.8 kB / 449.8 MB, free: 10.25 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01136/0.4478, allocations: 5.867 MB / 455.6 MB, free: 4.375 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.009485/0.4573, allocations: 3.188 MB / 458.8 MB, free: 1.176 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004131/0.4615, allocations: 1.455 MB / 460.3 MB, free: 15.72 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.02157/0.4831, allocations: 15.89 MB / 476.2 MB, free: 15.8 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01133/0.4944, allocations: 7.49 MB / 483.7 MB, free: 8.238 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.007293/0.5018, allocations: 3.223 MB / 486.9 MB, free: 5.004 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.008303/0.5101, allocations: 5.087 MB / 492 MB, free: 15.9 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002371/0.5125, allocations: 484 kB / 492.4 MB, free: 15.43 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005429/0.518, allocations: 1.506 MB / 494 MB, free: 13.92 MB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.006472/0.5245, allocations: 4.04 MB / 498 MB, free: 9.871 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00852/0.533, allocations: 4.229 MB / 0.4905 GB, free: 5.617 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01694/0.55, allocations: 13.14 MB / 0.5033 GB, free: 8.434 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.947e-06/0.55, allocations: 4 kB / 0.5033 GB, free: 8.43 MB / 414.1 MB
Notification: Performance of FrontEnd: time 2.364e-06/0.5501, allocations: 0 / 0.5033 GB, free: 8.43 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.000519/0.5506, allocations: 0 / 0.5033 GB, free: 8.43 MB / 414.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2386
 * Number of variables: 2386
Notification: Performance of Generate backend data structure: time 0.02328/0.5739, allocations: 9.699 MB / 0.5128 GB, free: 14.64 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.861e-05/0.5739, allocations: 12.03 kB / 0.5128 GB, free: 14.63 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04277/0.6167, allocations: 5.236 MB / 0.5179 GB, free: 9.375 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01893/0.6357, allocations: 8.195 MB / 0.5259 GB, free: 1.113 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000382/0.6361, allocations: 427.6 kB / 0.5263 GB, free: 0.6953 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002046/0.6381, allocations: 0.5814 MB / 0.5269 GB, free: 116 kB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.3367/0.9748, allocations: 16.55 MB / 0.543 GB, free: 158.9 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005055/0.9754, allocations: 102 kB / 0.5431 GB, free: 158.8 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001177/0.9765, allocations: 267.5 kB / 0.5434 GB, free: 158.8 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.005499/0.9821, allocations: 2.767 MB / 0.5461 GB, free: 158.8 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02901/1.011, allocations: 13.63 MB / 0.5594 GB, free: 154.3 MB / 446.1 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.08552/1.097, allocations: 67.99 MB / 0.6258 GB, free: 91.14 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01904/1.116, allocations: 7.895 MB / 0.6335 GB, free: 83.11 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005457/1.121, allocations: 4.506 MB / 0.6379 GB, free: 78.59 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.02018/1.141, allocations: 12.43 MB / 0.65 GB, free: 65.96 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.708e-05/1.142, allocations: 64.56 kB / 0.6501 GB, free: 65.89 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=280): time 5.711e-06/1.142, allocations: 4 kB / 0.6501 GB, free: 65.89 MB / 446.1 MB
Notification: Performance of matching and sorting (n=340): time 0.1713/1.313, allocations: 49.27 MB / 0.6982 GB, free: 16.68 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001071/1.313, allocations: 402 kB / 0.6986 GB, free: 16.2 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01098/1.324, allocations: 7.183 MB / 0.7056 GB, free: 9.031 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.000916/1.325, allocations: 77.7 kB / 0.7057 GB, free: 8.949 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.003123/1.328, allocations: 5.029 MB / 0.7106 GB, free: 3.859 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001651/1.33, allocations: 1.261 MB / 0.7118 GB, free: 2.582 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003949/1.334, allocations: 1.872 MB / 0.7137 GB, free: 0.6953 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 4.427e-05/1.334, allocations: 305.1 kB / 0.7139 GB, free: 404 kB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.1925/1.526, allocations: 4.546 MB / 0.7184 GB, free: 147.7 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00974/1.536, allocations: 9.261 MB / 0.7274 GB, free: 140 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01736/1.554, allocations: 14.55 MB / 0.7416 GB, free: 129.5 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001933/1.554, allocations: 50.38 kB / 0.7417 GB, free: 129.5 MB / 446.1 MB
Notification: Performance of matching and sorting (n=1185) (initialization): time 0.03816/1.592, allocations: 19.75 MB / 0.761 GB, free: 123.2 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001129/1.592, allocations: 74.02 kB / 0.761 GB, free: 123.2 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001547/1.592, allocations: 167.4 kB / 0.7612 GB, free: 123.2 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01925/1.612, allocations: 7.035 MB / 0.7681 GB, free: 117.8 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006333/1.618, allocations: 1.93 MB / 0.77 GB, free: 115.8 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03988/1.658, allocations: 33.15 MB / 0.8023 GB, free: 82.49 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008628/1.666, allocations: 0.5799 MB / 0.8029 GB, free: 81.93 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001878/1.668, allocations: 500 kB / 0.8034 GB, free: 81.45 MB / 446.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 733
 * Number of states: 0 ()
 * Number of discrete variables: 66 (cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],Cylinder.boxColor[3],Cylinder.boxColor[2],Cylinder.boxColor[1],Mid.color[3],Mid.color[2],Mid.color[1],B1.cylinderColor[3],B1.cylinderColor[2],B1.cylinderColor[1],Crank2.color[3],Crank2.color[2],Crank2.color[1],Crank2.body.sphereColor[3],Crank2.body.sphereColor[2],Crank2.body.sphereColor[1],Crank3.color[3],Crank3.color[2],Crank3.color[1],Crank3.body.sphereColor[3],Crank3.body.sphereColor[2],Crank3.body.sphereColor[1],Crank1.color[3],Crank1.color[2],Crank1.color[1],Crank1.body.sphereColor[3],Crank1.body.sphereColor[2],Crank1.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.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],Bearing.cylinderColor[3],Bearing.cylinderColor[2],Bearing.cylinderColor[1],B2.cylinderColor[3],B2.cylinderColor[2],B2.cylinderColor[1],Rod.color[3],Rod.color[2],Rod.color[1],Rod.body.sphereColor[3],Rod.body.sphereColor[2],Rod.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 (1049):
 * Single equations (assignments): 1043
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(8,93,51.6%), (3,5,88.9%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.006926/1.675, allocations: 3.487 MB / 0.8068 GB, free: 78.05 MB / 446.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001788/1.677, allocations: 0.6367 MB / 0.8074 GB, free: 77.75 MB / 446.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02673/1.704, allocations: 13.32 MB / 0.8204 GB, free: 64.35 MB / 446.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.275e-05/1.704, allocations: 38.72 kB / 0.8205 GB, free: 64.32 MB / 446.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.652e-05/1.704, allocations: 20 kB / 0.8205 GB, free: 64.3 MB / 446.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.714e-05/1.704, allocations: 19.94 kB / 0.8205 GB, free: 64.28 MB / 446.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05373/1.758, allocations: 26.72 MB / 0.8466 GB, free: 37.18 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.159e-05/1.758, allocations: 18.14 kB / 0.8466 GB, free: 37.16 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001908/1.76, allocations: 423.3 kB / 0.847 GB, free: 36.75 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01848/1.778, allocations: 6.662 MB / 0.8535 GB, free: 30.07 MB / 446.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000949/1.779, allocations: 63.94 kB / 0.8536 GB, free: 30.01 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.2576/2.037, allocations: 31.91 MB / 0.8847 GB, free: 131.8 MB / 446.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.343e-05/2.037, allocations: 13.72 kB / 0.8848 GB, free: 131.8 MB / 446.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03357/2.071, allocations: 18.76 MB / 0.9031 GB, free: 125.5 MB / 446.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.004791/2.076, allocations: 1.389 MB / 0.9044 GB, free: 125.1 MB / 446.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.00225/2.078, allocations: 63.78 kB / 0.9045 GB, free: 125 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004689/2.082, allocations: 309.3 kB / 0.9048 GB, free: 125 MB / 446.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001114/2.084, allocations: 242.3 kB / 0.905 GB, free: 124.9 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002911/2.084, allocations: 89.12 kB / 0.9051 GB, free: 124.9 MB / 446.1 MB
Notification: Performance of sorting global known variables: time 0.008554/2.092, allocations: 4.61 MB / 0.9096 GB, free: 123.4 MB / 446.1 MB
Notification: Performance of sort global known variables: time 4.71e-07/2.092, allocations: 0 / 0.9096 GB, free: 123.4 MB / 446.1 MB
Notification: Performance of remove unused functions: time 0.01134/2.104, allocations: 2.144 MB / 0.9117 GB, free: 123.2 MB / 446.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 28
 * Number of states: 2 (Inertia.phi,Inertia.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 (215):
 * Single equations (assignments): 209
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(7,87,55.1%), (2,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,6)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.000942/2.105, allocations: 220.3 kB / 0.9119 GB, free: 123.1 MB / 446.1 MB
Notification: Performance of simCode: created initialization part: time 0.01727/2.122, allocations: 11.57 MB / 0.9232 GB, free: 116.1 MB / 446.1 MB
Notification: Performance of simCode: created event and clocks part: time 1.066e-05/2.122, allocations: 4 kB / 0.9232 GB, free: 116.1 MB / 446.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.007415/2.13, allocations: 5.006 MB / 0.9281 GB, free: 111.5 MB / 446.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006651/2.136, allocations: 0.9337 MB / 0.929 GB, free: 110.6 MB / 446.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03651/2.173, allocations: 23.43 MB / 0.9519 GB, free: 87.38 MB / 446.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007934/2.181, allocations: 5.564 MB / 0.9573 GB, free: 81.78 MB / 446.1 MB
Notification: Performance of simCode: alias equations: time 0.006985/2.188, allocations: 2.601 MB / 0.9599 GB, free: 79.17 MB / 446.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001459/2.189, allocations: 395.4 kB / 0.9602 GB, free: 78.79 MB / 446.1 MB
Notification: Performance of SimCode: time 1.453e-06/2.189, allocations: 0 / 0.9602 GB, free: 78.79 MB / 446.1 MB
Notification: Performance of Templates: time 0.352/2.541, allocations: 95.46 MB / 1.053 GB, free: 125.7 MB / 446.1 MB
make -j1 -f Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.makefile
(rm -f Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; mkfifo Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe ; head -c 1048576 < Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe >> ../files/Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.sim & ./Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Modelica_3.2.2_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1a.pipe 2>&1)