Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.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.001809/0.001809, allocations: 107.6 kB / 18.95 MB, free: 2.812 MB / 14.72 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.002218/0.002218, allocations: 214.9 kB / 22.24 MB, free: 5.105 MB / 14.72 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.597/1.597, allocations: 230.6 MB / 256 MB, free: 7.82 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/package.mo): time 2.787/2.787, allocations: 476 MB / 0.7699 GB, free: 4.734 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 11.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.1-maint.11.x/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: "" <> buildModelFMU(Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer,fileNamePrefix="Buildings_11_Buildings_DHC_ETS_Combined_Subsystems_Validation_WatersideEconomizer",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer,fileNamePrefix="Buildings_11_Buildings_DHC_ETS_Combined_Subsystems_Validation_WatersideEconomizer",fmuType="me",version="2.0",platforms={"static"}) [Timeout 300]
"Notification: Buildings requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002169/0.002169, allocations: 73.34 kB / 1.117 GB, free: 9.188 MB / 0.8419 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3625/0.3647, allocations: 169.9 MB / 1.283 GB, free: 4.23 MB / 0.9825 GB
Notification: Performance of NFInst.instantiate(Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer): time 0.3142/0.6789, allocations: 276.8 MB / 1.553 GB, free: 14.5 MB / 1.264 GB
Notification: Performance of NFInst.instExpressions: time 1.029/1.707, allocations: 69.21 MB / 1.62 GB, free: 23.51 MB / 1.264 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.02075/1.728, allocations: 0.4955 MB / 1.621 GB, free: 23.51 MB / 1.264 GB
Notification: Performance of NFTyping.typeComponents: time 0.01448/1.743, allocations: 4.858 MB / 1.626 GB, free: 23.42 MB / 1.264 GB
Notification: Performance of NFTyping.typeBindings: time 0.03864/1.781, allocations: 10.78 MB / 1.636 GB, free: 23.02 MB / 1.264 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02778/1.809, allocations: 9.569 MB / 1.646 GB, free: 22.76 MB / 1.264 GB
Notification: Performance of NFFlatten.flatten: time 0.0515/1.861, allocations: 33.44 MB / 1.678 GB, free: 19.8 MB / 1.264 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.02096/1.882, allocations: 6.382 MB / 1.684 GB, free: 16.86 MB / 1.264 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.03069/1.912, allocations: 9.709 MB / 1.694 GB, free: 13.71 MB / 1.264 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01672/1.929, allocations: 6.246 MB / 1.7 GB, free: 11.75 MB / 1.264 GB
Notification: Performance of NFPackage.collectConstants: time 0.005683/1.935, allocations: 0.957 MB / 1.701 GB, free: 11.75 MB / 1.264 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02125/1.956, allocations: 5.205 MB / 1.706 GB, free: 9.707 MB / 1.264 GB
Notification: Performance of NFScalarize.scalarize: time 0.006148/1.962, allocations: 2.822 MB / 1.709 GB, free: 7.59 MB / 1.264 GB
Notification: Performance of NFVerifyModel.verify: time 0.01493/1.977, allocations: 4.246 MB / 1.713 GB, free: 5.004 MB / 1.264 GB
Notification: Performance of NFConvertDAE.convert: time 0.04034/2.017, allocations: 17.2 MB / 1.73 GB, free: 9.492 MB / 1.279 GB
Notification: Performance of FrontEnd - DAE generated: time 7.113e-06/2.017, allocations: 3.938 kB / 1.73 GB, free: 9.488 MB / 1.279 GB
Notification: Performance of FrontEnd: time 2.515e-06/2.017, allocations: 0 / 1.73 GB, free: 9.488 MB / 1.279 GB
Notification: Performance of Transformations before backend: time 0.0007411/2.018, allocations: 0 / 1.73 GB, free: 9.488 MB / 1.279 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1327
 * Number of variables: 1327
Notification: Performance of Generate backend data structure: time 0.0368/2.055, allocations: 10.94 MB / 1.74 GB, free: 1.727 MB / 1.279 GB
Notification: Performance of prepare preOptimizeDAE: time 5.519e-05/2.055, allocations: 10.94 kB / 1.74 GB, free: 1.727 MB / 1.279 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002653/2.058, allocations: 0.6721 MB / 1.741 GB, free: 1.211 MB / 1.279 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01167/2.069, allocations: 1.957 MB / 1.743 GB, free: 168 kB / 1.279 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.03363/2.103, allocations: 12.74 MB / 1.755 GB, free: 3.621 MB / 1.295 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007279/2.104, allocations: 441.6 kB / 1.756 GB, free: 3.191 MB / 1.295 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002737/2.106, allocations: 0.5663 MB / 1.756 GB, free: 2.625 MB / 1.295 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02216/2.129, allocations: 6.981 MB / 1.763 GB, free: 11.32 MB / 1.311 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002549/2.129, allocations: 7.938 kB / 1.763 GB, free: 11.31 MB / 1.311 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001953/2.131, allocations: 216 kB / 1.763 GB, free: 11.1 MB / 1.311 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002509/2.131, allocations: 185.7 kB / 1.764 GB, free: 10.92 MB / 1.311 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01845/2.149, allocations: 5.625 MB / 1.769 GB, free: 5.27 MB / 1.311 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.06622/2.216, allocations: 23.01 MB / 1.792 GB, free: 14.14 MB / 1.342 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01222/2.228, allocations: 4.013 MB / 1.796 GB, free: 10.11 MB / 1.342 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005781/2.234, allocations: 2.114 MB / 1.798 GB, free: 7.977 MB / 1.342 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02859/2.262, allocations: 7.434 MB / 1.805 GB, free: 0.6055 MB / 1.342 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.008123/2.27, allocations: 3.296 MB / 1.808 GB, free: 13.14 MB / 1.358 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.005078/2.275, allocations: 0.6335 MB / 1.809 GB, free: 12.51 MB / 1.358 GB
Notification: Performance of pre-optimization done (n=390): time 2.323e-05/2.275, allocations: 0 / 1.809 GB, free: 12.51 MB / 1.358 GB
Notification: Performance of matching and sorting (n=390): time 0.03273/2.308, allocations: 8.331 MB / 1.817 GB, free: 4.113 MB / 1.358 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002374/2.308, allocations: 268.6 kB / 1.817 GB, free: 3.801 MB / 1.358 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0201/2.329, allocations: 7.499 MB / 1.824 GB, free: 12.33 MB / 1.373 GB
Notification: Performance of collectPreVariables (initialization): time 0.00136/2.33, allocations: 152 kB / 1.825 GB, free: 12.18 MB / 1.373 GB
Notification: Performance of collectInitialEqns (initialization): time 0.005858/2.336, allocations: 3.758 MB / 1.828 GB, free: 8.449 MB / 1.373 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002417/2.338, allocations: 1.318 MB / 1.83 GB, free: 7.152 MB / 1.373 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.005568/2.344, allocations: 1.428 MB / 1.831 GB, free: 5.719 MB / 1.373 GB
Notification: Performance of setup shared object (initialization): time 0.0001556/2.344, allocations: 305.1 kB / 1.831 GB, free: 5.418 MB / 1.373 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007525/2.351, allocations: 2.99 MB / 1.834 GB, free: 2.41 MB / 1.373 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009896/2.361, allocations: 5.168 MB / 1.839 GB, free: 12.52 MB / 1.389 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01545/2.377, allocations: 6.829 MB / 1.846 GB, free: 5.184 MB / 1.389 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.657e-05/2.377, allocations: 12 kB / 1.846 GB, free: 5.172 MB / 1.389 GB
Notification: Performance of matching and sorting (n=848) (initialization): time 0.02619/2.403, allocations: 8.973 MB / 1.855 GB, free: 12.1 MB / 1.404 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001504/2.403, allocations: 83.59 kB / 1.855 GB, free: 12 MB / 1.404 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.873e-05/2.403, allocations: 43.98 kB / 1.855 GB, free: 11.96 MB / 1.404 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004908/2.408, allocations: 0.911 MB / 1.856 GB, free: 11.04 MB / 1.404 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006383/2.415, allocations: 1.232 MB / 1.857 GB, free: 9.809 MB / 1.404 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004829/2.419, allocations: 3.756 MB / 1.861 GB, free: 5.93 MB / 1.404 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008137/2.428, allocations: 370.3 kB / 1.861 GB, free: 5.57 MB / 1.404 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001565/2.429, allocations: 307.8 kB / 1.861 GB, free: 5.27 MB / 1.404 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01248/2.442, allocations: 4.362 MB / 1.865 GB, free: 0.8906 MB / 1.404 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009574/2.451, allocations: 5.046 MB / 1.87 GB, free: 11.12 MB / 1.42 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01482/2.466, allocations: 6.568 MB / 1.877 GB, free: 4.039 MB / 1.42 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.487e-05/2.466, allocations: 12 kB / 1.877 GB, free: 4.027 MB / 1.42 GB
Notification: Performance of matching and sorting (n=848) (initialization_lambda0): time 0.02475/2.491, allocations: 8.567 MB / 1.885 GB, free: 11.36 MB / 1.436 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001176/2.491, allocations: 83.58 kB / 1.885 GB, free: 11.27 MB / 1.436 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.887e-05/2.491, allocations: 36 kB / 1.885 GB, free: 11.23 MB / 1.436 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004336/2.495, allocations: 0.8497 MB / 1.886 GB, free: 10.38 MB / 1.436 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006085/2.501, allocations: 1.24 MB / 1.887 GB, free: 9.137 MB / 1.436 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006996/2.508, allocations: 5.327 MB / 1.892 GB, free: 3.551 MB / 1.436 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00789/2.516, allocations: 400.2 kB / 1.893 GB, free: 3.16 MB / 1.436 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001503/2.518, allocations: 295.9 kB / 1.893 GB, free: 2.871 MB / 1.436 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 146
 * Number of states: 0 ()
 * Number of discrete variables: 119 ($PRE.hexPum.conWSE.ena.enableFire,$PRE.hexPum.conWSE.dis.enableFire,$PRE.hexPum.conWSE.isValIsoEvaClo.lesHys.y,$PRE.hexVal.conWSE.ena.enableFire,$PRE.hexVal.conWSE.dis.enableFire,$PRE.hexVal.conWSE.isValIsoEvaClo.lesHys.y,hexVal.conWSE.dis.t_start,$PRE.hexVal.conWSE.dis.t_start,hexVal.conWSE.ena.t_start,$PRE.hexVal.conWSE.ena.t_start,hexPum.conWSE.dis.t_start,$PRE.hexPum.conWSE.dis.t_start,hexPum.conWSE.ena.t_start,$PRE.hexPum.conWSE.ena.t_start,hexVal.conWSE.stateGraphRoot.resume,hexVal.conWSE.stateGraphRoot.suspend,hexPum.conWSE.stateGraphRoot.resume,hexPum.conWSE.stateGraphRoot.suspend,hexPum.hex.flowRegime_nominal,hexPum.hex.flowRegime,hexVal.hex.flowRegime_nominal,hexVal.hex.flowRegime,hexPum.conWSE.isValIsoEvaClo.lesHys.y,hexVal.conWSE.isValIsoEvaClo.lesHys.y,TSerWat.nextEventScaled,TSerWat.nextEvent,$PRE.TSerWat.nextEvent,TSerWat.last,TSerWat.b,TSerWat.a,TChiWatRet.nextEventScaled,TChiWatRet.nextEvent,$PRE.TChiWatRet.nextEvent,TChiWatRet.last,TChiWatRet.b,TChiWatRet.a,hexVal.uCoo,hexVal.conWSE.and1.y,hexVal.conWSE.tim1.entryTime,$PRE.hexVal.conWSE.tim1.entryTime,hexVal.conWSE.tim1.passed,$PRE.hexVal.conWSE.tim1.passed,hexVal.conWSE.tim.entryTime,$PRE.hexVal.conWSE.tim.entryTime,hexVal.conWSE.tim.passed,$PRE.hexVal.conWSE.tim.passed,hexVal.conWSE.or1.uTemp[2],hexVal.conWSE.or1.y,hexVal.conWSE.or1.u[1],hexVal.conWSE.mulAnd.uTemp[3],hexVal.conWSE.mulAnd.uTemp[2],hexVal.conWSE.mulAnd.u[1],hexVal.conWSE.stateGraphRoot.activeSteps,hexVal.conWSE.dis.enableFire,hexVal.conWSE.dis.localCondition,hexVal.conWSE.actSta.active,hexVal.conWSE.actSta.oldActive,$PRE.hexVal.conWSE.actSta.oldActive,hexVal.conWSE.actSta.newActive,$PRE.hexVal.conWSE.actSta.newActive,hexVal.conWSE.actSta.localActive,$PRE.hexVal.conWSE.actSta.localActive,hexVal.conWSE.ena.enableFire,hexVal.conWSE.ena.localCondition,hexVal.conWSE.iniSta.active,hexVal.conWSE.iniSta.oldActive,$PRE.hexVal.conWSE.iniSta.oldActive,hexVal.conWSE.iniSta.newActive,$PRE.hexVal.conWSE.iniSta.newActive,hexVal.conWSE.iniSta.localActive,$PRE.hexVal.conWSE.iniSta.localActive,hexPum.conWSE.and1.y,hexPum.conWSE.tim1.entryTime,$PRE.hexPum.conWSE.tim1.entryTime,hexPum.conWSE.tim1.passed,$PRE.hexPum.conWSE.tim1.passed,hexPum.conWSE.tim.entryTime,$PRE.hexPum.conWSE.tim.entryTime,hexPum.conWSE.tim.passed,$PRE.hexPum.conWSE.tim.passed,hexPum.conWSE.or1.uTemp[2],hexPum.conWSE.or1.y,hexPum.conWSE.or1.u[1],hexPum.conWSE.mulAnd.uTemp[3],hexPum.conWSE.mulAnd.uTemp[2],hexPum.conWSE.mulAnd.u[1],hexPum.conWSE.stateGraphRoot.activeSteps,hexPum.conWSE.dis.enableFire,hexPum.conWSE.dis.localCondition,hexPum.conWSE.actSta.active,hexPum.conWSE.actSta.oldActive,$PRE.hexPum.conWSE.actSta.oldActive,hexPum.conWSE.actSta.newActive,$PRE.hexPum.conWSE.actSta.newActive,hexPum.conWSE.actSta.localActive,$PRE.hexPum.conWSE.actSta.localActive,hexPum.conWSE.ena.enableFire,hexPum.conWSE.ena.localCondition,hexPum.conWSE.iniSta.active,hexPum.conWSE.iniSta.oldActive,$PRE.hexPum.conWSE.iniSta.oldActive,hexPum.conWSE.iniSta.newActive,$PRE.hexPum.conWSE.iniSta.newActive,hexPum.conWSE.iniSta.localActive,$PRE.hexPum.conWSE.iniSta.localActive,$whenCondition14,$whenCondition13,$whenCondition3,$whenCondition2,$whenCondition1,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition12,$whenCondition11,$whenCondition10)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (697):
 * Single equations (assignments): 684
 * Array equations: 3
 * Algorithm blocks: 2
 * Record equations: 3
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
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): 4 systems
   {(2,12), (1,7), (3,11), (1,7)}
Notification: Performance of prepare postOptimizeDAE: time 0.002648/2.52, allocations: 0.6333 MB / 1.894 GB, free: 2.215 MB / 1.436 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003288/2.524, allocations: 0.8227 MB / 1.895 GB, free: 1.387 MB / 1.436 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02193/2.546, allocations: 7.133 MB / 1.902 GB, free: 10.29 MB / 1.451 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001916/2.546, allocations: 63.97 kB / 1.902 GB, free: 10.23 MB / 1.451 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.015e-05/2.546, allocations: 3.938 kB / 1.902 GB, free: 10.23 MB / 1.451 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01569/2.562, allocations: 5.3 MB / 1.907 GB, free: 4.863 MB / 1.451 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04168/2.603, allocations: 12.71 MB / 1.919 GB, free: 8.117 MB / 1.467 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.172e-05/2.603, allocations: 0 / 1.919 GB, free: 8.117 MB / 1.467 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003923/2.607, allocations: 0.7063 MB / 1.92 GB, free: 7.41 MB / 1.467 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.002627/2.61, allocations: 0.6095 MB / 1.92 GB, free: 6.793 MB / 1.467 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001281/2.611, allocations: 139.9 kB / 1.921 GB, free: 6.656 MB / 1.467 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.004591/2.616, allocations: 3.567 MB / 1.924 GB, free: 2.973 MB / 1.467 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.937e-06/2.616, allocations: 4 kB / 1.924 GB, free: 2.969 MB / 1.467 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02733/2.643, allocations: 9.528 MB / 1.933 GB, free: 9.246 MB / 1.483 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006308/2.649, allocations: 1.457 MB / 1.935 GB, free: 7.789 MB / 1.483 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003358/2.653, allocations: 116 kB / 1.935 GB, free: 7.676 MB / 1.483 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.006944/2.66, allocations: 292.5 kB / 1.935 GB, free: 7.391 MB / 1.483 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001974/2.662, allocations: 351 kB / 1.936 GB, free: 7.047 MB / 1.483 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001547/2.663, allocations: 195.9 kB / 1.936 GB, free: 6.855 MB / 1.483 GB
Notification: Performance of sorting global known variables: time 0.01598/2.679, allocations: 4.857 MB / 1.94 GB, free: 2.031 MB / 1.483 GB
Notification: Performance of sort global known variables: time 3.41e-07/2.679, allocations: 0 / 1.94 GB, free: 2.031 MB / 1.483 GB
Notification: Performance of remove unused functions: time 0.02201/2.701, allocations: 4.236 MB / 1.945 GB, free: 13.79 MB / 1.498 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 13
 * Number of states: 20 (pum2Pum.vol.dynBal.U,pum2Pum.filter.s[1],pum2Pum.filter.s[2],hexPum.senT2WatEnt.T,hexPum.senT2WatLvg.T,hexPum.senT1WatEnt.T,senT1OutPum.T,senT1InlPum.T,senT2OutPum.T,senT2InlPum.T,hexVal.senT2WatEnt.T,hexVal.senT2WatLvg.T,hexVal.senT1WatEnt.T,senT1InlVal.T,senT1OutVal.T,senT2OutVal.T,senT2InlVal.T,pum2Val.vol.dynBal.U,pum2Val.filter.s[1],pum2Val.filter.s[2])
 * Number of discrete variables: 77 (hexVal.conWSE.actSta.localActive,hexVal.conWSE.actSta.active,hexVal.conWSE.iniSta.localActive,hexVal.conWSE.iniSta.active,hexPum.conWSE.actSta.localActive,hexPum.conWSE.actSta.active,hexPum.conWSE.iniSta.localActive,hexPum.conWSE.iniSta.active,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition4,$whenCondition5,$whenCondition6,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition13,$whenCondition14,hexPum.conWSE.iniSta.newActive,hexPum.conWSE.iniSta.oldActive,hexPum.conWSE.ena.localCondition,hexPum.conWSE.ena.enableFire,hexPum.conWSE.actSta.newActive,hexPum.conWSE.actSta.oldActive,hexPum.conWSE.dis.localCondition,hexPum.conWSE.dis.enableFire,hexPum.conWSE.stateGraphRoot.activeSteps,hexPum.conWSE.mulAnd.u[1],hexPum.conWSE.mulAnd.uTemp[2],hexPum.conWSE.mulAnd.uTemp[3],hexPum.conWSE.or1.u[1],hexPum.conWSE.or1.y,hexPum.conWSE.or1.uTemp[2],hexPum.conWSE.tim.passed,hexPum.conWSE.tim.entryTime,hexPum.conWSE.tim1.passed,hexPum.conWSE.tim1.entryTime,hexPum.conWSE.and1.y,hexVal.conWSE.iniSta.newActive,hexVal.conWSE.iniSta.oldActive,hexVal.conWSE.ena.localCondition,hexVal.conWSE.ena.enableFire,hexVal.conWSE.actSta.newActive,hexVal.conWSE.actSta.oldActive,hexVal.conWSE.dis.localCondition,hexVal.conWSE.dis.enableFire,hexVal.conWSE.stateGraphRoot.activeSteps,hexVal.conWSE.mulAnd.u[1],hexVal.conWSE.mulAnd.uTemp[2],hexVal.conWSE.mulAnd.uTemp[3],hexVal.conWSE.or1.u[1],hexVal.conWSE.or1.y,hexVal.conWSE.or1.uTemp[2],hexVal.conWSE.tim.passed,hexVal.conWSE.tim.entryTime,hexVal.conWSE.tim1.passed,hexVal.conWSE.tim1.entryTime,hexVal.conWSE.and1.y,hexVal.uCoo,TChiWatRet.a,TChiWatRet.b,TChiWatRet.last,TChiWatRet.nextEvent,TChiWatRet.nextEventScaled,TSerWat.a,TSerWat.b,TSerWat.last,TSerWat.nextEvent,TSerWat.nextEventScaled,hexPum.conWSE.ena.t_start,hexPum.conWSE.dis.t_start,hexVal.conWSE.ena.t_start,hexVal.conWSE.dis.t_start)
 * Number of discrete states: 24 (hexVal.conWSE.dis.t_start,hexVal.conWSE.ena.t_start,hexPum.conWSE.dis.t_start,hexPum.conWSE.ena.t_start,hexPum.conWSE.iniSta.newActive,hexPum.conWSE.tim1.entryTime,hexPum.conWSE.actSta.newActive,hexPum.conWSE.tim.entryTime,hexVal.conWSE.iniSta.newActive,hexVal.conWSE.tim1.entryTime,hexVal.conWSE.actSta.newActive,hexVal.conWSE.tim.entryTime,TChiWatRet.nextEvent,TChiWatRet.last,TChiWatRet.nextEventScaled,TChiWatRet.b,TChiWatRet.a,$whenCondition14,TSerWat.nextEvent,TSerWat.last,TSerWat.nextEventScaled,TSerWat.b,TSerWat.a,$whenCondition13)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (418):
 * Single equations (assignments): 357
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 38
 * When equations: 16
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
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): 4 systems
   {(3,15), (4,14), (1,7), (1,7)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.03729/2.738, allocations: 11.73 MB / 1.956 GB, free: 1.988 MB / 1.498 GB
Notification: Performance of simCode: created initialization part: time 0.03321/2.772, allocations: 10.69 MB / 1.967 GB, free: 7.172 MB / 1.514 GB
Notification: Performance of simCode: created event and clocks part: time 1.196e-05/2.772, allocations: 0 / 1.967 GB, free: 7.172 MB / 1.514 GB
Notification: Performance of simCode: created simulation system equations: time 0.009291/2.781, allocations: 2.882 MB / 1.969 GB, free: 4.262 MB / 1.514 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02655/2.807, allocations: 3.811 MB / 1.973 GB, free: 0.5039 MB / 1.514 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1749/2.982, allocations: 60.77 MB / 2.032 GB, free: 2.363 MB / 1.576 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008729/2.991, allocations: 3.673 MB / 2.036 GB, free: 14.66 MB / 1.592 GB
Notification: Performance of simCode: alias equations: time 0.01379/3.005, allocations: 2.075 MB / 2.038 GB, free: 12.58 MB / 1.592 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.009784/3.015, allocations: 3.955 MB / 2.042 GB, free: 8.625 MB / 1.592 GB
Notification: Performance of SimCode: time 2.805e-06/3.015, allocations: 0 / 2.042 GB, free: 8.625 MB / 1.592 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 1.281/4.296, allocations: 242.3 MB / 2.279 GB, free: 0.7193 GB / 1.764 GB
Notification: Performance of buildModelFMU: Generate platform static: time 31.99/36.28, allocations: 6 kB / 2.279 GB, free: 0.7193 GB / 1.764 GB
"
[Timeout remaining time 263]
(rm -f Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.pipe ; mkfifo Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.pipe ; head -c 1048576 < Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.pipe >> ../files/Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.sim & python3 -m fmpy simulate  --output-file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv --start-time 0 --stop-time 5000 --timeout 400 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 2 Buildings_11_Buildings_DHC_ETS_Combined_Subsystems_Validation_WatersideEconomizer.fmu > Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.pipe 2>&1) [Timeout 420.0]
diffSimulationResults("Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv","/mnt/ReferenceFiles/Buildings/csv/maint_11.x/Buildings_DHC_ETS_Combined_Subsystems_Validation_WatersideEconomizer.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
"Error: Could not read variable senT1InlPum.T in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable senT1InlPum.T from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable senT2InlPum.T in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable senT2InlPum.T from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.conWSE.calTemLvg.T2WatLvg in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.conWSE.calTemLvg.T2WatLvg from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable senT2OutPum.T in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable senT2OutPum.T from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.m1_flow in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.m1_flow from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.m1_flow_nominal in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.m1_flow_nominal from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.m2_flow_nominal in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.m2_flow_nominal from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.hex.m2_flow in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.hex.m2_flow from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.conWSE.y1 in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.conWSE.y1 from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexPum.conWSE.yVal2 in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexPum.conWSE.yVal2 from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable senT1InlVal.T in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable senT1InlVal.T from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable senT2InlVal.T in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable senT2InlVal.T from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.conWSE.calTemLvg.T2WatLvg in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.conWSE.calTemLvg.T2WatLvg from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable senT2OutVal.T in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable senT2OutVal.T from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.m1_flow in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.m1_flow from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.m1_flow_nominal in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.m1_flow_nominal from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.m2_flow_nominal in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.m2_flow_nominal from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.hex.m2_flow in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.hex.m2_flow from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.conWSE.y1 in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.conWSE.y1 from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
Error: Could not read variable hexVal.conWSE.yVal2 in file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv.
Warning: Get data of variable hexVal.conWSE.yVal2 from file Buildings_11_Buildings.DHC.ETS.Combined.Subsystems.Validation.WatersideEconomizer_res.csv failed!
"
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 43.63314080517739]