Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.0.0_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 4.0.0+maint.om/package.mo", uses=false)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+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="mat",numberOfIntervals=5000,variableFilter="time|inertia.phi|inertia.w",fileNamePrefix="Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b")
translateModel(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|inertia.phi|inertia.w",fileNamePrefix="Modelica_4.0.0_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.001499/0.001499, allocations: 106.3 kB / 17.74 MB, free: 5.352 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.001676/0.001676, allocations: 185.4 kB / 18.68 MB, free: 4.426 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.295/1.295, allocations: 222.9 MB / 242.4 MB, free: 15.15 MB / 206.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001003/0.001003, allocations: 55.89 kB / 352 MB, free: 3.199 MB / 286.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2103/0.2113, allocations: 49.43 MB / 401.5 MB, free: 8.73 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b): time 0.02036/0.2317, allocations: 20.53 MB / 422 MB, free: 4.227 MB / 334.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01266/0.2444, allocations: 6.179 MB / 428.2 MB, free: 14.03 MB / 350.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002966/0.2474, allocations: 171.4 kB / 428.3 MB, free: 13.86 MB / 350.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01092/0.2583, allocations: 6.351 MB / 434.7 MB, free: 7.492 MB / 350.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007245/0.2656, allocations: 3.54 MB / 438.2 MB, free: 3.941 MB / 350.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003193/0.2688, allocations: 1.662 MB / 439.9 MB, free: 2.277 MB / 350.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01483/0.2836, allocations: 12.65 MB / 452.5 MB, free: 5.602 MB / 366.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.008629/0.2923, allocations: 8.06 MB / 460.6 MB, free: 13.45 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005062/0.2974, allocations: 3.751 MB / 464.4 MB, free: 9.691 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006259/0.3036, allocations: 4.978 MB / 469.3 MB, free: 4.699 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001305/0.3049, allocations: 0.7461 MB / 470.1 MB, free: 3.953 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002993/0.3079, allocations: 1.721 MB / 471.8 MB, free: 2.23 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0037/0.3117, allocations: 4.454 MB / 476.3 MB, free: 13.77 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0044/0.3161, allocations: 4.666 MB / 480.9 MB, free: 9.074 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01302/0.3291, allocations: 14.23 MB / 495.1 MB, free: 10.79 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.581e-06/0.3291, allocations: 0 / 495.1 MB, free: 10.79 MB / 414.1 MB
Notification: Performance of FrontEnd: time 1.343e-06/0.3291, allocations: 0 / 495.1 MB, free: 10.79 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0002799/0.3294, allocations: 0 / 495.1 MB, free: 10.79 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: 2489
 * Number of variables: 2489
Notification: Performance of Generate backend data structure: time 0.01842/0.3479, allocations: 10.3 MB / 0.4936 GB, free: 404 kB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.988e-05/0.3479, allocations: 8.031 kB / 0.4936 GB, free: 396 kB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.04081/0.3887, allocations: 5.334 MB / 0.4988 GB, free: 11.04 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01632/0.4051, allocations: 8.282 MB / 0.5069 GB, free: 2.695 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003192/0.4054, allocations: 449.1 kB / 0.5073 GB, free: 2.258 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001207/0.4066, allocations: 0.6167 MB / 0.5079 GB, free: 1.641 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02776/0.4344, allocations: 17.13 MB / 0.5247 GB, free: 15.45 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003083/0.4347, allocations: 99.28 kB / 0.5248 GB, free: 15.35 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001263/0.436, allocations: 283.8 kB / 0.525 GB, free: 15.07 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00441/0.4404, allocations: 2.854 MB / 0.5278 GB, free: 12.21 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.2012/0.6416, allocations: 14.03 MB / 0.5415 GB, free: 120.2 MB / 462.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.06281/0.7045, allocations: 69.8 MB / 0.6097 GB, free: 63.02 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01732/0.7218, allocations: 8.236 MB / 0.6177 GB, free: 54.69 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004131/0.7259, allocations: 4.634 MB / 0.6222 GB, free: 50.05 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01399/0.7399, allocations: 12.79 MB / 0.6347 GB, free: 37.11 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.01e-05/0.74, allocations: 68.5 kB / 0.6348 GB, free: 37.04 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=294): time 4.599e-06/0.74, allocations: 0 / 0.6348 GB, free: 37.04 MB / 462.1 MB
Notification: Performance of matching and sorting (n=360): time 0.1723/0.9123, allocations: 52.94 MB / 0.6865 GB, free: 212 kB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000198/0.9125, allocations: 394.2 kB / 0.6869 GB, free: 15.73 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01005/0.9226, allocations: 7.289 MB / 0.694 GB, free: 8.473 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0006097/0.9232, allocations: 89.28 kB / 0.6941 GB, free: 8.383 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.003578/0.9268, allocations: 5.379 MB / 0.6993 GB, free: 2.977 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001376/0.9282, allocations: 1.391 MB / 0.7007 GB, free: 1.574 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002658/0.9309, allocations: 1.929 MB / 0.7026 GB, free: 15.63 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 8.911e-05/0.931, allocations: 312 kB / 0.7029 GB, free: 15.33 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006531/0.9375, allocations: 4.715 MB / 0.7075 GB, free: 10.61 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009349/0.9469, allocations: 9.538 MB / 0.7168 GB, free: 14.73 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01704/0.9639, allocations: 15 MB / 0.7314 GB, free: 13.39 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.066e-05/0.964, allocations: 51.94 kB / 0.7315 GB, free: 13.34 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=1221) (initialization): time 0.222/1.186, allocations: 20.5 MB / 0.7515 GB, free: 178.8 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001871/1.186, allocations: 77.22 kB / 0.7516 GB, free: 178.8 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002424/1.186, allocations: 161.3 kB / 0.7517 GB, free: 178.8 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02055/1.207, allocations: 7.312 MB / 0.7589 GB, free: 178.8 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005883/1.213, allocations: 1.997 MB / 0.7608 GB, free: 178.8 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04083/1.254, allocations: 36.57 MB / 0.7965 GB, free: 153.6 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006939/1.261, allocations: 0.6054 MB / 0.7971 GB, free: 153 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007034/1.261, allocations: 0.5 MB / 0.7976 GB, free: 152.5 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 749
 * 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],b2.cylinderColor[3],b2.cylinderColor[2],b2.cylinderColor[1],b1.cylinderColor[3],b1.cylinderColor[2],b1.cylinderColor[1],cylPosition.color[3],cylPosition.color[2],cylPosition.color[1],mid.color[3],mid.color[2],mid.color[1],crank4.color[3],crank4.color[2],crank4.color[1],crank4.body.sphereColor[3],crank4.body.sphereColor[2],crank4.body.sphereColor[1],crank3.color[3],crank3.color[2],crank3.color[1],crank3.body.sphereColor[3],crank3.body.sphereColor[2],crank3.body.sphereColor[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],bearing.cylinderColor[3],bearing.cylinderColor[2],bearing.cylinderColor[1],connectingRod.color[3],connectingRod.color[2],connectingRod.color[1],connectingRod.body.sphereColor[3],connectingRod.body.sphereColor[2],connectingRod.body.sphereColor[1],piston.color[3],piston.color[2],piston.color[1],piston.body.sphereColor[3],piston.body.sphereColor[2],piston.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])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1082):
 * Single equations (assignments): 1076
 * 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,97,55.1%), (2,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(2,4)}
Notification: Performance of prepare postOptimizeDAE: time 0.004167/1.266, allocations: 3.594 MB / 0.8011 GB, free: 149 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009745/1.267, allocations: 0.6921 MB / 0.8018 GB, free: 148.7 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02603/1.293, allocations: 13.94 MB / 0.8154 GB, free: 134.8 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.973e-05/1.293, allocations: 22.22 kB / 0.8154 GB, free: 134.7 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.236e-06/1.293, allocations: 7.844 kB / 0.8154 GB, free: 134.7 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003246/1.296, allocations: 1.462 MB / 0.8169 GB, free: 133.6 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001068/1.297, allocations: 60 kB / 0.8169 GB, free: 133.6 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003281/1.3, allocations: 316.3 kB / 0.8172 GB, free: 133.3 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008268/1.301, allocations: 317.8 kB / 0.8175 GB, free: 133 MB / 0.5294 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02128/1.322, allocations: 12.77 MB / 0.83 GB, free: 120.4 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01321/1.336, allocations: 13.07 MB / 0.8428 GB, free: 107.5 MB / 0.5294 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003566/1.339, allocations: 3.722 MB / 0.8464 GB, free: 103.8 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.005144/1.344, allocations: 4.654 MB / 0.851 GB, free: 99.15 MB / 0.5294 GB
Notification: Performance of Backend: time 6.52e-07/1.344, allocations: 0 / 0.851 GB, free: 99.15 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.01408/1.359, allocations: 8.971 MB / 0.8597 GB, free: 90.2 MB / 0.5294 GB
Notification: Performance of SimCode: time 0.02149/1.38, allocations: 18.18 MB / 0.8775 GB, free: 71.98 MB / 0.5294 GB
Notification: Performance of Templates: time 0.3097/1.69, allocations: 108.6 MB / 0.9835 GB, free: 161 MB / 0.5294 GB
make -j1 -f Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.makefile
(rm -f Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; mkfifo Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; head -c 1048576 < Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe >> ../files/Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.sim & ./Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.0.0_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe 2>&1)