Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001853/0.001853, allocations: 92.72 kB / 20.21 MB, free: 4.215 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.002399/0.002399, allocations: 161.7 kB / 23.52 MB, free: 0.9062 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.621/1.621, allocations: 177.1 MB / 203.9 MB, free: 5.59 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 0.9657/0.9657, allocations: 115 MB / 375.3 MB, free: 7.562 MB / 346.7 MB
"
[Timeout remaining time 179]
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow")
translateModel(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.973e-06/1.973e-06, allocations: 0 / 480.8 MB, free: 11.91 MB / 410.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.548e-05/2.745e-05, allocations: 4.531 kB / 480.8 MB, free: 11.91 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow): time 0.05294/0.05297, allocations: 45.51 MB / 0.514 GB, free: 10.66 MB / 442.7 MB
Notification: Performance of NFInst.instExpressions: time 0.02335/0.07632, allocations: 11.86 MB / 0.5255 GB, free: 14.98 MB / 458.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003304/0.07962, allocations: 111.1 kB / 0.5257 GB, free: 14.88 MB / 458.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002873/0.08249, allocations: 0.768 MB / 0.5264 GB, free: 14.1 MB / 458.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.007285/0.08978, allocations: 1.981 MB / 0.5283 GB, free: 12.11 MB / 458.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004459/0.09424, allocations: 1.416 MB / 0.5297 GB, free: 10.7 MB / 458.7 MB
Notification: Performance of NFFlatten.flatten: time 0.005272/0.09951, allocations: 3.254 MB / 0.5329 GB, free: 7.43 MB / 458.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001661/0.1012, allocations: 0.9443 MB / 0.5338 GB, free: 6.449 MB / 458.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004755/0.1059, allocations: 1.924 MB / 0.5357 GB, free: 4.551 MB / 458.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00136/0.1073, allocations: 0.7941 MB / 0.5365 GB, free: 3.754 MB / 458.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001828/0.1075, allocations: 96 kB / 0.5366 GB, free: 3.66 MB / 458.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002135/0.1096, allocations: 0.8924 MB / 0.5374 GB, free: 2.766 MB / 458.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003811/0.11, allocations: 318.5 kB / 0.5377 GB, free: 2.453 MB / 458.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007626/0.1107, allocations: 458.8 kB / 0.5382 GB, free: 2.004 MB / 458.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.003612/0.1144, allocations: 2.029 MB / 0.5402 GB, free: 15.97 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.04e-06/0.1144, allocations: 0 / 0.5402 GB, free: 15.97 MB / 474.7 MB
Notification: Performance of FrontEnd: time 1.332e-06/0.1144, allocations: 0 / 0.5402 GB, free: 15.97 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 2.248e-05/0.1144, allocations: 4 kB / 0.5402 GB, free: 15.96 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 213
 * Number of variables: 213
Notification: Performance of Generate backend data structure: time 0.003047/0.1174, allocations: 1.651 MB / 0.5418 GB, free: 14.27 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.194e-05/0.1175, allocations: 12.03 kB / 0.5418 GB, free: 14.26 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0009499/0.1184, allocations: 287.9 kB / 0.5421 GB, free: 13.98 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002368/0.1208, allocations: 1.106 MB / 0.5431 GB, free: 12.83 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.452e-05/0.1209, allocations: 70.12 kB / 0.5432 GB, free: 12.76 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001356/0.121, allocations: 83.98 kB / 0.5433 GB, free: 12.68 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002191/0.1232, allocations: 1.129 MB / 0.5444 GB, free: 11.44 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.436e-05/0.1232, allocations: 3.938 kB / 0.5444 GB, free: 11.43 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.511e-05/0.1233, allocations: 40 kB / 0.5444 GB, free: 11.39 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.798e-05/0.1234, allocations: 20 kB / 0.5445 GB, free: 11.38 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001755/0.1251, allocations: 0.6477 MB / 0.5451 GB, free: 10.72 MB / 474.7 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.00581/0.1309, allocations: 3.804 MB / 0.5488 GB, free: 6.797 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001266/0.1322, allocations: 378.6 kB / 0.5492 GB, free: 6.418 MB / 474.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0005756/0.1328, allocations: 212.3 kB / 0.5494 GB, free: 6.211 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004321/0.1332, allocations: 107.9 kB / 0.5495 GB, free: 6.105 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.68e-05/0.1332, allocations: 34.56 kB / 0.5495 GB, free: 6.062 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=29): time 2.174e-06/0.1332, allocations: 4 kB / 0.5495 GB, free: 6.059 MB / 474.7 MB
Notification: Performance of matching and sorting (n=29): time 0.003011/0.1362, allocations: 0.8481 MB / 0.5503 GB, free: 5.195 MB / 474.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.451e-05/0.1363, allocations: 55.41 kB / 0.5504 GB, free: 5.129 MB / 474.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001251/0.1375, allocations: 0.8751 MB / 0.5512 GB, free: 4.234 MB / 474.7 MB
Notification: Performance of collectPreVariables (initialization): time 6.512e-05/0.1376, allocations: 33.7 kB / 0.5513 GB, free: 4.195 MB / 474.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0005582/0.1382, allocations: 0.5973 MB / 0.5519 GB, free: 3.59 MB / 474.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001208/0.1383, allocations: 103.1 kB / 0.552 GB, free: 3.488 MB / 474.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001907/0.1385, allocations: 106.1 kB / 0.5521 GB, free: 3.375 MB / 474.7 MB
Notification: Performance of setup shared object (initialization): time 0.0001923/0.1387, allocations: 329.9 kB / 0.5524 GB, free: 3.047 MB / 474.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005808/0.1393, allocations: 204.8 kB / 0.5526 GB, free: 2.848 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009023/0.1402, allocations: 0.5429 MB / 0.5531 GB, free: 2.184 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001344/0.1415, allocations: 0.6037 MB / 0.5537 GB, free: 1.492 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.108e-06/0.1415, allocations: 0 / 0.5537 GB, free: 1.492 MB / 474.7 MB
Notification: Performance of matching and sorting (n=60) (initialization): time 0.001879/0.1434, allocations: 0.7682 MB / 0.5544 GB, free: 0.707 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.028e-05/0.1434, allocations: 8.844 kB / 0.5544 GB, free: 0.6992 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.54e-05/0.1434, allocations: 8 kB / 0.5544 GB, free: 0.6914 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007335/0.1442, allocations: 162.4 kB / 0.5546 GB, free: 0.5234 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006522/0.1448, allocations: 147.8 kB / 0.5547 GB, free: 388 kB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001612/0.1464, allocations: 1.716 MB / 0.5564 GB, free: 14.59 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006599/0.1471, allocations: 87.58 kB / 0.5565 GB, free: 14.5 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.493e-05/0.1472, allocations: 36.12 kB / 0.5565 GB, free: 14.46 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 29
 * 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 (52):
 * Single equations (assignments): 49
 * Array equations: 0
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(1,2), (1,3), (1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.0004869/0.1476, allocations: 261.7 kB / 0.5568 GB, free: 14.2 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002077/0.1478, allocations: 115.3 kB / 0.5569 GB, free: 14.09 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001985/0.1498, allocations: 0.7105 MB / 0.5576 GB, free: 13.37 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.369e-05/0.1498, allocations: 7.984 kB / 0.5576 GB, free: 13.36 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.749e-06/0.1499, allocations: 4 kB / 0.5576 GB, free: 13.36 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.757e-06/0.1499, allocations: 3.938 kB / 0.5576 GB, free: 13.35 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003049/0.1529, allocations: 1.443 MB / 0.559 GB, free: 11.85 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.718e-06/0.1529, allocations: 4 kB / 0.559 GB, free: 11.84 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005271/0.1534, allocations: 83.84 kB / 0.5591 GB, free: 11.76 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.000622/0.1541, allocations: 150.5 kB / 0.5592 GB, free: 11.61 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.148e-05/0.1541, allocations: 8 kB / 0.5593 GB, free: 11.6 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001478/0.1556, allocations: 1.724 MB / 0.5609 GB, free: 9.797 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.92e-06/0.1556, allocations: 3.938 kB / 0.5609 GB, free: 9.793 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001825/0.1574, allocations: 0.7855 MB / 0.5617 GB, free: 8.965 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004015/0.1578, allocations: 163.2 kB / 0.5619 GB, free: 8.805 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.952e-05/0.1579, allocations: 8 kB / 0.5619 GB, free: 8.797 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006579/0.1586, allocations: 47.95 kB / 0.5619 GB, free: 8.75 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 9.655e-05/0.1587, allocations: 31.88 kB / 0.5619 GB, free: 8.719 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.624e-05/0.1587, allocations: 23.98 kB / 0.562 GB, free: 8.695 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.001008/0.1597, allocations: 0.5688 MB / 0.5625 GB, free: 8.117 MB / 490.7 MB
Notification: Performance of sort global known variables: time 1.01e-07/0.1597, allocations: 3.938 kB / 0.5625 GB, free: 8.113 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.001242/0.161, allocations: 402.8 kB / 0.5629 GB, free: 7.719 MB / 490.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 7
 * 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 (26):
 * Single equations (assignments): 18
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 5
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(1,3), (1,2), (1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0007896/0.1618, allocations: 395.8 kB / 0.5633 GB, free: 7.328 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.001692/0.1635, allocations: 0.5327 MB / 0.5638 GB, free: 6.797 MB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 2.905e-06/0.1635, allocations: 0 / 0.5638 GB, free: 6.797 MB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.0008587/0.1643, allocations: 284.3 kB / 0.5641 GB, free: 6.52 MB / 490.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001992/0.1663, allocations: 450.3 kB / 0.5645 GB, free: 6.105 MB / 490.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003371/0.1697, allocations: 1.817 MB / 0.5663 GB, free: 4.23 MB / 490.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006051/0.1703, allocations: 0.5129 MB / 0.5668 GB, free: 3.68 MB / 490.7 MB
Notification: Performance of simCode: alias equations: time 0.0005823/0.1709, allocations: 165.2 kB / 0.5669 GB, free: 3.52 MB / 490.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001568/0.171, allocations: 64.5 kB / 0.567 GB, free: 3.457 MB / 490.7 MB
Notification: Performance of SimCode: time 7.81e-07/0.171, allocations: 0 / 0.567 GB, free: 3.457 MB / 490.7 MB
Notification: Performance of Templates: time 0.03526/0.2063, allocations: 18.53 MB / 0.5851 GB, free: 1.352 MB / 0.4948 GB
"
[Timeout remaining time 660]
make -j1 -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.makefile [Timeout 660]
(rm -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.sim & ./IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MixingVolumeZeroFlow.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.47404020302929]