Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example4.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)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo)
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: "" <> buildModelFMU(IDEAS.Fluid.Examples.Performance.Example4,fileNamePrefix="IDEAS_dev_IDEAS_Fluid_Examples_Performance_Example4",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(IDEAS.Fluid.Examples.Performance.Example4,fileNamePrefix="IDEAS_dev_IDEAS_Fluid_Examples_Performance_Example4",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0008245/0.0008246, allocations: 90.75 kB / 15.06 MB, free: 1.098 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.0008862/0.0008864, allocations: 172.7 kB / 15.95 MB, free: 208 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.381/1.381, allocations: 223.2 MB / 240 MB, free: 5.332 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.9694/0.9694, allocations: 135.3 MB / 426.4 MB, free: 14.21 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003226/0.0003227, allocations: 12.22 kB / 0.5071 GB, free: 5.023 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1443/0.1447, allocations: 82.31 MB / 0.5875 GB, free: 2.887 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Examples.Performance.Example4): time 0.4693/0.614, allocations: 216.6 MB / 0.799 GB, free: 4.18 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.01736/0.6314, allocations: 14.72 MB / 0.8134 GB, free: 5.438 MB / 0.6231 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001431/0.6328, allocations: 55.62 kB / 0.8134 GB, free: 5.383 MB / 0.6231 GB
Notification: Performance of NFTyping.typeComponents: time 0.001743/0.6346, allocations: 0.652 MB / 0.8141 GB, free: 4.727 MB / 0.6231 GB
Notification: Performance of NFTyping.typeBindings: time 0.004965/0.6396, allocations: 2.126 MB / 0.8162 GB, free: 2.59 MB / 0.6231 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003274/0.6428, allocations: 1.258 MB / 0.8174 GB, free: 1.352 MB / 0.6231 GB
Notification: Performance of NFFlatten.flatten: time 0.004606/0.6475, allocations: 3.958 MB / 0.8212 GB, free: 13.38 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001446/0.6489, allocations: 0.9383 MB / 0.8222 GB, free: 12.43 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005163/0.6541, allocations: 1.918 MB / 0.824 GB, free: 10.5 MB / 0.6387 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002172/0.6563, allocations: 0.9997 MB / 0.825 GB, free: 9.5 MB / 0.6387 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003165/0.6567, allocations: 48 kB / 0.8251 GB, free: 9.453 MB / 0.6387 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004735/0.6614, allocations: 1.906 MB / 0.8269 GB, free: 7.543 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.0005665/0.662, allocations: 398.5 kB / 0.8273 GB, free: 7.152 MB / 0.6387 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006042/0.6626, allocations: 328.9 kB / 0.8276 GB, free: 6.824 MB / 0.6387 GB
Notification: Performance of NFConvertDAE.convert: time 0.002752/0.6654, allocations: 2.026 MB / 0.8296 GB, free: 4.793 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 4.088e-06/0.6654, allocations: 4 kB / 0.8296 GB, free: 4.789 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.029e-05/0.6654, allocations: 0 / 0.8296 GB, free: 4.789 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 1.929e-05/0.6654, allocations: 3.938 kB / 0.8296 GB, free: 4.785 MB / 0.6387 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 251
 * Number of variables: 251
Notification: Performance of Generate backend data structure: time 0.002907/0.6683, allocations: 1.735 MB / 0.8313 GB, free: 2.996 MB / 0.6387 GB
Notification: Performance of prepare preOptimizeDAE: time 4.042e-05/0.6684, allocations: 12.03 kB / 0.8313 GB, free: 2.984 MB / 0.6387 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.000182/0.6686, allocations: 152.2 kB / 0.8314 GB, free: 2.828 MB / 0.6387 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002542/0.6711, allocations: 0.5366 MB / 0.832 GB, free: 2.289 MB / 0.6387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001905/0.673, allocations: 1.064 MB / 0.833 GB, free: 1.172 MB / 0.6387 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008224/0.6739, allocations: 281.8 kB / 0.8333 GB, free: 0.8945 MB / 0.6387 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001336/0.674, allocations: 100 kB / 0.8334 GB, free: 0.7969 MB / 0.6387 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002273/0.6763, allocations: 1.326 MB / 0.8347 GB, free: 15.38 MB / 0.6544 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.234e-05/0.6763, allocations: 3.938 kB / 0.8347 GB, free: 15.38 MB / 0.6544 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.656e-05/0.6764, allocations: 51.94 kB / 0.8347 GB, free: 15.33 MB / 0.6544 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.727e-05/0.6765, allocations: 49.98 kB / 0.8348 GB, free: 15.28 MB / 0.6544 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002327/0.6788, allocations: 1.108 MB / 0.8359 GB, free: 14.17 MB / 0.6544 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.006706/0.6856, allocations: 6.664 MB / 0.8424 GB, free: 7.066 MB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001527/0.6871, allocations: 0.5942 MB / 0.8429 GB, free: 6.465 MB / 0.6544 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006108/0.6877, allocations: 285.2 kB / 0.8432 GB, free: 6.184 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003501/0.6881, allocations: 283.6 kB / 0.8435 GB, free: 5.852 MB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.848e-05/0.6881, allocations: 42.8 kB / 0.8435 GB, free: 5.801 MB / 0.6544 GB
Notification: Performance of pre-optimization done (n=33): time 1.944e-06/0.6881, allocations: 0 / 0.8435 GB, free: 5.801 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=33): time 0.002219/0.6904, allocations: 0.9066 MB / 0.8444 GB, free: 4.887 MB / 0.6544 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.91e-05/0.6904, allocations: 55.41 kB / 0.8445 GB, free: 4.82 MB / 0.6544 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001651/0.6921, allocations: 1.358 MB / 0.8458 GB, free: 3.453 MB / 0.6544 GB
Notification: Performance of collectPreVariables (initialization): time 5.116e-05/0.6921, allocations: 33.7 kB / 0.8458 GB, free: 3.414 MB / 0.6544 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000491/0.6926, allocations: 0.6795 MB / 0.8465 GB, free: 2.73 MB / 0.6544 GB
Notification: Performance of collectInitialBindings (initialization): time 9.862e-05/0.6927, allocations: 122.3 kB / 0.8466 GB, free: 2.609 MB / 0.6544 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.844e-05/0.6928, allocations: 12 kB / 0.8466 GB, free: 2.598 MB / 0.6544 GB
Notification: Performance of setup shared object (initialization): time 0.0001139/0.6929, allocations: 334 kB / 0.8469 GB, free: 2.266 MB / 0.6544 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007778/0.6937, allocations: 325.1 kB / 0.8472 GB, free: 1.945 MB / 0.6544 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001001/0.6947, allocations: 0.6677 MB / 0.8479 GB, free: 1.125 MB / 0.6544 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008492/0.6956, allocations: 387.7 kB / 0.8483 GB, free: 0.7461 MB / 0.6544 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.117e-06/0.6956, allocations: 4 kB / 0.8483 GB, free: 0.7422 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=81) (initialization): time 0.002116/0.6977, allocations: 1.051 MB / 0.8493 GB, free: 15.68 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002187/0.6979, allocations: 379.8 kB / 0.8497 GB, free: 15.18 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.759e-05/0.698, allocations: 15.98 kB / 0.8497 GB, free: 15.16 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.216e-05/0.698, allocations: 15.94 kB / 0.8497 GB, free: 15.15 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008164/0.6988, allocations: 203.8 kB / 0.8499 GB, free: 14.95 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.929e-05/0.6988, allocations: 23.86 kB / 0.8499 GB, free: 14.93 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001195/0.7, allocations: 234.8 kB / 0.8501 GB, free: 14.7 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.46e-05/0.7001, allocations: 39.98 kB / 0.8502 GB, free: 14.66 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 7.755e-06/0.7001, allocations: 6.188 kB / 0.8502 GB, free: 14.65 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001926/0.7021, allocations: 0.9027 MB / 0.8511 GB, free: 13.75 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.378e-06/0.7021, allocations: 8 kB / 0.8511 GB, free: 13.74 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.371e-05/0.7021, allocations: 16 kB / 0.8511 GB, free: 13.73 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007626/0.7029, allocations: 212.3 kB / 0.8513 GB, free: 13.52 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.386e-05/0.7029, allocations: 15.94 kB / 0.8513 GB, free: 13.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001063/0.704, allocations: 210.9 kB / 0.8515 GB, free: 13.3 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.298e-05/0.704, allocations: 39.98 kB / 0.8515 GB, free: 13.26 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (81):
 * Single equations (assignments): 81
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0005607/0.7046, allocations: 408.8 kB / 0.8519 GB, free: 12.85 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000173/0.7048, allocations: 134.3 kB / 0.852 GB, free: 12.71 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001564/0.7064, allocations: 0.6923 MB / 0.8527 GB, free: 12.01 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.913e-06/0.7064, allocations: 4 kB / 0.8527 GB, free: 12 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.118e-06/0.7064, allocations: 4 kB / 0.8527 GB, free: 12 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.853e-06/0.7064, allocations: 7.938 kB / 0.8527 GB, free: 11.99 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003264/0.7097, allocations: 1.932 MB / 0.8546 GB, free: 9.953 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.624e-06/0.7097, allocations: 4 kB / 0.8546 GB, free: 9.949 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004879/0.7102, allocations: 104 kB / 0.8547 GB, free: 9.848 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.097e-05/0.7102, allocations: 8.188 kB / 0.8547 GB, free: 9.84 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.403e-05/0.7103, allocations: 11.98 kB / 0.8548 GB, free: 9.828 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.748e-06/0.7103, allocations: 11.94 kB / 0.8548 GB, free: 9.816 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.25e-06/0.7103, allocations: 4 kB / 0.8548 GB, free: 9.812 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001441/0.7117, allocations: 0.8413 MB / 0.8556 GB, free: 8.906 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004086/0.7121, allocations: 287.2 kB / 0.8559 GB, free: 8.605 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001827/0.7123, allocations: 8 kB / 0.8559 GB, free: 8.598 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008941/0.7132, allocations: 107.9 kB / 0.856 GB, free: 8.492 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.161e-05/0.7133, allocations: 39.86 kB / 0.856 GB, free: 8.453 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.702e-05/0.7134, allocations: 19.98 kB / 0.856 GB, free: 8.434 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.001342/0.7147, allocations: 0.9355 MB / 0.8569 GB, free: 7.5 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.7e-07/0.7147, allocations: 0 / 0.8569 GB, free: 7.5 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.001602/0.7164, allocations: 0.5731 MB / 0.8575 GB, free: 6.926 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * Number of states: 0 ()
 * 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 (32):
 * Single equations (assignments): 32
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.00212/0.7185, allocations: 1.565 MB / 0.859 GB, free: 5.301 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.002658/0.7211, allocations: 1.147 MB / 0.8602 GB, free: 4.156 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 3.887e-06/0.7212, allocations: 0 / 0.8602 GB, free: 4.156 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.000637/0.7218, allocations: 276.9 kB / 0.8604 GB, free: 3.887 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002699/0.7245, allocations: 0.623 MB / 0.861 GB, free: 3.277 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2988/1.023, allocations: 7.817 MB / 0.8687 GB, free: 214.9 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000545/1.024, allocations: 1.183 MB / 0.8698 GB, free: 214.2 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.001511/1.025, allocations: 351.8 kB / 0.8701 GB, free: 214.2 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0006586/1.026, allocations: 484.7 kB / 0.8706 GB, free: 214.2 MB / 0.67 GB
Notification: Performance of SimCode: time 6.61e-07/1.026, allocations: 0 / 0.8706 GB, free: 214.2 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.06174/1.088, allocations: 33.26 MB / 0.9031 GB, free: 209.6 MB / 0.67 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0001315/1.088, allocations: 210.4 kB / 0.9033 GB, free: 209.4 MB / 0.67 GB
Notification: Performance of buildModelFMU: Generate platform static: time 2.421/3.509, allocations: 3.938 kB / 0.9033 GB, free: 209.4 MB / 0.67 GB
(rm -f IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example4.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example4.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example4.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example4.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator  --tempDir=temp_IDEAS_dev_IDEAS_Fluid_Examples_Performance_Example4_fmu --startTime=0 --stopTime=20 --timeout=50 --tolerance=1e-06 IDEAS_dev_IDEAS_Fluid_Examples_Performance_Example4.fmu > IDEAS_dev_IDEAS.Fluid.Examples.Performance.Example4.pipe 2>&1)
Environment - environmentSimulation: