Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
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: "" <> buildModelFMU(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,fileNamePrefix="Modelica_trunk_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1b",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b,fileNamePrefix="Modelica_trunk_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1b",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001386/0.001386, allocations: 95.08 kB / 17 MB, free: 6.418 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.002292/0.002292, allocations: 194.6 kB / 17.9 MB, free: 5.938 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.45/1.45, allocations: 227.4 MB / 246 MB, free: 10.07 MB / 206.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0002315/0.0002316, allocations: 8 kB / 306.8 MB, free: 1.238 MB / 238.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.09645/0.0967, allocations: 51.49 MB / 358.3 MB, free: 13.76 MB / 302.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b): time 0.2756/0.3723, allocations: 101.2 MB / 459.5 MB, free: 6.359 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.05533/0.4277, allocations: 20.43 MB / 479.9 MB, free: 1.859 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02603/0.4538, allocations: 0.4987 MB / 480.4 MB, free: 1.359 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0203/0.4741, allocations: 5.284 MB / 485.7 MB, free: 12.05 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.04393/0.5181, allocations: 15.62 MB / 0.4896 GB, free: 12.38 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.008287/0.5264, allocations: 1.488 MB / 0.491 GB, free: 10.89 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.4709/0.9973, allocations: 179.5 MB / 0.6664 GB, free: 14.56 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0138/1.011, allocations: 9.275 MB / 0.6754 GB, free: 8 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.008106/1.019, allocations: 3.069 MB / 0.6784 GB, free: 5.887 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.01082/1.03, allocations: 4.613 MB / 0.6829 GB, free: 2.539 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003105/1.033, allocations: 292 kB / 0.6832 GB, free: 2.254 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006255/1.04, allocations: 1.128 MB / 0.6843 GB, free: 1.324 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.01034/1.05, allocations: 3.809 MB / 0.688 GB, free: 14.48 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.006785/1.057, allocations: 1.65 MB / 0.6896 GB, free: 13.87 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.01906/1.076, allocations: 11.61 MB / 0.701 GB, free: 3.797 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 7.114e-06/1.076, allocations: 0 / 0.701 GB, free: 3.797 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 2.715e-06/1.076, allocations: 0 / 0.701 GB, free: 3.797 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 0.0004906/1.077, allocations: 0 / 0.701 GB, free: 3.797 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2488
 * Number of variables: 2488
Notification: Performance of Generate backend data structure: time 0.02333/1.1, allocations: 9.009 MB / 0.7098 GB, free: 11.5 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 4.796e-05/1.1, allocations: 8.031 kB / 0.7098 GB, free: 11.49 MB / 0.5137 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.003546/1.104, allocations: 1.193 MB / 0.7109 GB, free: 10.79 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.6572/1.761, allocations: 24.83 MB / 0.7352 GB, free: 221.2 MB / 0.5138 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0244/1.785, allocations: 8.935 MB / 0.7439 GB, free: 217.3 MB / 0.5138 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003286/1.786, allocations: 450.6 kB / 0.7443 GB, free: 217.2 MB / 0.5138 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002019/1.788, allocations: 0.6134 MB / 0.7449 GB, free: 217 MB / 0.5138 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0275/1.815, allocations: 16.77 MB / 0.7613 GB, free: 200.8 MB / 0.5138 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.00036/1.815, allocations: 99.11 kB / 0.7614 GB, free: 200.7 MB / 0.5138 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001369/1.817, allocations: 278.1 kB / 0.7617 GB, free: 200.5 MB / 0.5138 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00377/1.821, allocations: 2.86 MB / 0.7645 GB, free: 197.6 MB / 0.5138 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0268/1.847, allocations: 13.27 MB / 0.7774 GB, free: 184.4 MB / 0.5138 GB
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.08561/1.933, allocations: 68.35 MB / 0.8442 GB, free: 114.4 MB / 0.5138 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0184/1.952, allocations: 7.921 MB / 0.8519 GB, free: 106.3 MB / 0.5138 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005222/1.957, allocations: 4.312 MB / 0.8561 GB, free: 102 MB / 0.5138 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01951/1.976, allocations: 12.05 MB / 0.8679 GB, free: 89.84 MB / 0.5138 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.19e-05/1.977, allocations: 72.5 kB / 0.868 GB, free: 89.77 MB / 0.5138 GB
Notification: Performance of pre-optimization done (n=293): time 7.865e-06/1.977, allocations: 0 / 0.868 GB, free: 89.77 MB / 0.5138 GB
Notification: Performance of matching and sorting (n=359): time 0.1725/2.149, allocations: 50.72 MB / 0.9175 GB, free: 39.09 MB / 0.5138 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001203/2.149, allocations: 402.2 kB / 0.9179 GB, free: 38.61 MB / 0.5138 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01404/2.163, allocations: 7.265 MB / 0.925 GB, free: 31.39 MB / 0.5138 GB
Notification: Performance of collectPreVariables (initialization): time 0.001337/2.165, allocations: 87.42 kB / 0.9251 GB, free: 31.3 MB / 0.5138 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004701/2.169, allocations: 5.352 MB / 0.9303 GB, free: 25.94 MB / 0.5138 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002265/2.172, allocations: 1.308 MB / 0.9316 GB, free: 24.64 MB / 0.5138 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001514/2.173, allocations: 168 kB / 0.9317 GB, free: 24.48 MB / 0.5138 GB
Notification: Performance of setup shared object (initialization): time 0.0008468/2.174, allocations: 0.6616 MB / 0.9324 GB, free: 23.81 MB / 0.5138 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006296/2.18, allocations: 4.092 MB / 0.9364 GB, free: 19.71 MB / 0.5138 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009014/2.189, allocations: 8.827 MB / 0.945 GB, free: 8.551 MB / 0.5138 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008214/2.198, allocations: 5.832 MB / 0.9507 GB, free: 2.703 MB / 0.5138 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001533/2.198, allocations: 48 kB / 0.9507 GB, free: 2.656 MB / 0.5138 GB
Notification: Performance of matching and sorting (n=1106) (initialization): time 0.2917/2.49, allocations: 17.79 MB / 0.9681 GB, free: 194.8 MB / 0.5138 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001329/2.49, allocations: 73.84 kB / 0.9682 GB, free: 194.8 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002739/2.49, allocations: 163 kB / 0.9683 GB, free: 194.8 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0177/2.508, allocations: 6.105 MB / 0.9743 GB, free: 193.8 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00575/2.514, allocations: 1.82 MB / 0.9761 GB, free: 193.5 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04147/2.555, allocations: 30.86 MB / 1.006 GB, free: 175.7 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0125/2.568, allocations: 0.5458 MB / 1.007 GB, free: 175.2 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002146/2.57, allocations: 489.2 kB / 1.007 GB, free: 174.7 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 743
 * 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 (967):
 * Single equations (assignments): 961
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(8,51.6%) 96,(2,100.0%) 6}
 * Non-linear torn systems: 1 {2 4}
Notification: Performance of prepare postOptimizeDAE: time 0.009873/2.58, allocations: 3.58 MB / 1.011 GB, free: 171.2 MB / 0.5138 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002035/2.582, allocations: 0.6741 MB / 1.011 GB, free: 170.9 MB / 0.5138 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02917/2.611, allocations: 12.06 MB / 1.023 GB, free: 158.8 MB / 0.5138 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.219e-05/2.611, allocations: 39.95 kB / 1.023 GB, free: 158.8 MB / 0.5138 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.488e-05/2.611, allocations: 20.88 kB / 1.023 GB, free: 158.8 MB / 0.5138 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.106e-05/2.611, allocations: 15.94 kB / 1.023 GB, free: 158.7 MB / 0.5138 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04446/2.656, allocations: 26 MB / 1.049 GB, free: 132.5 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.893e-05/2.656, allocations: 4 kB / 1.049 GB, free: 132.5 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002243/2.658, allocations: 470 kB / 1.049 GB, free: 132 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01563/2.674, allocations: 5.734 MB / 1.055 GB, free: 126.3 MB / 0.5138 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000859/2.674, allocations: 68.33 kB / 1.055 GB, free: 126.3 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03416/2.709, allocations: 29.75 MB / 1.084 GB, free: 96.36 MB / 0.5138 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.732e-06/2.709, allocations: 11.94 kB / 1.084 GB, free: 96.35 MB / 0.5138 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02778/2.737, allocations: 17.36 MB / 1.101 GB, free: 78.91 MB / 0.5138 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002254/2.739, allocations: 0.7031 MB / 1.101 GB, free: 78.18 MB / 0.5138 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001692/2.741, allocations: 64 kB / 1.101 GB, free: 78.12 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003581/2.744, allocations: 285.3 kB / 1.102 GB, free: 77.84 MB / 0.5138 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001097/2.745, allocations: 254.8 kB / 1.102 GB, free: 77.6 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004348/2.746, allocations: 95.98 kB / 1.102 GB, free: 77.5 MB / 0.5138 GB
Notification: Performance of sorting global known variables: time 0.009192/2.755, allocations: 4.667 MB / 1.107 GB, free: 72.88 MB / 0.5138 GB
Notification: Performance of sort global known variables: time 3.31e-07/2.755, allocations: 0 / 1.107 GB, free: 72.88 MB / 0.5138 GB
Notification: Performance of remove unused functions: time 0.01151/2.766, allocations: 2.055 MB / 1.109 GB, free: 70.83 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 26
 * 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 (231):
 * Single equations (assignments): 225
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(7,55.1%) 90,(2,100.0%) 4}
 * Non-linear torn systems: 1 {2 6}
Notification: Performance of Backend phase and start with SimCode phase: time 0.03221/2.799, allocations: 18.07 MB / 1.126 GB, free: 52.59 MB / 0.5138 GB
Notification: Performance of simCode: created initialization part: time 0.01964/2.818, allocations: 10.86 MB / 1.137 GB, free: 41.68 MB / 0.5138 GB
Notification: Performance of simCode: created event and clocks part: time 1.342e-05/2.818, allocations: 0 / 1.137 GB, free: 41.68 MB / 0.5138 GB
Notification: Performance of simCode: created simulation system equations: time 0.009278/2.828, allocations: 4.939 MB / 1.142 GB, free: 36.69 MB / 0.5138 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00952/2.837, allocations: 1.047 MB / 1.143 GB, free: 35.65 MB / 0.5138 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3927/3.23, allocations: 83.06 MB / 1.224 GB, free: 152 MB / 0.5138 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008999/3.239, allocations: 5.728 MB / 1.229 GB, free: 147.1 MB / 0.5138 GB
Notification: Performance of simCode: alias equations: time 0.0075/3.247, allocations: 2.714 MB / 1.232 GB, free: 144.6 MB / 0.5138 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.007253/3.254, allocations: 3.884 MB / 1.236 GB, free: 140.7 MB / 0.5138 GB
Notification: Performance of SimCode: time 1.593e-06/3.254, allocations: 0 / 1.236 GB, free: 140.7 MB / 0.5138 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.479/3.733, allocations: 168.7 MB / 1.401 GB, free: 167.4 MB / 0.5138 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001799/3.733, allocations: 226.3 kB / 1.401 GB, free: 167.2 MB / 0.5138 GB
Notification: Performance of buildModelFMU: Generate platform static: time 6.528/10.26, allocations: 2.297 kB / 1.401 GB, free: 167.2 MB / 0.5138 GB
(rm -f Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; mkfifo Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe ; head -c 1048576 < Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe >> ../files/Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_res.mat --tempDir=temp_Modelica_trunk_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1b_fmu --startTime=0 --stopTime=0.5 --timeout=50 --tolerance=1e-06 Modelica_trunk_Modelica_Mechanics_MultiBody_Examples_Loops_Engine1b.fmu > Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b.pipe 2>&1)
TODO: How the !@#!# did the simulation report success but simulation result Modelica_trunk_Modelica.Mechanics.MultiBody.Examples.Loops.Engine1b_res.mat does not exist to compare? outputFormat=mat