Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.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/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.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: translateModel(IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater")
translateModel(IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.000803/0.000803, allocations: 99.05 kB / 16.02 MB, free: 80 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001301/0.001301, allocations: 181.4 kB / 16.92 MB, free: 6.777 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.394/1.394, allocations: 223.3 MB / 241 MB, free: 5.355 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.827/0.827, allocations: 123 MB / 415.1 MB, free: 11.14 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004157/0.0004159, allocations: 8 kB / 0.4933 GB, free: 13.96 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4428/0.4432, allocations: 79.28 MB / 0.5707 GB, free: 9.906 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater): time 0.02193/0.4652, allocations: 22.61 MB / 0.5928 GB, free: 10.58 MB / 446.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01068/0.476, allocations: 6.797 MB / 0.5995 GB, free: 5.75 MB / 446.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001219/0.4772, allocations: 25.81 kB / 0.5995 GB, free: 5.738 MB / 446.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001025/0.4782, allocations: 274.6 kB / 0.5997 GB, free: 5.578 MB / 446.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002997/0.4812, allocations: 0.8392 MB / 0.6006 GB, free: 5.09 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002199/0.4835, allocations: 0.6826 MB / 0.6012 GB, free: 4.738 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006114/0.4896, allocations: 4.671 MB / 0.6058 GB, free: 2.887 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001523/0.4911, allocations: 1.073 MB / 0.6068 GB, free: 2.301 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00208/0.4932, allocations: 0.9357 MB / 0.6078 GB, free: 1.816 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001533/0.4948, allocations: 0.9025 MB / 0.6086 GB, free: 1.293 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001187/0.4949, allocations: 36.69 kB / 0.6087 GB, free: 1.293 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001764/0.4967, allocations: 0.7057 MB / 0.6094 GB, free: 0.9062 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0002737/0.497, allocations: 222.4 kB / 0.6096 GB, free: 0.7539 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0007596/0.4977, allocations: 0.5765 MB / 0.6101 GB, free: 396 kB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.00235/0.5001, allocations: 1.809 MB / 0.6119 GB, free: 15.24 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.767e-06/0.5001, allocations: 0 / 0.6119 GB, free: 15.24 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.733e-06/0.5001, allocations: 0 / 0.6119 GB, free: 15.24 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 2.565e-05/0.5002, allocations: 0 / 0.6119 GB, free: 15.24 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 183
 * Number of variables: 183
Notification: Performance of Generate backend data structure: time 0.002132/0.5023, allocations: 1.311 MB / 0.6132 GB, free: 14.21 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.686e-05/0.5023, allocations: 10.09 kB / 0.6132 GB, free: 14.21 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004378/0.5028, allocations: 143.2 kB / 0.6133 GB, free: 14.14 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001953/0.5048, allocations: 1.198 MB / 0.6145 GB, free: 13.29 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.423e-05/0.5048, allocations: 57.31 kB / 0.6146 GB, free: 13.27 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001427/0.505, allocations: 75.34 kB / 0.6146 GB, free: 13.24 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001632/0.5066, allocations: 1.041 MB / 0.6156 GB, free: 12.59 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.411e-05/0.5066, allocations: 1.281 kB / 0.6156 GB, free: 12.59 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.268e-05/0.5067, allocations: 31.88 kB / 0.6157 GB, free: 12.57 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.104e-05/0.5068, allocations: 21.25 kB / 0.6157 GB, free: 12.57 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001214/0.508, allocations: 0.7705 MB / 0.6164 GB, free: 12.18 MB / 462.1 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.005196/0.5132, allocations: 4.619 MB / 0.621 GB, free: 8.176 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0005613/0.5138, allocations: 388.9 kB / 0.6213 GB, free: 7.984 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003546/0.5142, allocations: 265.1 kB / 0.6216 GB, free: 7.859 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003933/0.5146, allocations: 427.4 kB / 0.622 GB, free: 7.375 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.887e-05/0.5146, allocations: 45.8 kB / 0.622 GB, free: 7.324 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=40): time 3.116e-06/0.5146, allocations: 0 / 0.622 GB, free: 7.324 MB / 462.1 MB
Notification: Performance of matching and sorting (n=40): time 0.001837/0.5165, allocations: 0.8711 MB / 0.6229 GB, free: 6.477 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.48e-05/0.5165, allocations: 59.39 kB / 0.6229 GB, free: 6.406 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001104/0.5176, allocations: 0.9154 MB / 0.6238 GB, free: 5.484 MB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 5.541e-05/0.5177, allocations: 37.7 kB / 0.6239 GB, free: 5.441 MB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0003674/0.5181, allocations: 0.5029 MB / 0.6244 GB, free: 4.93 MB / 462.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001325/0.5182, allocations: 141.9 kB / 0.6245 GB, free: 4.789 MB / 462.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 7.342e-05/0.5183, allocations: 61.62 kB / 0.6246 GB, free: 4.723 MB / 462.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001032/0.5184, allocations: 329.9 kB / 0.6249 GB, free: 4.395 MB / 462.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003022/0.5187, allocations: 224.9 kB / 0.6251 GB, free: 4.176 MB / 462.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004594/0.5192, allocations: 442.6 kB / 0.6255 GB, free: 3.656 MB / 462.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0007674/0.52, allocations: 0.6569 MB / 0.6261 GB, free: 2.926 MB / 462.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.02e-06/0.52, allocations: 4 kB / 0.6262 GB, free: 2.922 MB / 462.1 MB
Notification: Performance of matching and sorting (n=70) (initialization): time 0.001298/0.5213, allocations: 0.8055 MB / 0.6269 GB, free: 2.105 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001572/0.5214, allocations: 262 kB / 0.6272 GB, free: 1.77 MB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.053e-05/0.5215, allocations: 4 kB / 0.6272 GB, free: 1.766 MB / 462.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004195/0.5219, allocations: 100.8 kB / 0.6273 GB, free: 1.664 MB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003598/0.5223, allocations: 107.8 kB / 0.6274 GB, free: 1.559 MB / 462.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0004586/0.5227, allocations: 0.6264 MB / 0.628 GB, free: 0.9062 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008353/0.5236, allocations: 107.4 kB / 0.6281 GB, free: 0.8008 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.036e-05/0.5236, allocations: 24 kB / 0.6281 GB, free: 0.7773 MB / 462.1 MB
Notification: Performance of prepare postOptimizeDAE: time 8.146e-06/0.5236, allocations: 4 kB / 0.6281 GB, free: 0.7734 MB / 462.1 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001008/0.5247, allocations: 0.6874 MB / 0.6288 GB, free: 84 kB / 462.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.861e-06/0.5247, allocations: 4 kB / 0.6288 GB, free: 80 kB / 462.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001518/0.5248, allocations: 68.73 kB / 0.6289 GB, free: 8 kB / 462.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003617/0.5252, allocations: 111.8 kB / 0.629 GB, free: 15.9 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001586/0.5268, allocations: 1.72 MB / 0.6307 GB, free: 14.03 MB / 478.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006754/0.5275, allocations: 90.84 kB / 0.6307 GB, free: 13.95 MB / 478.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.506e-05/0.5275, allocations: 35.94 kB / 0.6308 GB, free: 13.91 MB / 478.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 25
 * 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 (64):
 * Single equations (assignments): 63
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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): 1 system
   {(1,6)}
Notification: Performance of prepare postOptimizeDAE: time 0.000677/0.5282, allocations: 488.7 kB / 0.6312 GB, free: 13.42 MB / 478.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001402/0.5284, allocations: 90.88 kB / 0.6313 GB, free: 13.33 MB / 478.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00102/0.5294, allocations: 0.6471 MB / 0.632 GB, free: 12.68 MB / 478.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.999e-06/0.5294, allocations: 4 kB / 0.632 GB, free: 12.67 MB / 478.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.854e-06/0.5294, allocations: 3.938 kB / 0.632 GB, free: 12.67 MB / 478.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0008234/0.5303, allocations: 0.8243 MB / 0.6328 GB, free: 11.75 MB / 478.1 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 postOpt removeSimpleEquations (simulation): time 0.002344/0.5326, allocations: 1.681 MB / 0.6344 GB, free: 9.988 MB / 478.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.118e-06/0.5326, allocations: 0 / 0.6344 GB, free: 9.988 MB / 478.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003325/0.533, allocations: 79.89 kB / 0.6345 GB, free: 9.91 MB / 478.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0001137/0.5331, allocations: 40.83 kB / 0.6345 GB, free: 9.867 MB / 478.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.346e-05/0.5331, allocations: 15.92 kB / 0.6345 GB, free: 9.852 MB / 478.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0003404/0.5335, allocations: 0.572 MB / 0.6351 GB, free: 9.254 MB / 478.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.774e-06/0.5335, allocations: 3.938 kB / 0.6351 GB, free: 9.25 MB / 478.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001206/0.5347, allocations: 0.9565 MB / 0.636 GB, free: 8.262 MB / 478.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003798/0.5351, allocations: 263.8 kB / 0.6363 GB, free: 7.984 MB / 478.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 9.836e-05/0.5352, allocations: 11.98 kB / 0.6363 GB, free: 7.973 MB / 478.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006134/0.5358, allocations: 71.19 kB / 0.6364 GB, free: 7.906 MB / 478.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 5.232e-05/0.5359, allocations: 27.89 kB / 0.6364 GB, free: 7.879 MB / 478.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.226e-05/0.536, allocations: 27.98 kB / 0.6364 GB, free: 7.852 MB / 478.1 MB
Notification: Performance of sorting global known variables: time 0.0007852/0.5367, allocations: 0.6512 MB / 0.6371 GB, free: 7.203 MB / 478.1 MB
Notification: Performance of sort global known variables: time 1.61e-07/0.5368, allocations: 0 / 0.6371 GB, free: 7.203 MB / 478.1 MB
Notification: Performance of remove unused functions: time 0.001703/0.5385, allocations: 0.5612 MB / 0.6376 GB, free: 6.641 MB / 478.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 3 (vol[1].dynBal.U,vol[2].dynBal.U,vol[3].dynBal.U)
 * 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 (39):
 * Single equations (assignments): 36
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 2
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * 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): 1 system
   {(1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.0005925/0.5391, allocations: 294.3 kB / 0.6379 GB, free: 6.355 MB / 478.1 MB
Notification: Performance of simCode: created initialization part: time 0.001525/0.5406, allocations: 1 MB / 0.6389 GB, free: 5.344 MB / 478.1 MB
Notification: Performance of simCode: created event and clocks part: time 3.116e-06/0.5406, allocations: 0 / 0.6389 GB, free: 5.344 MB / 478.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.000363/0.541, allocations: 247.5 kB / 0.6391 GB, free: 5.102 MB / 478.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002839/0.5438, allocations: 0.5684 MB / 0.6397 GB, free: 4.547 MB / 478.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003107/0.547, allocations: 2.473 MB / 0.6421 GB, free: 2.031 MB / 478.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007476/0.5477, allocations: 1.228 MB / 0.6433 GB, free: 0.7734 MB / 478.1 MB
Notification: Performance of simCode: alias equations: time 0.001144/0.5489, allocations: 449.2 kB / 0.6437 GB, free: 348 kB / 478.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001731/0.5491, allocations: 79.41 kB / 0.6438 GB, free: 268 kB / 478.1 MB
Notification: Performance of SimCode: time 6.91e-07/0.5491, allocations: 0 / 0.6438 GB, free: 268 kB / 478.1 MB
Notification: Performance of Templates: time 0.03206/0.5811, allocations: 19.47 MB / 0.6628 GB, free: 13.05 MB / 0.4981 GB
make -j1 -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.sim & ./IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.MixingVolumes.BaseClasses.Validation.MixingVolumeHeatPortWater.pipe 2>&1)