Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Buildings_9_Buildings.Fluid.Examples.Performance.Example3.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/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/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/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 Buildings with version 9.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/package.mo)
Running command: translateModel(Buildings.Fluid.Examples.Performance.Example3,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|pump.m_flow",fileNamePrefix="Buildings_9_Buildings.Fluid.Examples.Performance.Example3")
translateModel(Buildings.Fluid.Examples.Performance.Example3,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|pump.m_flow",fileNamePrefix="Buildings_9_Buildings.Fluid.Examples.Performance.Example3")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.1-maint.9.1.x/package.mo): time 2.298/2.298, allocations: 390.3 MB / 405.3 MB, free: 13.49 MB / 314.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001234/0.001234, allocations: 186.9 kB / 498.1 MB, free: 5.75 MB / 394.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.713/1.713, allocations: 223.2 MB / 0.7948 GB, free: 17.9 MB / 0.5573 GB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001062/0.001062, allocations: 96.33 kB / 0.9346 GB, free: 6.199 MB / 0.6823 GB
Notification: Performance of FrontEnd - loaded program: time 0.0003757/0.0003759, allocations: 9.719 kB / 1.084 GB, free: 221.3 MB / 0.823 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.334/0.3344, allocations: 142.3 MB / 1.223 GB, free: 114.3 MB / 0.823 GB
Notification: Performance of FrontEnd - scodeFlatten: time 1.2/1.534, allocations: 402.8 MB / 1.616 GB, free: 8.969 MB / 0.9323 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.00031/1.534, allocations: 97.05 kB / 1.616 GB, free: 8.922 MB / 0.9323 GB
Notification: Performance of FrontEnd - DAE generated: time 2.198/3.732, allocations: 0.7071 GB / 2.323 GB, free: 76 kB / 1.167 GB
Notification: Performance of FrontEnd: time 2.926e-06/3.732, allocations: 0 / 2.323 GB, free: 76 kB / 1.167 GB
Notification: Performance of Transformations before backend: time 0.0004106/3.733, allocations: 56.14 kB / 2.323 GB, free: 20 kB / 1.167 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 235
 * Number of variables: 235
Notification: Performance of Generate backend data structure: time 0.7853/4.518, allocations: 2.441 MB / 2.326 GB, free: 299 MB / 1.167 GB
Notification: Performance of prepare preOptimizeDAE: time 5.955e-05/4.518, allocations: 8.969 kB / 2.326 GB, free: 299 MB / 1.167 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003036/4.521, allocations: 376.8 kB / 2.326 GB, free: 298.9 MB / 1.167 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0101/4.531, allocations: 2.585 MB / 2.329 GB, free: 298.5 MB / 1.167 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001568/4.532, allocations: 87.27 kB / 2.329 GB, free: 298.5 MB / 1.167 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002905/4.532, allocations: 104.8 kB / 2.329 GB, free: 298.5 MB / 1.167 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003447/4.535, allocations: 1.272 MB / 2.33 GB, free: 298.2 MB / 1.167 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.12e-05/4.535, allocations: 6.281 kB / 2.33 GB, free: 298.2 MB / 1.167 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001366/4.535, allocations: 38.28 kB / 2.33 GB, free: 298.2 MB / 1.167 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.135e-05/4.536, allocations: 23.59 kB / 2.33 GB, free: 298.2 MB / 1.167 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002961/4.539, allocations: 1.003 MB / 2.331 GB, free: 298.2 MB / 1.167 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.01291/4.551, allocations: 6.651 MB / 2.338 GB, free: 294.4 MB / 1.167 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001712/4.553, allocations: 0.6568 MB / 2.338 GB, free: 294.3 MB / 1.167 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.003947/4.557, allocations: 1.641 MB / 2.34 GB, free: 293.6 MB / 1.167 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0009194/4.558, allocations: 404.1 kB / 2.34 GB, free: 293.5 MB / 1.167 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001234/4.559, allocations: 131.4 kB / 2.34 GB, free: 293.5 MB / 1.167 GB
Notification: Performance of pre-optimization done (n=47): time 3.647e-06/4.559, allocations: 3.828 kB / 2.34 GB, free: 293.5 MB / 1.167 GB
Notification: Performance of matching and sorting (n=47): time 0.003784/4.563, allocations: 1.312 MB / 2.342 GB, free: 293.3 MB / 1.167 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.181e-05/4.563, allocations: 60.55 kB / 2.342 GB, free: 293.2 MB / 1.167 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003031/4.566, allocations: 1.573 MB / 2.343 GB, free: 293 MB / 1.167 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001277/4.566, allocations: 40.14 kB / 2.343 GB, free: 293 MB / 1.167 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008714/4.567, allocations: 0.8499 MB / 2.344 GB, free: 292.8 MB / 1.167 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001843/4.567, allocations: 167.8 kB / 2.344 GB, free: 292.7 MB / 1.167 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.52e-05/4.568, allocations: 13.28 kB / 2.344 GB, free: 292.7 MB / 1.167 GB
Notification: Performance of setup shared object (initialization): time 9.709e-05/4.568, allocations: 347 kB / 2.345 GB, free: 292.4 MB / 1.167 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007627/4.568, allocations: 301.3 kB / 2.345 GB, free: 292.4 MB / 1.167 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009903/4.569, allocations: 0.7493 MB / 2.346 GB, free: 291.8 MB / 1.167 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0008857/4.57, allocations: 442.8 kB / 2.346 GB, free: 291.8 MB / 1.167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.468e-06/4.57, allocations: 2.844 kB / 2.346 GB, free: 291.8 MB / 1.167 GB
Notification: Performance of matching and sorting (n=99) (initialization): time 0.003034/4.573, allocations: 1.255 MB / 2.347 GB, free: 291.6 MB / 1.167 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001155/4.575, allocations: 4.128 MB / 2.351 GB, free: 287.3 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.851e-05/4.575, allocations: 9.562 kB / 2.351 GB, free: 287.3 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004798/4.575, allocations: 146 kB / 2.352 GB, free: 287.3 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008218/4.576, allocations: 186.3 kB / 2.352 GB, free: 287.3 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0007665/4.577, allocations: 0.7598 MB / 2.352 GB, free: 286.8 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001995/4.579, allocations: 115.6 kB / 2.353 GB, free: 286.8 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001188/4.579, allocations: 50.53 kB / 2.353 GB, free: 286.8 MB / 1.167 GB
Notification: Performance of prepare postOptimizeDAE: time 1.51e-05/4.579, allocations: 3.75 kB / 2.353 GB, free: 286.8 MB / 1.167 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002559/4.581, allocations: 1.111 MB / 2.354 GB, free: 286.6 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.181e-05/4.581, allocations: 9.844 kB / 2.354 GB, free: 286.6 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003426/4.582, allocations: 125 kB / 2.354 GB, free: 286.6 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007509/4.583, allocations: 171.6 kB / 2.354 GB, free: 286.6 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002657/4.585, allocations: 2.145 MB / 2.356 GB, free: 285.3 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00201/4.587, allocations: 118.8 kB / 2.356 GB, free: 285.3 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.589e-05/4.587, allocations: 42.25 kB / 2.356 GB, free: 285.3 MB / 1.167 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 45
 * Number of states: 0 ()
 * Number of discrete variables: 7 ($whenCondition1,$PRE.pulse.T_start,pulse.T_start,$PRE.pulse.count,pulse.count,from_dp.y,mergeDp.y)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (85):
 * Single equations (assignments): 82
 * Array equations: 1
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {1 12}
Notification: Performance of prepare postOptimizeDAE: time 0.00149/4.589, allocations: 0.5787 MB / 2.357 GB, free: 285.1 MB / 1.167 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000485/4.589, allocations: 179.9 kB / 2.357 GB, free: 285 MB / 1.167 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002386/4.592, allocations: 1.047 MB / 2.358 GB, free: 284.9 MB / 1.167 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.507e-05/4.592, allocations: 7.75 kB / 2.358 GB, free: 284.9 MB / 1.167 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.036e-06/4.592, allocations: 3.281 kB / 2.358 GB, free: 284.9 MB / 1.167 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001717/4.594, allocations: 0.9947 MB / 2.359 GB, free: 284.5 MB / 1.167 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004965/4.598, allocations: 2.222 MB / 2.361 GB, free: 284.1 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.992e-06/4.599, allocations: 6 kB / 2.361 GB, free: 284.1 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005052/4.599, allocations: 98.97 kB / 2.361 GB, free: 284.1 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0002865/4.599, allocations: 120.8 kB / 2.361 GB, free: 284.1 MB / 1.167 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.711e-05/4.599, allocations: 16.03 kB / 2.361 GB, free: 284.1 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0005368/4.6, allocations: 0.7389 MB / 2.362 GB, free: 283.6 MB / 1.167 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.324e-06/4.6, allocations: 0 / 2.362 GB, free: 283.6 MB / 1.167 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002756/4.603, allocations: 1.471 MB / 2.364 GB, free: 283.2 MB / 1.167 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001214/4.604, allocations: 317.5 kB / 2.364 GB, free: 283 MB / 1.167 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002758/4.604, allocations: 15.05 kB / 2.364 GB, free: 283 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001059/4.605, allocations: 83.97 kB / 2.364 GB, free: 283 MB / 1.167 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000124/4.605, allocations: 50.19 kB / 2.364 GB, free: 283 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.011e-05/4.606, allocations: 22.69 kB / 2.364 GB, free: 283 MB / 1.167 GB
Notification: Performance of sorting global known variables: time 0.002168/4.608, allocations: 1.233 MB / 2.365 GB, free: 282.9 MB / 1.167 GB
Notification: Performance of sort global known variables: time 2.21e-07/4.608, allocations: 0 / 2.365 GB, free: 282.9 MB / 1.167 GB
Notification: Performance of remove unused functions: time 0.004783/4.612, allocations: 0.8738 MB / 2.366 GB, free: 282.9 MB / 1.167 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 1 (pump.vol.dynBal.U)
 * Number of discrete variables: 3 ($whenCondition1,pulse.T_start,pulse.count)
 * Number of discrete states: 1 (pulse.count)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (41):
 * Single equations (assignments): 35
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 3
 * When equations: 2
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {1 12}
Notification: Performance of Backend phase and start with SimCode phase: time 0.002534/4.615, allocations: 0.8035 MB / 2.367 GB, free: 282.7 MB / 1.167 GB
Notification: Performance of simCode: created initialization part: time 0.004106/4.619, allocations: 1.512 MB / 2.368 GB, free: 282.5 MB / 1.167 GB
Notification: Performance of simCode: created event and clocks part: time 6.843e-06/4.619, allocations: 3.438 kB / 2.368 GB, free: 282.5 MB / 1.167 GB
Notification: Performance of simCode: created simulation system equations: time 0.001099/4.62, allocations: 356.1 kB / 2.369 GB, free: 282.5 MB / 1.167 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004976/4.625, allocations: 0.7436 MB / 2.369 GB, free: 282.4 MB / 1.167 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008534/4.634, allocations: 3.47 MB / 2.373 GB, free: 281 MB / 1.167 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001773/4.636, allocations: 1.527 MB / 2.374 GB, free: 280.3 MB / 1.167 GB
Notification: Performance of simCode: alias equations: time 0.002428/4.638, allocations: 441.3 kB / 2.375 GB, free: 280.3 MB / 1.167 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003386/4.639, allocations: 130.6 kB / 2.375 GB, free: 280.3 MB / 1.167 GB
Notification: Performance of SimCode: time 1.453e-06/4.639, allocations: 2.812 kB / 2.375 GB, free: 280.3 MB / 1.167 GB
Notification: Performance of Templates: time 0.05573/4.694, allocations: 33.66 MB / 2.408 GB, free: 279.5 MB / 1.167 GB
make -j1 -f Buildings_9_Buildings.Fluid.Examples.Performance.Example3.makefile
(rm -f Buildings_9_Buildings.Fluid.Examples.Performance.Example3.pipe ; mkfifo Buildings_9_Buildings.Fluid.Examples.Performance.Example3.pipe ; head -c 1048576 < Buildings_9_Buildings.Fluid.Examples.Performance.Example3.pipe >> ../files/Buildings_9_Buildings.Fluid.Examples.Performance.Example3.sim & ./Buildings_9_Buildings.Fluid.Examples.Performance.Example3  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Fluid.Examples.Performance.Example3.pipe 2>&1)
diffSimulationResults("Buildings_9_Buildings.Fluid.Examples.Performance.Example3_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_9.1.x/Buildings_Fluid_Examples_Performance_Example3.csv","../files/Buildings_9_Buildings.Fluid.Examples.Performance.Example3.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches