Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.conf.json
loadFile("/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.0.0/package.mo", uses=false)
loadFile("/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Using package Buildings with version 8.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.0.0/package.mo)
Running command: translateModel(Buildings.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4")
translateModel(Buildings.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 8.0.0/package.mo): time 2.513/2.513, allocations: 287.5 MB / 302.4 MB, free: 8.273 MB / 234.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.001536/0.001536, allocations: 179.1 kB / 371 MB, free: 10.79 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 2/2, allocations: 205.4 MB / 0.6297 GB, free: 10.5 MB / 458.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001213/0.001213, allocations: 96.73 kB / 0.743 GB, free: 6.504 MB / 0.5573 GB
Notification: Performance of FrontEnd - loaded program: time 0.0005232/0.0005233, allocations: 15.94 kB / 0.8658 GB, free: 141.1 MB / 0.6198 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2149/0.2155, allocations: 115.3 MB / 0.9784 GB, free: 28.29 MB / 0.6198 GB
Notification: Performance of FrontEnd - scodeFlatten: time 6.578/6.794, allocations: 2.105 GB / 3.084 GB, free: 164.2 MB / 0.948 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003155/6.794, allocations: 96.2 kB / 3.084 GB, free: 164.2 MB / 0.948 GB
Notification: Performance of FrontEnd - DAE generated: time 7.619/14.41, allocations: 1.453 GB / 4.537 GB, free: 136.7 MB / 0.9636 GB
Notification: Performance of FrontEnd: time 4.849e-06/14.41, allocations: 0 / 4.537 GB, free: 136.7 MB / 0.9636 GB
Notification: Performance of Transformations before backend: time 0.0002022/14.41, allocations: 44.8 kB / 4.537 GB, free: 136.7 MB / 0.9636 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.008585/14.42, allocations: 2.143 MB / 4.539 GB, free: 135.4 MB / 0.9636 GB
Notification: Performance of prepare preOptimizeDAE: time 4.326e-05/14.42, allocations: 7.531 kB / 4.539 GB, free: 135.4 MB / 0.9636 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005054/14.43, allocations: 0.5076 MB / 4.539 GB, free: 135.4 MB / 0.9636 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004899/14.43, allocations: 1.24 MB / 4.54 GB, free: 134.7 MB / 0.9636 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00184/14.43, allocations: 275.9 kB / 4.541 GB, free: 134.6 MB / 0.9636 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004313/14.43, allocations: 98.81 kB / 4.541 GB, free: 134.6 MB / 0.9636 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004265/14.44, allocations: 1.325 MB / 4.542 GB, free: 133.7 MB / 0.9636 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.6e-05/14.44, allocations: 3.938 kB / 4.542 GB, free: 133.7 MB / 0.9636 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001784/14.44, allocations: 43.44 kB / 4.542 GB, free: 133.7 MB / 0.9636 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.26e-05/14.44, allocations: 43.42 kB / 4.542 GB, free: 133.7 MB / 0.9636 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00449/14.44, allocations: 1.073 MB / 4.543 GB, free: 133.2 MB / 0.9636 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.01213/14.46, allocations: 6.655 MB / 4.55 GB, free: 127.3 MB / 0.9636 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00364/14.46, allocations: 0.6311 MB / 4.55 GB, free: 127.1 MB / 0.9636 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005233/14.46, allocations: 285.9 kB / 4.551 GB, free: 126.8 MB / 0.9636 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.611e-05/14.46, allocations: 34.78 kB / 4.551 GB, free: 126.7 MB / 0.9636 GB
Notification: Performance of pre-optimization done (n=33): time 3.456e-06/14.46, allocations: 2.094 kB / 4.551 GB, free: 126.7 MB / 0.9636 GB
Notification: Performance of matching and sorting (n=33): time 0.004425/14.46, allocations: 0.8921 MB / 4.552 GB, free: 126.3 MB / 0.9636 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.743e-05/14.46, allocations: 58.72 kB / 4.552 GB, free: 126.2 MB / 0.9636 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002813/14.47, allocations: 1.195 MB / 4.553 GB, free: 125.6 MB / 0.9636 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001899/14.47, allocations: 32.14 kB / 4.553 GB, free: 125.6 MB / 0.9636 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006411/14.47, allocations: 0.6844 MB / 4.553 GB, free: 125 MB / 0.9636 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001638/14.47, allocations: 121 kB / 4.554 GB, free: 124.9 MB / 0.9636 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 7.896e-05/14.47, allocations: 15.28 kB / 4.554 GB, free: 124.9 MB / 0.9636 GB
Notification: Performance of setup shared object (initialization): time 8.541e-05/14.47, allocations: 337.1 kB / 4.554 GB, free: 124.5 MB / 0.9636 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001563/14.47, allocations: 322.3 kB / 4.554 GB, free: 124.4 MB / 0.9636 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001768/14.47, allocations: 0.6746 MB / 4.555 GB, free: 123.8 MB / 0.9636 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001728/14.47, allocations: 405.5 kB / 4.555 GB, free: 123.6 MB / 0.9636 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.658e-06/14.47, allocations: 3.125 kB / 4.555 GB, free: 123.6 MB / 0.9636 GB
Notification: Performance of matching and sorting (n=81) (initialization): time 0.004288/14.48, allocations: 1.043 MB / 4.556 GB, free: 123.2 MB / 0.9636 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0008541/14.48, allocations: 3.824 MB / 4.56 GB, free: 119.2 MB / 0.9636 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.547e-05/14.48, allocations: 8.625 kB / 4.56 GB, free: 119.2 MB / 0.9636 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.095e-05/14.48, allocations: 14.66 kB / 4.56 GB, free: 119.2 MB / 0.9636 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001727/14.48, allocations: 209.2 kB / 4.56 GB, free: 119.1 MB / 0.9636 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.661e-05/14.48, allocations: 21.08 kB / 4.56 GB, free: 119.1 MB / 0.9636 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00269/14.48, allocations: 228.1 kB / 4.561 GB, free: 119 MB / 0.9636 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001921/14.48, allocations: 39.95 kB / 4.561 GB, free: 119 MB / 0.9636 GB
Notification: Performance of prepare postOptimizeDAE: time 1.413e-05/14.48, allocations: 7.219 kB / 4.561 GB, free: 119 MB / 0.9636 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003668/14.49, allocations: 0.904 MB / 4.561 GB, free: 118.6 MB / 0.9636 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.192e-05/14.49, allocations: 10.84 kB / 4.561 GB, free: 118.6 MB / 0.9636 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.008e-05/14.49, allocations: 13.41 kB / 4.561 GB, free: 118.6 MB / 0.9636 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001638/14.49, allocations: 209.6 kB / 4.562 GB, free: 118.6 MB / 0.9636 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.284e-05/14.49, allocations: 19.28 kB / 4.562 GB, free: 118.6 MB / 0.9636 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002466/14.49, allocations: 206.3 kB / 4.562 GB, free: 118.5 MB / 0.9636 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001532/14.49, allocations: 41.14 kB / 4.562 GB, free: 118.5 MB / 0.9636 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.001064/14.49, allocations: 418.4 kB / 4.562 GB, free: 118.1 MB / 0.9636 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004465/14.49, allocations: 132 kB / 4.562 GB, free: 118.1 MB / 0.9636 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002967/14.5, allocations: 0.6961 MB / 4.563 GB, free: 117.7 MB / 0.9636 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.119e-05/14.5, allocations: 3.875 kB / 4.563 GB, free: 117.7 MB / 0.9636 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.336e-06/14.5, allocations: 0 / 4.563 GB, free: 117.7 MB / 0.9636 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.185e-06/14.5, allocations: 0 / 4.563 GB, free: 117.7 MB / 0.9636 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.006408/14.5, allocations: 1.928 MB / 4.565 GB, free: 116.4 MB / 0.9636 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.676e-06/14.5, allocations: 6.906 kB / 4.565 GB, free: 116.4 MB / 0.9636 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009574/14.5, allocations: 101.8 kB / 4.565 GB, free: 116.3 MB / 0.9636 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.507e-05/14.5, allocations: 8.094 kB / 4.565 GB, free: 116.3 MB / 0.9636 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.255e-05/14.5, allocations: 19.7 kB / 4.565 GB, free: 116.3 MB / 0.9636 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 8.687e-06/14.5, allocations: 11 kB / 4.565 GB, free: 116.3 MB / 0.9636 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.181e-06/14.5, allocations: 9.344 kB / 4.565 GB, free: 116.3 MB / 0.9636 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002883/14.51, allocations: 1.056 MB / 4.566 GB, free: 115.3 MB / 0.9636 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008372/14.51, allocations: 283.2 kB / 4.566 GB, free: 115.1 MB / 0.9636 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000395/14.51, allocations: 23.83 kB / 4.566 GB, free: 115 MB / 0.9636 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00197/14.51, allocations: 115.8 kB / 4.567 GB, free: 114.9 MB / 0.9636 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001447/14.51, allocations: 31.89 kB / 4.567 GB, free: 114.9 MB / 0.9636 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.347e-05/14.51, allocations: 19.94 kB / 4.567 GB, free: 114.9 MB / 0.9636 GB
Notification: Performance of sorting global known variables: time 0.002264/14.51, allocations: 0.9278 MB / 4.568 GB, free: 113.9 MB / 0.9636 GB
Notification: Performance of sort global known variables: time 5.61e-07/14.51, allocations: 0 / 4.568 GB, free: 113.9 MB / 0.9636 GB
Notification: Performance of remove unused functions: time 0.004096/14.52, allocations: 0.8772 MB / 4.568 GB, free: 113.1 MB / 0.9636 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.005679/14.52, allocations: 3.124 MB / 4.571 GB, free: 110.1 MB / 0.9636 GB
Notification: Performance of simCode: created initialization part: time 0.005107/14.53, allocations: 1.157 MB / 4.573 GB, free: 108.9 MB / 0.9636 GB
Notification: Performance of simCode: created event and clocks part: time 1.068e-05/14.53, allocations: 3.938 kB / 4.573 GB, free: 108.9 MB / 0.9636 GB
Notification: Performance of simCode: created simulation system equations: time 0.001246/14.53, allocations: 272.8 kB / 4.573 GB, free: 108.6 MB / 0.9636 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005391/14.53, allocations: 0.6124 MB / 4.573 GB, free: 108 MB / 0.9636 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008013/14.54, allocations: 2.645 MB / 4.576 GB, free: 105.3 MB / 0.9636 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008036/14.54, allocations: 1.213 MB / 4.577 GB, free: 104.1 MB / 0.9636 GB
Notification: Performance of simCode: alias equations: time 0.002775/14.55, allocations: 360.2 kB / 4.578 GB, free: 103.7 MB / 0.9636 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004312/14.55, allocations: 95.41 kB / 4.578 GB, free: 103.6 MB / 0.9636 GB
Notification: Performance of SimCode: time 1.754e-06/14.55, allocations: 0 / 4.578 GB, free: 103.6 MB / 0.9636 GB
Notification: Performance of Templates: time 0.1197/14.67, allocations: 54.92 MB / 4.631 GB, free: 49.49 MB / 0.9636 GB
make -j1 -f Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.makefile
(rm -f Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.pipe ; mkfifo Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.pipe ; head -c 1048576 < Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.pipe >> ../files/Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.sim & ./Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4 -abortSlowSimulation -alarm=480 -lv LOG_STATS > Buildings_8.0.0_Buildings.Fluid.Examples.Performance.Example4.pipe 2>&1)