Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.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.Engine1b,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b")
translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.006503/0.006503, allocations: 102 kB / 15.73 MB, free: 6.051 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.00164/0.00164, allocations: 195.5 kB / 16.67 MB, free: 5.965 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.552/1.552, allocations: 205.1 MB / 222.5 MB, free: 4.484 MB / 174.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.001181/0.001181, allocations: 50.58 kB / 326.6 MB, free: 14.39 MB / 270.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09325/0.09444, allocations: 46.63 MB / 373.2 MB, free: 1.285 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b): time 0.01875/0.1132, allocations: 20.6 MB / 393.8 MB, free: 12.6 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01271/0.126, allocations: 6.223 MB / 400 MB, free: 6.355 MB / 334.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003849/0.1299, allocations: 167.4 kB / 400.2 MB, free: 6.191 MB / 334.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01198/0.1419, allocations: 6.355 MB / 406.5 MB, free: 15.82 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01264/0.1546, allocations: 3.455 MB / 410 MB, free: 12.35 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004756/0.1594, allocations: 1.662 MB / 411.7 MB, free: 10.68 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01726/0.1766, allocations: 11.66 MB / 423.3 MB, free: 15 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01002/0.1867, allocations: 8.072 MB / 431.4 MB, free: 6.84 MB / 366.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.008537/0.1953, allocations: 3.782 MB / 435.2 MB, free: 3.047 MB / 366.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.008777/0.2041, allocations: 4.97 MB / 440.1 MB, free: 14.06 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002683/0.2068, allocations: 0.7461 MB / 440.9 MB, free: 13.32 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0058/0.2126, allocations: 1.713 MB / 442.6 MB, free: 11.6 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.006846/0.2195, allocations: 4.392 MB / 447 MB, free: 7.199 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.008943/0.2285, allocations: 4.674 MB / 451.7 MB, free: 2.5 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.2608/0.4894, allocations: 14.25 MB / 465.9 MB, free: 23.17 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.313e-06/0.4894, allocations: 0 / 465.9 MB, free: 23.17 MB / 382.1 MB
Notification: Performance of FrontEnd: time 1.903e-06/0.4894, allocations: 0 / 465.9 MB, free: 23.17 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 0.0006615/0.4901, allocations: 0 / 465.9 MB, free: 23.17 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2489
 * Number of variables: 2489
Notification: Performance of Generate backend data structure: time 0.04132/0.5314, allocations: 10.33 MB / 476.2 MB, free: 19.25 MB / 382.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.679e-05/0.5315, allocations: 9.938 kB / 476.2 MB, free: 19.25 MB / 382.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04517/0.5767, allocations: 5.331 MB / 481.6 MB, free: 18.55 MB / 382.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02222/0.5989, allocations: 8.594 MB / 490.2 MB, free: 15.79 MB / 382.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004174/0.5994, allocations: 448.3 kB / 490.6 MB, free: 15.77 MB / 382.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003642/0.603, allocations: 0.6146 MB / 491.2 MB, free: 15.64 MB / 382.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03843/0.6415, allocations: 17.13 MB / 0.4964 GB, free: 4.238 MB / 382.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000392/0.6419, allocations: 102.2 kB / 0.4965 GB, free: 4.168 MB / 382.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001511/0.6434, allocations: 277.5 kB / 0.4968 GB, free: 3.945 MB / 382.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003743/0.6472, allocations: 2.859 MB / 0.4996 GB, free: 1.129 MB / 382.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03269/0.6799, allocations: 14.03 MB / 0.5133 GB, free: 3.359 MB / 398.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.1071/0.787, allocations: 69.23 MB / 0.5809 GB, free: 13.04 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.02121/0.8083, allocations: 7.464 MB / 0.5882 GB, free: 5.449 MB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00704/0.8153, allocations: 4.47 MB / 0.5925 GB, free: 0.9688 MB / 478.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.02678/0.8422, allocations: 12.77 MB / 0.605 GB, free: 4.008 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.229e-05/0.8423, allocations: 72.56 kB / 0.6051 GB, free: 3.93 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=293): time 7.885e-06/0.8423, allocations: 0 / 0.6051 GB, free: 3.93 MB / 494.1 MB
Notification: Performance of matching and sorting (n=365): time 0.4116/1.254, allocations: 43.96 MB / 0.648 GB, free: 194.2 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001396/1.254, allocations: 405.4 kB / 0.6484 GB, free: 193.8 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01422/1.268, allocations: 7.397 MB / 0.6556 GB, free: 191.5 MB / 0.5137 GB
Notification: Performance of collectPreVariables (initialization): time 0.00133/1.27, allocations: 80.95 kB / 0.6557 GB, free: 191.4 MB / 0.5137 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004033/1.274, allocations: 5.426 MB / 0.661 GB, free: 188 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002175/1.276, allocations: 1.408 MB / 0.6624 GB, free: 187 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003779/1.28, allocations: 1.947 MB / 0.6643 GB, free: 186.1 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 6.854e-05/1.28, allocations: 309.5 kB / 0.6646 GB, free: 185.8 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006353/1.286, allocations: 4.281 MB / 0.6687 GB, free: 184.3 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01025/1.296, allocations: 9.197 MB / 0.6777 GB, free: 176.3 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01693/1.313, allocations: 14.25 MB / 0.6916 GB, free: 161.7 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001645/1.314, allocations: 48 kB / 0.6917 GB, free: 161.7 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=1238) (initialization): time 0.04334/1.357, allocations: 18.04 MB / 0.7093 GB, free: 143.7 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000259/1.357, allocations: 76 kB / 0.7094 GB, free: 143.6 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003512/1.358, allocations: 168.8 kB / 0.7095 GB, free: 143.4 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01568/1.373, allocations: 5.328 MB / 0.7147 GB, free: 138.1 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007215/1.381, allocations: 2.008 MB / 0.7167 GB, free: 136.1 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03998/1.421, allocations: 31.13 MB / 0.7471 GB, free: 104.5 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01071/1.431, allocations: 0.5592 MB / 0.7477 GB, free: 104 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002606/1.434, allocations: 0.5117 MB / 0.7482 GB, free: 103.5 MB / 0.5137 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 762
 * Number of states: 0 ()
 * Number of discrete variables: 72 (Rod3.color[3],Rod3.color[2],Rod3.color[1],Rod1.color[3],Rod1.color[2],Rod1.color[1],Cylinder.boxColor[3],Cylinder.boxColor[2],Cylinder.boxColor[1],B1.cylinderColor[3],B1.cylinderColor[2],B1.cylinderColor[1],B2.cylinderColor[3],B2.cylinderColor[2],B2.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.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],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 (1102):
 * Single equations (assignments): 1096
 * 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
   {(6,100,72.2%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.01116/1.445, allocations: 3.625 MB / 0.7517 GB, free: 99.84 MB / 0.5137 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002673/1.448, allocations: 0.6946 MB / 0.7524 GB, free: 99.14 MB / 0.5137 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02595/1.474, allocations: 11.27 MB / 0.7634 GB, free: 87.8 MB / 0.5137 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.704e-05/1.474, allocations: 41.27 kB / 0.7634 GB, free: 87.76 MB / 0.5137 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.946e-05/1.474, allocations: 20 kB / 0.7634 GB, free: 87.74 MB / 0.5137 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001074/1.474, allocations: 23.94 kB / 0.7635 GB, free: 87.72 MB / 0.5137 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05799/1.532, allocations: 22.02 MB / 0.785 GB, free: 65.55 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.598e-05/1.532, allocations: 8 kB / 0.785 GB, free: 65.55 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00229/1.535, allocations: 455.3 kB / 0.7854 GB, free: 65.1 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01446/1.549, allocations: 5.015 MB / 0.7903 GB, free: 60.07 MB / 0.5137 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001092/1.55, allocations: 64 kB / 0.7904 GB, free: 60.01 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03655/1.587, allocations: 30.54 MB / 0.8202 GB, free: 29.06 MB / 0.5137 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.704e-06/1.587, allocations: 12 kB / 0.8202 GB, free: 29.05 MB / 0.5137 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.3168/1.904, allocations: 16.52 MB / 0.8363 GB, free: 176 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004826/1.908, allocations: 1.377 MB / 0.8377 GB, free: 175.9 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002508/1.911, allocations: 66.69 kB / 0.8377 GB, free: 175.9 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004504/1.916, allocations: 267.6 kB / 0.838 GB, free: 175.9 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009753/1.917, allocations: 264.8 kB / 0.8383 GB, free: 175.9 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003059/1.917, allocations: 98.09 kB / 0.8384 GB, free: 175.9 MB / 0.5137 GB
Notification: Performance of sorting global known variables: time 0.0087/1.926, allocations: 4.736 MB / 0.843 GB, free: 174.9 MB / 0.5137 GB
Notification: Performance of sort global known variables: time 2.31e-07/1.926, allocations: 0 / 0.843 GB, free: 174.9 MB / 0.5137 GB
Notification: Performance of remove unused functions: time 0.01177/1.937, allocations: 2.178 MB / 0.8451 GB, free: 174.7 MB / 0.5137 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 35
 * 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 (241):
 * Single equations (assignments): 235
 * 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
   {(5,96,72.0%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,5)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.001194/1.939, allocations: 225.1 kB / 0.8453 GB, free: 174.5 MB / 0.5137 GB
Notification: Performance of simCode: created initialization part: time 0.01834/1.957, allocations: 10.89 MB / 0.856 GB, free: 170.5 MB / 0.5137 GB
Notification: Performance of simCode: created event and clocks part: time 1.386e-05/1.957, allocations: 0 / 0.856 GB, free: 170.5 MB / 0.5137 GB
Notification: Performance of simCode: created simulation system equations: time 0.007536/1.965, allocations: 4.49 MB / 0.8603 GB, free: 168.5 MB / 0.5137 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01228/1.977, allocations: 1.022 MB / 0.8613 GB, free: 168.1 MB / 0.5137 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.04749/2.024, allocations: 19.65 MB / 0.8805 GB, free: 151.4 MB / 0.5137 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01562/2.04, allocations: 5.769 MB / 0.8862 GB, free: 147.1 MB / 0.5137 GB
Notification: Performance of simCode: alias equations: time 0.008655/2.049, allocations: 2.742 MB / 0.8888 GB, free: 144.7 MB / 0.5137 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002223/2.051, allocations: 419.4 kB / 0.8892 GB, free: 144.3 MB / 0.5137 GB
Notification: Performance of SimCode: time 7.22e-07/2.051, allocations: 0 / 0.8892 GB, free: 144.3 MB / 0.5137 GB
Notification: Performance of Templates: time 0.1534/2.204, allocations: 93.41 MB / 0.9805 GB, free: 50.9 MB / 0.5137 GB
make -j1 -f Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.makefile
(rm -f Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; mkfifo Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; head -c 1048576 < Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe >> ../files/Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.sim & ./Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Modelica_3.1_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe 2>&1)