Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_master_Buildings.Fluid.MixingVolumes.Validation.CoupledVolumesWater.conf.json
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 9.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo)
Running command: translateModel(Buildings.Fluid.MixingVolumes.Validation.CoupledVolumesWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_master_Buildings.Fluid.MixingVolumes.Validation.CoupledVolumesWater")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo): time 4.862/4.862, allocations: 0.5623 GB / 0.5776 GB, free: 3.336 MB / 474.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002477/0.002477, allocations: 351.9 kB / 0.6447 GB, free: 185.7 MB / 474.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 4.124/4.124, allocations: 410.7 MB / 1.113 GB, free: 5.988 MB / 0.6042 GB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001849/0.00185, allocations: 179.5 kB / 1.226 GB, free: 6.184 MB / 0.7136 GB
Notification: Performance of FrontEnd - loaded program: time 0.0005056/0.0005058, allocations: 12 kB / 1.349 GB, free: 8.32 MB / 0.8386 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7162/0.7167, allocations: 115.3 MB / 1.461 GB, free: 284.4 MB / 0.8542 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MixingVolumes.Validation.CoupledVolumesWater): time 0.03053/0.7473, allocations: 24.12 MB / 1.485 GB, free: 260.3 MB / 0.8542 GB
Notification: Performance of NFInst.instExpressions: time 0.01205/0.7594, allocations: 6.488 MB / 1.491 GB, free: 253.8 MB / 0.8542 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001137/0.7606, allocations: 31.75 kB / 1.491 GB, free: 253.8 MB / 0.8542 GB
Notification: Performance of NFTyping.typeComponents: time 0.001542/0.7622, allocations: 361.7 kB / 1.492 GB, free: 253.4 MB / 0.8542 GB
Notification: Performance of NFTyping.typeBindings: time 0.004742/0.7669, allocations: 1.213 MB / 1.493 GB, free: 252.2 MB / 0.8542 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004287/0.7712, allocations: 1.061 MB / 1.494 GB, free: 251.2 MB / 0.8542 GB
Notification: Performance of NFFlatten.flatten: time 0.003424/0.7747, allocations: 2.365 MB / 1.496 GB, free: 248.8 MB / 0.8542 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008473/0.7756, allocations: 460.6 kB / 1.497 GB, free: 248.4 MB / 0.8542 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001108/0.7767, allocations: 0.5689 MB / 1.497 GB, free: 247.8 MB / 0.8542 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007877/0.7775, allocations: 0.4954 MB / 1.498 GB, free: 247.3 MB / 0.8542 GB
Notification: Performance of NFPackage.collectConstants: time 8.644e-05/0.7776, allocations: 20 kB / 1.498 GB, free: 247.3 MB / 0.8542 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001433/0.7791, allocations: 464.1 kB / 1.498 GB, free: 246.9 MB / 0.8542 GB
Notification: Performance of NFScalarize.scalarize: time 0.000165/0.7792, allocations: 119.4 kB / 1.498 GB, free: 246.7 MB / 0.8542 GB
Notification: Performance of NFVerifyModel.verify: time 0.0001943/0.7795, allocations: 170.7 kB / 1.498 GB, free: 246.6 MB / 0.8542 GB
Notification: Performance of NFConvertDAE.convert: time 0.001179/0.7806, allocations: 0.8066 MB / 1.499 GB, free: 245.8 MB / 0.8542 GB
Notification: Performance of FrontEnd - DAE generated: time 5e-06/0.7807, allocations: 0 / 1.499 GB, free: 245.8 MB / 0.8542 GB
Notification: Performance of FrontEnd: time 1.613e-06/0.7807, allocations: 0 / 1.499 GB, free: 245.8 MB / 0.8542 GB
Notification: Performance of Transformations before backend: time 9.097e-06/0.7807, allocations: 0.6875 kB / 1.499 GB, free: 245.8 MB / 0.8542 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 110
 * Number of variables: 110
Notification: Performance of Generate backend data structure: time 0.001525/0.7822, allocations: 0.9014 MB / 1.5 GB, free: 244.8 MB / 0.8542 GB
Notification: Performance of prepare preOptimizeDAE: time 4.231e-05/0.7823, allocations: 11.62 kB / 1.5 GB, free: 244.8 MB / 0.8542 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0003427/0.7826, allocations: 92.58 kB / 1.5 GB, free: 244.7 MB / 0.8542 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00147/0.7841, allocations: 0.6988 MB / 1.501 GB, free: 244 MB / 0.8542 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.618e-05/0.7842, allocations: 42.05 kB / 1.501 GB, free: 243.9 MB / 0.8542 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 9.148e-05/0.7843, allocations: 48 kB / 1.501 GB, free: 243.9 MB / 0.8542 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0009989/0.7853, allocations: 0.5014 MB / 1.501 GB, free: 243.4 MB / 0.8542 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.618e-05/0.7853, allocations: 0 / 1.501 GB, free: 243.4 MB / 0.8542 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.243e-05/0.7854, allocations: 16 kB / 1.501 GB, free: 243.4 MB / 0.8542 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.173e-05/0.7855, allocations: 16 kB / 1.501 GB, free: 243.3 MB / 0.8542 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0007052/0.7862, allocations: 315.7 kB / 1.502 GB, free: 243 MB / 0.8542 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.003355/0.7895, allocations: 2.746 MB / 1.504 GB, free: 240.1 MB / 0.8542 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0004083/0.79, allocations: 187.8 kB / 1.505 GB, free: 239.9 MB / 0.8542 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001987/0.7902, allocations: 102 kB / 1.505 GB, free: 239.8 MB / 0.8542 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003429/0.7905, allocations: 340.6 kB / 1.505 GB, free: 239.4 MB / 0.8542 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.509e-05/0.7906, allocations: 45.31 kB / 1.505 GB, free: 239.3 MB / 0.8542 GB
Notification: Performance of pre-optimization done (n=22): time 2.645e-06/0.7906, allocations: 0 / 1.505 GB, free: 239.3 MB / 0.8542 GB
Notification: Performance of matching and sorting (n=22): time 0.0007485/0.7914, allocations: 302 kB / 1.505 GB, free: 239 MB / 0.8542 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.382e-05/0.7914, allocations: 55.41 kB / 1.505 GB, free: 238.9 MB / 0.8542 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0006927/0.7921, allocations: 0.5029 MB / 1.506 GB, free: 238.4 MB / 0.8542 GB
Notification: Performance of collectPreVariables (initialization): time 4.313e-05/0.7921, allocations: 29.7 kB / 1.506 GB, free: 238.4 MB / 0.8542 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002285/0.7924, allocations: 341.9 kB / 1.506 GB, free: 238.1 MB / 0.8542 GB
Notification: Performance of collectInitialBindings (initialization): time 8.824e-05/0.7925, allocations: 78.84 kB / 1.506 GB, free: 238 MB / 0.8542 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 2.046e-05/0.7925, allocations: 8 kB / 1.506 GB, free: 238 MB / 0.8542 GB
Notification: Performance of setup shared object (initialization): time 9.61e-05/0.7926, allocations: 362.8 kB / 1.507 GB, free: 237.6 MB / 0.8542 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001956/0.7928, allocations: 83.95 kB / 1.507 GB, free: 237.5 MB / 0.8542 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002829/0.7931, allocations: 230 kB / 1.507 GB, free: 237.2 MB / 0.8542 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0002761/0.7934, allocations: 135.5 kB / 1.507 GB, free: 237.1 MB / 0.8542 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.488e-06/0.7934, allocations: 0 / 1.507 GB, free: 237.1 MB / 0.8542 GB
Notification: Performance of matching and sorting (n=44) (initialization): time 0.0007635/0.7942, allocations: 387.9 kB / 1.507 GB, free: 236.7 MB / 0.8542 GB
Notification: Performance of prepare postOptimizeDAE: time 9.513e-05/0.7943, allocations: 215.2 kB / 1.508 GB, free: 236.5 MB / 0.8542 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.087e-05/0.7943, allocations: 7.984 kB / 1.508 GB, free: 236.5 MB / 0.8542 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.385e-05/0.7944, allocations: 8 kB / 1.508 GB, free: 236.5 MB / 0.8542 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004738/0.7949, allocations: 72.47 kB / 1.508 GB, free: 236.4 MB / 0.8542 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.815e-05/0.7949, allocations: 14.75 kB / 1.508 GB, free: 236.4 MB / 0.8542 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006792/0.7956, allocations: 60.34 kB / 1.508 GB, free: 236.3 MB / 0.8542 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.62e-05/0.7956, allocations: 20 kB / 1.508 GB, free: 236.3 MB / 0.8542 GB
Notification: Performance of prepare postOptimizeDAE: time 7.033e-06/0.7957, allocations: 5.469 kB / 1.508 GB, free: 236.3 MB / 0.8542 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.0006134/0.7963, allocations: 309.8 kB / 1.508 GB, free: 236 MB / 0.8542 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.523e-06/0.7963, allocations: 4 kB / 1.508 GB, free: 236 MB / 0.8542 GB
Notification: Performance of postOpt tearingSystem (initialization): time 8.987e-06/0.7963, allocations: 8.938 kB / 1.508 GB, free: 236 MB / 0.8542 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004538/0.7968, allocations: 79.89 kB / 1.508 GB, free: 235.9 MB / 0.8542 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 9.618e-06/0.7968, allocations: 8.844 kB / 1.508 GB, free: 235.9 MB / 0.8542 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006265/0.7974, allocations: 49.98 kB / 1.508 GB, free: 235.9 MB / 0.8542 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.231e-05/0.7975, allocations: 20.89 kB / 1.508 GB, free: 235.8 MB / 0.8542 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 19
 * 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 (44):
 * Single equations (assignments): 44
 * 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.0003481/0.7979, allocations: 277.9 kB / 1.509 GB, free: 235.6 MB / 0.8542 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001188/0.798, allocations: 55.34 kB / 1.509 GB, free: 235.5 MB / 0.8542 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006415/0.7986, allocations: 276.5 kB / 1.509 GB, free: 235.2 MB / 0.8542 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0004215/0.7991, allocations: 384.4 kB / 1.509 GB, free: 234.8 MB / 0.8542 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.018e-06/0.7991, allocations: 0.8438 kB / 1.509 GB, free: 234.8 MB / 0.8542 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002955/0.7994, allocations: 168.3 kB / 1.509 GB, free: 234.6 MB / 0.8542 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001005/0.7995, allocations: 13.39 kB / 1.509 GB, free: 234.6 MB / 0.8542 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006338/0.8002, allocations: 27.14 kB / 1.509 GB, free: 234.6 MB / 0.8542 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 4.411e-05/0.8002, allocations: 11.98 kB / 1.509 GB, free: 234.6 MB / 0.8542 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0007705/0.801, allocations: 332.8 kB / 1.51 GB, free: 234.2 MB / 0.8542 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.0007782/0.8018, allocations: 0.5439 MB / 1.51 GB, free: 233.7 MB / 0.8542 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0001737/0.802, allocations: 87.98 kB / 1.51 GB, free: 233.6 MB / 0.8542 GB
Notification: Performance of sorting global known variables: time 0.0005118/0.8025, allocations: 358.8 kB / 1.511 GB, free: 233.2 MB / 0.8542 GB
Notification: Performance of Backend: time 7.11e-07/0.8025, allocations: 0 / 1.511 GB, free: 233.2 MB / 0.8542 GB
Notification: Performance of simCode: created initialization part: time 0.001998/0.8045, allocations: 0.6084 MB / 1.511 GB, free: 232.7 MB / 0.8542 GB
Notification: Performance of SimCode: time 0.004236/0.8088, allocations: 1.983 MB / 1.513 GB, free: 230.7 MB / 0.8542 GB
Notification: Performance of Templates: time 0.029/0.8379, allocations: 11.8 MB / 1.525 GB, free: 219.2 MB / 0.8542 GB