Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.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.001795/0.001795, allocations: 75.75 kB / 19.77 MB, free: 1.875 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.002102/0.002102, allocations: 161.4 kB / 23.02 MB, free: 4.578 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.616/1.616, allocations: 177.2 MB / 203.4 MB, free: 8.988 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 2.984/2.984, allocations: 394.2 MB / 0.6387 GB, free: 11.92 MB / 0.6075 GB
"
[Timeout remaining time 176]
Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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: translateModel(Buildings.Fluid.AirFilters.Examples.SpaceCooling,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling")
translateModel(Buildings.Fluid.AirFilters.Examples.SpaceCooling,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling") [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.002098/0.002098, allocations: 71.89 kB / 1.009 GB, free: 14.48 MB / 0.9669 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.49/1.492, allocations: 181.8 MB / 1.187 GB, free: 16.21 MB / 1.076 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.AirFilters.Examples.SpaceCooling): time 0.5073/2, allocations: 339 MB / 1.518 GB, free: 2.152 MB / 1.357 GB
Notification: Performance of NFInst.instExpressions: time 0.09482/2.094, allocations: 45.13 MB / 1.562 GB, free: 4.906 MB / 1.404 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01274/2.107, allocations: 261.9 kB / 1.562 GB, free: 4.648 MB / 1.404 GB
Notification: Performance of NFTyping.typeComponents: time 0.01665/2.124, allocations: 3.916 MB / 1.566 GB, free: 0.7109 MB / 1.404 GB
Notification: Performance of NFTyping.typeBindings: time 0.03878/2.163, allocations: 9.927 MB / 1.576 GB, free: 6.75 MB / 1.42 GB
Notification: Performance of NFTyping.typeClassSections: time 0.02242/2.185, allocations: 6.107 MB / 1.581 GB, free: 0.668 MB / 1.42 GB
Notification: Performance of NFFlatten.flatten: time 0.0394/2.224, allocations: 18.01 MB / 1.599 GB, free: 14.62 MB / 1.451 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01554/2.24, allocations: 4.897 MB / 1.604 GB, free: 9.621 MB / 1.451 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02843/2.268, allocations: 8.429 MB / 1.612 GB, free: 1.191 MB / 1.451 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01463/2.283, allocations: 4.583 MB / 1.617 GB, free: 12.61 MB / 1.467 GB
Notification: Performance of NFPackage.collectConstants: time 0.004729/2.288, allocations: 0.7656 MB / 1.617 GB, free: 11.84 MB / 1.467 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02686/2.315, allocations: 7.443 MB / 1.625 GB, free: 4.387 MB / 1.467 GB
Notification: Performance of NFScalarize.scalarize: time 0.006681/2.321, allocations: 2.555 MB / 1.627 GB, free: 1.824 MB / 1.467 GB
Notification: Performance of NFVerifyModel.verify: time 0.01152/2.333, allocations: 3.325 MB / 1.63 GB, free: 14.48 MB / 1.482 GB
Notification: Performance of NFConvertDAE.convert: time 0.03849/2.371, allocations: 13.93 MB / 1.644 GB, free: 0.5078 MB / 1.482 GB
Notification: Performance of FrontEnd - DAE generated: time 1.356e-05/2.371, allocations: 4 kB / 1.644 GB, free: 0.5039 MB / 1.482 GB
Notification: Performance of FrontEnd: time 2.635e-06/2.371, allocations: 0 / 1.644 GB, free: 0.5039 MB / 1.482 GB
Notification: Performance of Transformations before backend: time 0.0005497/2.372, allocations: 0 / 1.644 GB, free: 0.5039 MB / 1.482 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1250
 * Number of variables: 1250
Notification: Performance of Generate backend data structure: time 0.03562/2.407, allocations: 9.129 MB / 1.653 GB, free: 7.254 MB / 1.498 GB
Notification: Performance of prepare preOptimizeDAE: time 5.912e-05/2.407, allocations: 8.031 kB / 1.653 GB, free: 7.246 MB / 1.498 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03379/2.441, allocations: 4.018 MB / 1.657 GB, free: 3.219 MB / 1.498 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0209/2.462, allocations: 6.186 MB / 1.663 GB, free: 12.94 MB / 1.514 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008047/2.463, allocations: 445.8 kB / 1.663 GB, free: 12.49 MB / 1.514 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003002/2.466, allocations: 0.5038 MB / 1.664 GB, free: 11.98 MB / 1.514 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02946/2.495, allocations: 8.559 MB / 1.672 GB, free: 3.055 MB / 1.514 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002509/2.496, allocations: 4 kB / 1.672 GB, free: 3.051 MB / 1.514 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001937/2.498, allocations: 208 kB / 1.672 GB, free: 2.848 MB / 1.514 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003172/2.498, allocations: 201.3 kB / 1.672 GB, free: 2.648 MB / 1.514 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03063/2.529, allocations: 8.87 MB / 1.681 GB, free: 9.762 MB / 1.529 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.07006/2.599, allocations: 22.51 MB / 1.703 GB, free: 2.973 MB / 1.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02128/2.62, allocations: 6.641 MB / 1.71 GB, free: 12.35 MB / 1.561 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0115/2.631, allocations: 3.527 MB / 1.713 GB, free: 8.809 MB / 1.561 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 evalFunc (simulation): time 0.02749/2.659, allocations: 8.735 MB / 1.722 GB, free: 32 kB / 1.561 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 1.386/4.045, allocations: 4.667 MB / 1.726 GB, free: 147.6 MB / 1.561 GB
Notification: Performance of pre-optimization done (n=362): time 1.365e-05/4.045, allocations: 0 / 1.726 GB, free: 147.6 MB / 1.561 GB
Notification: Performance of matching and sorting (n=369): time 0.0962/4.142, allocations: 19.81 MB / 1.746 GB, free: 145.9 MB / 1.561 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002224/4.142, allocations: 248.7 kB / 1.746 GB, free: 145.7 MB / 1.561 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01771/4.159, allocations: 4.318 MB / 1.75 GB, free: 145.3 MB / 1.561 GB
Notification: Performance of collectPreVariables (initialization): time 0.00178/4.161, allocations: 108.5 kB / 1.75 GB, free: 145.3 MB / 1.561 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003948/4.165, allocations: 2.771 MB / 1.753 GB, free: 144.6 MB / 1.561 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003407/4.169, allocations: 1.303 MB / 1.754 GB, free: 143.9 MB / 1.561 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002916/4.172, allocations: 0.8769 MB / 1.755 GB, free: 143.9 MB / 1.561 GB
Notification: Performance of setup shared object (initialization): time 0.0001072/4.172, allocations: 305.7 kB / 1.755 GB, free: 143.6 MB / 1.561 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01186/4.183, allocations: 3.359 MB / 1.758 GB, free: 143.5 MB / 1.561 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01468/4.198, allocations: 4.882 MB / 1.763 GB, free: 141.9 MB / 1.561 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02434/4.222, allocations: 6.58 MB / 1.77 GB, free: 141.2 MB / 1.561 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001057/4.223, allocations: 5 kB / 1.77 GB, free: 141.2 MB / 1.561 GB
Notification: Performance of matching and sorting (n=512) (initialization): time 0.0332/4.256, allocations: 8.638 MB / 1.778 GB, free: 140.7 MB / 1.561 GB
Notification: Performance of prepare postOptimizeDAE: time 5.783e-05/4.256, allocations: 18.16 kB / 1.778 GB, free: 140.7 MB / 1.561 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.165e-05/4.256, allocations: 14.06 kB / 1.778 GB, free: 140.7 MB / 1.561 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003143/4.259, allocations: 0.9029 MB / 1.779 GB, free: 140.6 MB / 1.561 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008332/4.267, allocations: 1.315 MB / 1.78 GB, free: 140.6 MB / 1.561 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01284/4.28, allocations: 11.17 MB / 1.791 GB, free: 132.8 MB / 1.561 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01374/4.294, allocations: 0.5588 MB / 1.792 GB, free: 132.8 MB / 1.561 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002077/4.296, allocations: 219.6 kB / 1.792 GB, free: 132.8 MB / 1.561 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.02384/4.32, allocations: 4.492 MB / 1.796 GB, free: 132.6 MB / 1.561 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01994/4.34, allocations: 4.859 MB / 1.801 GB, free: 130.9 MB / 1.561 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03416/4.374, allocations: 6.544 MB / 1.807 GB, free: 130.1 MB / 1.561 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.326e-05/4.374, allocations: 5.188 kB / 1.807 GB, free: 130.1 MB / 1.561 GB
Notification: Performance of matching and sorting (n=512) (initialization_lambda0): time 0.04292/4.417, allocations: 8.586 MB / 1.816 GB, free: 129.5 MB / 1.561 GB
Notification: Performance of prepare postOptimizeDAE: time 4.967e-05/4.417, allocations: 15.88 kB / 1.816 GB, free: 129.5 MB / 1.561 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.898e-05/4.417, allocations: 15.88 kB / 1.816 GB, free: 129.5 MB / 1.561 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.002915/4.42, allocations: 0.8733 MB / 1.817 GB, free: 129.4 MB / 1.561 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008375/4.428, allocations: 1.312 MB / 1.818 GB, free: 129.4 MB / 1.561 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01839/4.447, allocations: 12.88 MB / 1.831 GB, free: 120.7 MB / 1.561 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0104/4.457, allocations: 0.584 MB / 1.831 GB, free: 120.7 MB / 1.561 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001507/4.459, allocations: 214.8 kB / 1.831 GB, free: 120.7 MB / 1.561 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 74
 * Number of states: 0 ()
 * Number of discrete variables: 27 (airFil.masAcc.edg.u,$PRE.airFil.masAcc.edg.u,airFil.masAcc.edg.y,$whenCondition6,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,cooCoi.flowRegime_nominal,cooCoi.UAFroRated.isFulWet,cooCoi.UAFroRated.isFulDry,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,airFil.masAcc.notFul.greHys.y,$PRE.airFil.masAcc.notFul.greHys.y,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,conRoo.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (470):
 * Single equations (assignments): 459
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 7
 * 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): 7 systems
   {(1,2), (1,18), (1,2), (1,4), (5,2), (1,5), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001863/4.461, allocations: 428.1 kB / 1.832 GB, free: 120.5 MB / 1.561 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002557/4.463, allocations: 0.5489 MB / 1.832 GB, free: 120.2 MB / 1.561 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02649/4.49, allocations: 8.388 MB / 1.841 GB, free: 119.7 MB / 1.561 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02687/4.516, allocations: 7.092 MB / 1.847 GB, free: 119.3 MB / 1.561 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.961e-05/4.516, allocations: 8 kB / 1.847 GB, free: 119.3 MB / 1.561 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.006361/4.523, allocations: 1.279 MB / 1.849 GB, free: 119 MB / 1.561 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003175/4.526, allocations: 82.03 kB / 1.849 GB, free: 119 MB / 1.561 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.008883/4.535, allocations: 0.5366 MB / 1.849 GB, free: 118.9 MB / 1.561 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001228/4.536, allocations: 274.3 kB / 1.85 GB, free: 118.9 MB / 1.561 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.03146/4.568, allocations: 7.98 MB / 1.857 GB, free: 118.4 MB / 1.561 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.03707/4.605, allocations: 11.78 MB / 1.869 GB, free: 117.5 MB / 1.561 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01143/4.616, allocations: 3.411 MB / 1.872 GB, free: 117.4 MB / 1.561 GB
Notification: Performance of sorting global known variables: time 0.00872/4.625, allocations: 2.878 MB / 1.875 GB, free: 117.3 MB / 1.561 GB
Notification: Performance of Backend: time 6.01e-07/4.625, allocations: 0 / 1.875 GB, free: 117.3 MB / 1.561 GB
Notification: Performance of simCode: created initialization part: time 0.04953/4.674, allocations: 12.18 MB / 1.887 GB, free: 113.9 MB / 1.561 GB
Notification: Performance of SimCode: time 0.03733/4.712, allocations: 12.84 MB / 1.899 GB, free: 103.3 MB / 1.561 GB
Notification: Performance of Templates: time 0.3228/5.034, allocations: 190.1 MB / 2.085 GB, free: 14.94 MB / 1.654 GB
"
[Timeout remaining time 295]
make -j1 -f Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.makefile [Timeout 300]
(rm -f Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.pipe ; mkfifo Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.pipe ; head -c 1048576 < Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.pipe >> ../files/Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.sim & ./Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling  -abortSlowSimulation -alarm=800 -lv LOG_STATS > Buildings_latest_Buildings.Fluid.AirFilters.Examples.SpaceCooling.pipe 2>&1) [Timeout 800]
[Calling os._exit(0), Time elapsed: 20.901566231972538]
Failed to read output from testmodel.py, exit status != 0:
5.047936038929038 5.065943997 2.690766009
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f34017f2fe0>