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.001447/0.001447, allocations: 100.1 kB / 15.88 MB, free: 12 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.001736/0.001736, allocations: 196 kB / 16.79 MB, free: 6.809 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.358/1.358, allocations: 222.9 MB / 240.5 MB, free: 4.602 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.37/2.37, allocations: 390.2 MB / 0.6649 GB, free: 0.5898 MB / 0.5137 GB
Notification: Performance of FrontEnd - loaded program: time 0.001298/0.001298, allocations: 59.91 kB / 0.8125 GB, free: 14.85 MB / 0.67 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7297/0.731, allocations: 140.4 MB / 0.9496 GB, free: 9.098 MB / 0.7169 GB
Notification: Performance of NFInst.instantiate(Buildings.Experimental.DHC.Loads.BaseClasses.Validation.BuildingWithETS): time 3.802/4.533, allocations: 1.34 GB / 2.289 GB, free: 74.61 MB / 1.451 GB
Notification: Performance of NFInst.instExpressions: time 0.3964/4.929, allocations: 331.2 MB / 2.613 GB, free: 35.11 MB / 1.451 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.05698/4.986, allocations: 1.06 MB / 2.614 GB, free: 34.5 MB / 1.451 GB
Notification: Performance of NFTyping.typeComponents: time 0.03856/5.025, allocations: 10.62 MB / 2.624 GB, free: 24.95 MB / 1.451 GB
Notification: Performance of NFTyping.typeBindings: time 0.0946/5.12, allocations: 29.39 MB / 2.653 GB, free: 116 kB / 1.451 GB
Notification: Performance of NFTyping.typeClassSections: time 0.05237/5.172, allocations: 17.19 MB / 2.669 GB, free: 0.7109 MB / 1.467 GB
Notification: Performance of NFFlatten.flatten: time 0.1008/5.273, allocations: 74.41 MB / 2.742 GB, free: 7.82 MB / 1.545 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04886/5.322, allocations: 17.94 MB / 2.76 GB, free: 6 MB / 1.561 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.06129/5.383, allocations: 20.79 MB / 2.78 GB, free: 2.219 MB / 1.576 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.04019/5.423, allocations: 17.57 MB / 2.797 GB, free: 1.82 MB / 1.592 GB
Notification: Performance of NFPackage.collectConstants: time 0.009056/5.433, allocations: 0.8789 MB / 2.798 GB, free: 0.9414 MB / 1.592 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04937/5.482, allocations: 15.83 MB / 2.813 GB, free: 1.828 MB / 1.607 GB
Notification: Performance of NFScalarize.scalarize: time 0.009034/5.491, allocations: 3.591 MB / 2.817 GB, free: 14.29 MB / 1.623 GB
Notification: Performance of NFVerifyModel.verify: time 0.02707/5.518, allocations: 9.611 MB / 2.826 GB, free: 4.695 MB / 1.623 GB
Notification: Performance of NFConvertDAE.convert: time 0.05503/5.573, allocations: 32.97 MB / 2.859 GB, free: 4.844 MB / 1.654 GB
Notification: Performance of FrontEnd - DAE generated: time 1.158e-05/5.573, allocations: 3.984 kB / 2.859 GB, free: 4.84 MB / 1.654 GB
Notification: Performance of FrontEnd: time 3.196e-06/5.573, allocations: 0 / 2.859 GB, free: 4.84 MB / 1.654 GB
Notification: Performance of Transformations before backend: time 0.001314/5.575, allocations: 0 / 2.859 GB, free: 4.84 MB / 1.654 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 0.06142/5.636, allocations: 20.34 MB / 2.878 GB, free: 0.5547 MB / 1.67 GB
Notification: Performance of prepare preOptimizeDAE: time 5.583e-05/5.636, allocations: 8.031 kB / 2.878 GB, free: 0.5469 MB / 1.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01132/5.647, allocations: 2.187 MB / 2.881 GB, free: 14.33 MB / 1.686 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.04064/5.688, allocations: 17.46 MB / 2.898 GB, free: 13.19 MB / 1.701 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001286/5.689, allocations: 0.9442 MB / 2.899 GB, free: 12.24 MB / 1.701 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.004882/5.694, allocations: 1.257 MB / 2.9 GB, free: 10.99 MB / 1.701 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04178/5.736, allocations: 13.97 MB / 2.913 GB, free: 12.32 MB / 1.717 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0005456/5.737, allocations: 47.53 kB / 2.913 GB, free: 12.27 MB / 1.717 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003128/5.74, allocations: 0.5074 MB / 2.914 GB, free: 11.77 MB / 1.717 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003572/5.74, allocations: 339.9 kB / 2.914 GB, free: 11.44 MB / 1.717 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02194/5.762, allocations: 9.676 MB / 2.924 GB, free: 1.797 MB / 1.717 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.8123/6.575, allocations: 70.47 MB / 2.993 GB, free: 0.8697 GB / 1.717 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008646/6.583, allocations: 4.567 MB / 2.997 GB, free: 0.8671 GB / 1.717 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004355/6.588, allocations: 2.44 MB / 2.999 GB, free: 0.8658 GB / 1.717 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.07451/6.662, allocations: 51.49 MB / 3.05 GB, free: 0.8226 GB / 1.717 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0002472/6.663, allocations: 132.6 kB / 3.05 GB, free: 0.8225 GB / 1.717 GB
Notification: Performance of pre-optimization done (n=412): time 8.335e-06/6.663, allocations: 0 / 3.05 GB, free: 0.8225 GB / 1.717 GB
Notification: Performance of matching and sorting (n=412): time 0.01906/6.682, allocations: 6.933 MB / 3.057 GB, free: 0.8192 GB / 1.717 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.000224/6.682, allocations: 0.7927 MB / 3.057 GB, free: 0.8183 GB / 1.717 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03445/6.716, allocations: 15.97 MB / 3.073 GB, free: 0.8103 GB / 1.717 GB
Notification: Performance of collectPreVariables (initialization): time 0.001685/6.718, allocations: 147.1 kB / 3.073 GB, free: 0.8102 GB / 1.717 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01003/6.728, allocations: 8.286 MB / 3.081 GB, free: 0.8031 GB / 1.717 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002818/6.731, allocations: 1.454 MB / 3.083 GB, free: 0.8018 GB / 1.717 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00392/6.735, allocations: 1.37 MB / 3.084 GB, free: 0.8011 GB / 1.717 GB
Notification: Performance of setup shared object (initialization): time 6.695e-05/6.735, allocations: 305.8 kB / 3.084 GB, free: 0.8008 GB / 1.717 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004153/6.739, allocations: 1.974 MB / 3.086 GB, free: 0.7998 GB / 1.717 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006682/6.746, allocations: 4.735 MB / 3.091 GB, free: 0.7955 GB / 1.717 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008759/6.755, allocations: 6.427 MB / 3.097 GB, free: 0.7908 GB / 1.717 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001092/6.755, allocations: 21.31 kB / 3.097 GB, free: 0.7908 GB / 1.717 GB
Notification: Performance of matching and sorting (n=775) (initialization): time 0.02091/6.776, allocations: 7.75 MB / 3.105 GB, free: 0.787 GB / 1.717 GB
Notification: Performance of prepare postOptimizeDAE: time 6.362e-05/6.776, allocations: 32.66 kB / 3.105 GB, free: 0.787 GB / 1.717 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.783e-05/6.776, allocations: 65.34 kB / 3.105 GB, free: 0.787 GB / 1.717 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001164/6.776, allocations: 102.1 kB / 3.105 GB, free: 0.787 GB / 1.717 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005104/6.781, allocations: 1.327 MB / 3.106 GB, free: 0.7864 GB / 1.717 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001317/6.781, allocations: 135.5 kB / 3.106 GB, free: 0.7863 GB / 1.717 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01624/6.798, allocations: 1.682 MB / 3.108 GB, free: 0.7847 GB / 1.717 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001972/6.8, allocations: 355.9 kB / 3.108 GB, free: 0.7844 GB / 1.717 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.01039/6.81, allocations: 3.386 MB / 3.112 GB, free: 0.781 GB / 1.717 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.006712/6.817, allocations: 1.609 MB / 3.113 GB, free: 0.7794 GB / 1.717 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01983/6.837, allocations: 7.537 MB / 3.12 GB, free: 0.7723 GB / 1.717 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002687/6.837, allocations: 81.95 kB / 3.121 GB, free: 0.7722 GB / 1.717 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.611e-05/6.837, allocations: 8 kB / 3.121 GB, free: 0.7722 GB / 1.717 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.005312/6.842, allocations: 3.852 MB / 3.124 GB, free: 0.7681 GB / 1.717 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.06354/6.906, allocations: 28.37 MB / 3.152 GB, free: 0.7401 GB / 1.717 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001836/6.906, allocations: 32 kB / 3.152 GB, free: 0.74 GB / 1.717 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003313/6.909, allocations: 0.7723 MB / 3.153 GB, free: 0.7393 GB / 1.717 GB
Notification: Performance of postOpt tearingSystem (simulation): time 9.307e-05/6.91, allocations: 52 kB / 3.153 GB, free: 0.7392 GB / 1.717 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0009428/6.911, allocations: 156 kB / 3.153 GB, free: 0.7391 GB / 1.717 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.898e-05/6.911, allocations: 71.72 kB / 3.153 GB, free: 0.739 GB / 1.717 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.301e-05/6.911, allocations: 48.06 kB / 3.153 GB, free: 0.739 GB / 1.717 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0211/6.932, allocations: 11.9 MB / 3.165 GB, free: 0.7268 GB / 1.717 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.009564/6.941, allocations: 2.806 MB / 3.167 GB, free: 0.724 GB / 1.717 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.006153/6.948, allocations: 151.6 kB / 3.168 GB, free: 0.7238 GB / 1.717 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01278/6.96, allocations: 0.5489 MB / 3.168 GB, free: 0.7233 GB / 1.717 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001378/6.962, allocations: 402.5 kB / 3.169 GB, free: 0.7229 GB / 1.717 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001461/6.963, allocations: 255.9 kB / 3.169 GB, free: 0.7227 GB / 1.717 GB
Notification: Performance of sorting global known variables: time 0.02791/6.991, allocations: 11.39 MB / 3.18 GB, free: 0.7116 GB / 1.717 GB
Notification: Performance of sort global known variables: time 1.162e-06/6.991, allocations: 0 / 3.18 GB, free: 0.7116 GB / 1.717 GB
Notification: Performance of remove unused functions: time 0.02352/7.015, allocations: 5.221 MB / 3.185 GB, free: 0.7068 GB / 1.717 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.01312/7.028, allocations: 4.444 MB / 3.189 GB, free: 0.7029 GB / 1.717 GB
Notification: Performance of simCode: created initialization part: time 0.01101/7.039, allocations: 5.34 MB / 3.195 GB, free: 0.6977 GB / 1.717 GB
Notification: Performance of simCode: created event and clocks part: time 4.023e-05/7.039, allocations: 8 kB / 3.195 GB, free: 0.6977 GB / 1.717 GB
Notification: Performance of simCode: created simulation system equations: time 0.004729/7.044, allocations: 2.585 MB / 3.197 GB, free: 0.6952 GB / 1.717 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.05832/7.102, allocations: 9.342 MB / 3.206 GB, free: 0.6866 GB / 1.717 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.06323/7.165, allocations: 32.92 MB / 3.238 GB, free: 0.654 GB / 1.717 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01625/7.182, allocations: 7.269 MB / 3.245 GB, free: 0.6469 GB / 1.717 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004775/7.187, allocations: 1.089 MB / 3.247 GB, free: 0.6458 GB / 1.717 GB
Notification: Performance of SimCode: time 2.785e-06/7.187, allocations: 0 / 3.247 GB, free: 0.6458 GB / 1.717 GB
Notification: Performance of Templates: time 0.404/7.591, allocations: 312.7 MB / 3.552 GB, free: 349.7 MB / 1.717 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