Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.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/Buildings 9.1.2-maint.9.1.x/package.mo", uses=false)
Using package Buildings with version 9.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.x/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(Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|buiTesOutETS.PHea|buiTesOutETS.PCoo|buiTesOutETS.PFan|buiTesOutETS.PPum|buiTesOutETSBui.QHea_flow|buiTesOutETSBui.QCoo_flow|buiTesOutETSBui.PHea|buiTesOutETSBui.PCoo|buiTesOutETSBui.PFan|buiTesOutETSBui.PPum|buiTesFacMul.PHea|buiTesFacMul.PCoo|buiTesFacMul.PFan|buiTesFacMul.PPum|buiTesFacMul.QHea_flow|buiTesFacMul.QCoo_flow",fileNamePrefix="Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS")
translateModel(Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|buiTesOutETS.PHea|buiTesOutETS.PCoo|buiTesOutETS.PFan|buiTesOutETS.PPum|buiTesOutETSBui.QHea_flow|buiTesOutETSBui.QCoo_flow|buiTesOutETSBui.PHea|buiTesOutETSBui.PCoo|buiTesOutETSBui.PFan|buiTesOutETSBui.PPum|buiTesFacMul.PHea|buiTesFacMul.PCoo|buiTesFacMul.PFan|buiTesFacMul.PPum|buiTesFacMul.QHea_flow|buiTesFacMul.QCoo_flow",fileNamePrefix="Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001038/0.001038, allocations: 92.62 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.001633/0.001633, allocations: 175.1 kB / 16.92 MB, free: 6.652 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.414/1.414, allocations: 223.3 MB / 241 MB, free: 5.277 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.1.2-maint.9.1.x/package.mo): time 2.415/2.415, allocations: 390.9 MB / 0.667 GB, free: 2.359 MB / 0.5137 GB
Notification: Performance of FrontEnd - loaded program: time 0.0003661/0.0003661, allocations: 8 kB / 0.8166 GB, free: 13.22 MB / 0.67 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7982/0.7986, allocations: 142.4 MB / 0.9557 GB, free: 7.777 MB / 0.7169 GB
Notification: Performance of NFInst.instantiate(Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS): time 3.877/4.676, allocations: 1.322 GB / 2.278 GB, free: 54.17 MB / 1.389 GB
Notification: Performance of NFInst.instExpressions: time 0.4052/5.081, allocations: 327.7 MB / 2.598 GB, free: 12.71 MB / 1.389 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.05729/5.138, allocations: 0.9969 MB / 2.599 GB, free: 11.71 MB / 1.389 GB
Notification: Performance of NFTyping.typeComponents: time 0.03791/5.176, allocations: 10.55 MB / 2.609 GB, free: 1.598 MB / 1.389 GB
Notification: Performance of NFTyping.typeBindings: time 0.1102/5.286, allocations: 29.38 MB / 2.638 GB, free: 8.77 MB / 1.42 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05239/5.339, allocations: 17.27 MB / 2.655 GB, free: 9.309 MB / 1.436 GB
Notification: Performance of NFFlatten.flatten: time 0.09966/5.438, allocations: 74.4 MB / 2.727 GB, free: 0.8867 MB / 1.498 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04704/5.486, allocations: 17.36 MB / 2.744 GB, free: 15.66 MB / 1.529 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.05896/5.545, allocations: 20.15 MB / 2.764 GB, free: 12.53 MB / 1.545 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.03611/5.581, allocations: 16.8 MB / 2.78 GB, free: 12.87 MB / 1.561 GB
Notification: Performance of NFPackage.collectConstants: time 0.007547/5.588, allocations: 0.6211 MB / 2.781 GB, free: 12.25 MB / 1.561 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.05295/5.641, allocations: 15.57 MB / 2.796 GB, free: 13.39 MB / 1.576 GB
Notification: Performance of NFScalarize.scalarize: time 0.01358/5.655, allocations: 3.17 MB / 2.799 GB, free: 10.27 MB / 1.576 GB
Notification: Performance of NFVerifyModel.verify: time 0.02874/5.684, allocations: 9.108 MB / 2.808 GB, free: 1.172 MB / 1.576 GB
Notification: Performance of NFConvertDAE.convert: time 0.05562/5.739, allocations: 32.3 MB / 2.84 GB, free: 1.984 MB / 1.607 GB
Notification: Performance of FrontEnd - DAE generated: time 5.61e-06/5.739, allocations: 0 / 2.84 GB, free: 1.984 MB / 1.607 GB
Notification: Performance of FrontEnd: time 3.367e-06/5.739, allocations: 0 / 2.84 GB, free: 1.984 MB / 1.607 GB
Notification: Performance of Transformations before backend: time 0.001343/5.741, allocations: 0 / 2.84 GB, free: 1.984 MB / 1.607 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3135
 * Number of variables: 3135
Notification: Performance of Generate backend data structure: time 1.215/6.956, allocations: 16.34 MB / 2.856 GB, free: 0.8234 GB / 1.608 GB
Notification: Performance of prepare preOptimizeDAE: time 7.155e-05/6.956, allocations: 9.656 kB / 2.856 GB, free: 0.8234 GB / 1.608 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01426/6.971, allocations: 2.185 MB / 2.858 GB, free: 0.8224 GB / 1.608 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.04432/7.015, allocations: 17.47 MB / 2.875 GB, free: 0.8113 GB / 1.608 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001666/7.017, allocations: 0.9459 MB / 2.876 GB, free: 0.8109 GB / 1.608 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004759/7.022, allocations: 1.254 MB / 2.877 GB, free: 0.8105 GB / 1.608 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0414/7.063, allocations: 13.96 MB / 2.891 GB, free: 0.8017 GB / 1.608 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005547/7.064, allocations: 43.39 kB / 2.891 GB, free: 0.8017 GB / 1.608 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002376/7.066, allocations: 0.5063 MB / 2.891 GB, free: 0.8013 GB / 1.608 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003078/7.066, allocations: 337.5 kB / 2.891 GB, free: 0.8013 GB / 1.608 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0192/7.085, allocations: 9.68 MB / 2.901 GB, free: 0.7964 GB / 1.608 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.08906/7.175, allocations: 70.52 MB / 2.97 GB, free: 0.7363 GB / 1.608 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.006649/7.181, allocations: 4.568 MB / 2.974 GB, free: 0.7337 GB / 1.608 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003989/7.185, allocations: 2.433 MB / 2.977 GB, free: 0.7323 GB / 1.608 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.07763/7.263, allocations: 51.88 MB / 3.027 GB, free: 0.6776 GB / 1.608 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0002166/7.263, allocations: 129.6 kB / 3.027 GB, free: 0.6775 GB / 1.608 GB
Notification: Performance of pre-optimization done (n=412): time 7.264e-06/7.263, allocations: 0 / 3.027 GB, free: 0.6775 GB / 1.608 GB
Notification: Performance of matching and sorting (n=412): time 0.01681/7.28, allocations: 6.934 MB / 3.034 GB, free: 0.6707 GB / 1.608 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002045/7.28, allocations: 0.7902 MB / 3.035 GB, free: 0.6698 GB / 1.608 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03574/7.316, allocations: 15.97 MB / 3.051 GB, free: 0.6543 GB / 1.608 GB
Notification: Performance of collectPreVariables (initialization): time 0.001512/7.318, allocations: 145.7 kB / 3.051 GB, free: 0.6542 GB / 1.608 GB
Notification: Performance of collectInitialEqns (initialization): time 0.008989/7.327, allocations: 8.293 MB / 3.059 GB, free: 0.6461 GB / 1.608 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002534/7.329, allocations: 1.447 MB / 3.06 GB, free: 0.6447 GB / 1.608 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001418/7.331, allocations: 491.1 kB / 3.061 GB, free: 0.6442 GB / 1.608 GB
Notification: Performance of setup shared object (initialization): time 6.679e-05/7.331, allocations: 309.1 kB / 3.061 GB, free: 0.6439 GB / 1.608 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003763/7.335, allocations: 1.971 MB / 3.063 GB, free: 0.642 GB / 1.608 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006808/7.341, allocations: 4.734 MB / 3.068 GB, free: 0.6363 GB / 1.608 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007663/7.349, allocations: 6.421 MB / 3.074 GB, free: 0.6291 GB / 1.608 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.91e-05/7.349, allocations: 24 kB / 3.074 GB, free: 0.6291 GB / 1.608 GB
Notification: Performance of matching and sorting (n=775) (initialization): time 0.02031/7.37, allocations: 7.743 MB / 3.081 GB, free: 0.6216 GB / 1.608 GB
Notification: Performance of prepare postOptimizeDAE: time 6.966e-05/7.37, allocations: 35.84 kB / 3.081 GB, free: 0.6215 GB / 1.608 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.89e-05/7.37, allocations: 68.09 kB / 3.081 GB, free: 0.6215 GB / 1.608 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001214/7.37, allocations: 100 kB / 3.082 GB, free: 0.6214 GB / 1.608 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004912/7.375, allocations: 1.33 MB / 3.083 GB, free: 0.6201 GB / 1.608 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001381/7.375, allocations: 139.5 kB / 3.083 GB, free: 0.6199 GB / 1.608 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01502/7.39, allocations: 1.689 MB / 3.085 GB, free: 0.6183 GB / 1.608 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001611/7.392, allocations: 355.9 kB / 3.085 GB, free: 0.618 GB / 1.608 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 295
 * Number of states: 0 ()
 * Number of discrete variables: 4 (senDifEntFlo1.state_b1_inflow.phase,senDifEntFlo1.state_a1_inflow.phase,senDifEntFlo.state_b1_inflow.phase,senDifEntFlo.state_a1_inflow.phase)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (775):
 * Single equations (assignments): 775
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.009041/7.401, allocations: 3.386 MB / 3.088 GB, free: 0.6146 GB / 1.608 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.005008/7.406, allocations: 1.609 MB / 3.09 GB, free: 0.613 GB / 1.608 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01474/7.421, allocations: 7.534 MB / 3.097 GB, free: 0.6059 GB / 1.608 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001616/7.421, allocations: 79.94 kB / 3.097 GB, free: 0.6058 GB / 1.608 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.4e-05/7.421, allocations: 8 kB / 3.097 GB, free: 0.6058 GB / 1.608 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003762/7.425, allocations: 3.851 MB / 3.101 GB, free: 0.6017 GB / 1.608 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05047/7.475, allocations: 28.36 MB / 3.129 GB, free: 0.5736 GB / 1.608 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.542e-05/7.475, allocations: 32 kB / 3.129 GB, free: 0.5736 GB / 1.608 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002883/7.478, allocations: 0.7762 MB / 3.13 GB, free: 0.5728 GB / 1.608 GB
Notification: Performance of postOpt tearingSystem (simulation): time 5.2e-05/7.478, allocations: 48 kB / 3.13 GB, free: 0.5728 GB / 1.608 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004598/7.479, allocations: 152 kB / 3.13 GB, free: 0.5726 GB / 1.608 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 4.703e-05/7.479, allocations: 75.72 kB / 3.13 GB, free: 0.5726 GB / 1.608 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.323e-05/7.479, allocations: 47.81 kB / 3.13 GB, free: 0.5725 GB / 1.608 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01639/7.495, allocations: 11.91 MB / 3.141 GB, free: 0.5603 GB / 1.608 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01006/7.505, allocations: 3.082 MB / 3.144 GB, free: 0.5573 GB / 1.608 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.006241/7.511, allocations: 128 kB / 3.145 GB, free: 0.5571 GB / 1.608 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01308/7.525, allocations: 0.5533 MB / 3.145 GB, free: 0.5566 GB / 1.608 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001201/7.526, allocations: 402.5 kB / 3.146 GB, free: 0.5562 GB / 1.608 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.00077/7.527, allocations: 255.9 kB / 3.146 GB, free: 0.556 GB / 1.608 GB
Notification: Performance of sorting global known variables: time 0.02543/7.552, allocations: 11.39 MB / 3.157 GB, free: 0.5449 GB / 1.608 GB
Notification: Performance of sort global known variables: time 7.22e-07/7.552, allocations: 0 / 3.157 GB, free: 0.5449 GB / 1.608 GB
Notification: Performance of remove unused functions: time 0.02289/7.575, allocations: 5.219 MB / 3.162 GB, free: 0.5398 GB / 1.608 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 141
 * Number of states: 15 (buiTesFacMul.bui.loaHea.vol.dynBal.U,buiTesFacMul.bui.loaCoo.vol.dynBal.U,buiTesOutETSBui.bui.loaHea.vol.dynBal.U,buiTesOutETSBui.bui.loaCoo.vol.dynBal.U,buiTesOutETS.bui.loaHea.vol.dynBal.U,buiTesOutETS.bui.loaCoo.vol.dynBal.U,buiComGen5.bui.loaHea.vol.dynBal.U,buiComGen5.bui.loaCoo.vol.dynBal.U,buiHeaGen2to4.bui.loaHea.vol.dynBal.U,buiComGen2to4.bui.loaHea.vol.dynBal.U,buiComGen2to4.bui.loaCoo.vol.dynBal.U,buiCoo.bui.loaCoo.vol.dynBal.U,buiComGen1.bui.loaHea.vol.dynBal.U,buiComGen1.bui.loaCoo.vol.dynBal.U,buiHeaGen1.bui.loaHea.vol.dynBal.U)
 * Number of discrete variables: 4 ($cse16.phase,$cse16.region,$cse7.phase,$cse7.region)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (478):
 * Single equations (assignments): 412
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 66
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.01276/7.588, allocations: 4.702 MB / 3.167 GB, free: 0.5356 GB / 1.608 GB
Notification: Performance of simCode: created initialization part: time 0.009743/7.598, allocations: 5.343 MB / 3.172 GB, free: 0.5304 GB / 1.608 GB
Notification: Performance of simCode: created event and clocks part: time 3.973e-05/7.598, allocations: 11.91 kB / 3.172 GB, free: 0.5303 GB / 1.608 GB
Notification: Performance of simCode: created simulation system equations: time 0.004038/7.602, allocations: 2.578 MB / 3.174 GB, free: 0.5278 GB / 1.608 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.1678/7.769, allocations: 10.81 MB / 3.185 GB, free: 0.5178 GB / 1.608 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.05956/7.829, allocations: 33.17 MB / 3.217 GB, free: 496.7 MB / 1.608 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01296/7.842, allocations: 7.281 MB / 3.224 GB, free: 489.4 MB / 1.608 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.005451/7.848, allocations: 1.347 MB / 3.226 GB, free: 488 MB / 1.608 GB
Notification: Performance of SimCode: time 1.102e-06/7.848, allocations: 4 kB / 3.226 GB, free: 488 MB / 1.608 GB
Notification: Performance of Templates: time 0.3997/8.247, allocations: 309.9 MB / 3.528 GB, free: 179.1 MB / 1.608 GB
make -j1 -f Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.makefile
(rm -f Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.pipe ; mkfifo Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.pipe ; head -c 1048576 < Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.pipe >> ../files/Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.sim & ./Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.pipe 2>&1)
diffSimulationResults("Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_9.1.x/Buildings_Experimental_DHC_Loads_BaseClasses_Validation_BuildingWithETS.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_9_Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches