Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones")
translateModel(IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001533/0.001533, allocations: 99.06 kB / 16.63 MB, free: 6.828 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001568/0.001568, allocations: 185.9 kB / 17.56 MB, free: 5.938 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.385/1.385, allocations: 222.9 MB / 241.2 MB, free: 15.39 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.8342/0.8342, allocations: 125.4 MB / 416.7 MB, free: 13.79 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.002025/0.002025, allocations: 63.89 kB / 0.4943 GB, free: 4.801 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3746/0.3767, allocations: 78.55 MB / 0.571 GB, free: 31.52 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones): time 0.6075/0.9843, allocations: 291.8 MB / 0.856 GB, free: 15.6 MB / 0.5919 GB
Notification: Performance of NFInst.instExpressions: time 0.02353/1.008, allocations: 16.95 MB / 0.8725 GB, free: 8.969 MB / 0.5919 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004139/1.012, allocations: 68.72 kB / 0.8726 GB, free: 8.969 MB / 0.5919 GB
Notification: Performance of NFTyping.typeComponents: time 0.005285/1.017, allocations: 1.699 MB / 0.8742 GB, free: 8.148 MB / 0.5919 GB
Notification: Performance of NFTyping.typeBindings: time 0.01706/1.034, allocations: 6.057 MB / 0.8802 GB, free: 4.199 MB / 0.5919 GB
Notification: Performance of NFTyping.typeClassSections: time 0.008149/1.043, allocations: 2.703 MB / 0.8828 GB, free: 2.012 MB / 0.5919 GB
Notification: Performance of NFFlatten.flatten: time 0.01121/1.054, allocations: 7.712 MB / 0.8903 GB, free: 10.87 MB / 0.6075 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004276/1.058, allocations: 2.546 MB / 0.8928 GB, free: 8.297 MB / 0.6075 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006734/1.065, allocations: 3.051 MB / 0.8958 GB, free: 5.402 MB / 0.6075 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006006/1.071, allocations: 2.848 MB / 0.8986 GB, free: 2.566 MB / 0.6075 GB
Notification: Performance of NFPackage.collectConstants: time 0.0009007/1.072, allocations: 328 kB / 0.8989 GB, free: 2.246 MB / 0.6075 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01345/1.085, allocations: 6.421 MB / 0.9052 GB, free: 11.81 MB / 0.6231 GB
Notification: Performance of NFScalarize.scalarize: time 0.002217/1.088, allocations: 1.333 MB / 0.9065 GB, free: 10.47 MB / 0.6231 GB
Notification: Performance of NFVerifyModel.verify: time 0.00328/1.091, allocations: 1.886 MB / 0.9083 GB, free: 8.578 MB / 0.6231 GB
Notification: Performance of NFConvertDAE.convert: time 0.009057/1.1, allocations: 5.114 MB / 0.9133 GB, free: 3.453 MB / 0.6231 GB
Notification: Performance of FrontEnd - DAE generated: time 6.572e-06/1.1, allocations: 0 / 0.9133 GB, free: 3.453 MB / 0.6231 GB
Notification: Performance of FrontEnd: time 1.623e-06/1.1, allocations: 0 / 0.9133 GB, free: 3.453 MB / 0.6231 GB
Notification: Performance of Transformations before backend: time 0.0001122/1.1, allocations: 4 kB / 0.9133 GB, free: 3.449 MB / 0.6231 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 669
 * Number of variables: 669
Notification: Performance of Generate backend data structure: time 0.01216/1.112, allocations: 4.12 MB / 0.9173 GB, free: 15.26 MB / 0.6387 GB
Notification: Performance of prepare preOptimizeDAE: time 5.123e-05/1.112, allocations: 8.031 kB / 0.9173 GB, free: 15.25 MB / 0.6387 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006908/1.119, allocations: 0.9211 MB / 0.9182 GB, free: 14.32 MB / 0.6387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004926/1.124, allocations: 1.838 MB / 0.92 GB, free: 12.43 MB / 0.6387 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000235/1.125, allocations: 197 kB / 0.9202 GB, free: 12.23 MB / 0.6387 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007333/1.125, allocations: 263.9 kB / 0.9205 GB, free: 11.98 MB / 0.6387 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007386/1.133, allocations: 3.331 MB / 0.9237 GB, free: 8.543 MB / 0.6387 GB
Notification: Performance of preOpt findStateOrder (simulation): time 6.951e-05/1.133, allocations: 7.938 kB / 0.9237 GB, free: 8.535 MB / 0.6387 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003162/1.133, allocations: 112 kB / 0.9238 GB, free: 8.426 MB / 0.6387 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001702/1.133, allocations: 129.1 kB / 0.924 GB, free: 8.297 MB / 0.6387 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007009/1.14, allocations: 3.502 MB / 0.9274 GB, free: 4.781 MB / 0.6387 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.02118/1.162, allocations: 12.33 MB / 0.9394 GB, free: 7.492 MB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.005135/1.167, allocations: 3.509 MB / 0.9428 GB, free: 3.902 MB / 0.6544 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003042/1.17, allocations: 1.818 MB / 0.9446 GB, free: 2.082 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.00179/1.172, allocations: 1.497 MB / 0.9461 GB, free: 276 kB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.302e-05/1.172, allocations: 64.75 kB / 0.9461 GB, free: 200 kB / 0.6544 GB
Notification: Performance of pre-optimization done (n=278): time 4.318e-06/1.172, allocations: 4 kB / 0.9461 GB, free: 196 kB / 0.6544 GB
Notification: Performance of matching and sorting (n=278): time 0.007618/1.179, allocations: 3.873 MB / 0.9499 GB, free: 12.26 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.366e-05/1.179, allocations: 81.58 kB / 0.95 GB, free: 12.17 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002402/1.182, allocations: 1.376 MB / 0.9514 GB, free: 10.79 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003963/1.182, allocations: 73.7 kB / 0.9514 GB, free: 10.71 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009025/1.183, allocations: 0.9881 MB / 0.9524 GB, free: 9.711 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001272/1.184, allocations: 0.947 MB / 0.9533 GB, free: 8.75 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007414/1.185, allocations: 404.9 kB / 0.9537 GB, free: 8.348 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001524/1.185, allocations: 301.1 kB / 0.954 GB, free: 8.051 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002024/1.187, allocations: 1.098 MB / 0.9551 GB, free: 6.949 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002255/1.19, allocations: 1.362 MB / 0.9564 GB, free: 5.52 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004654/1.194, allocations: 2.646 MB / 0.959 GB, free: 2.781 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.139e-05/1.194, allocations: 0 / 0.959 GB, free: 2.781 MB / 0.67 GB
Notification: Performance of matching and sorting (n=311) (initialization): time 0.005853/1.2, allocations: 2.977 MB / 0.9619 GB, free: 15.75 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 3.535e-05/1.2, allocations: 10.59 kB / 0.9619 GB, free: 15.75 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.64e-05/1.2, allocations: 4 kB / 0.9619 GB, free: 15.74 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.365e-05/1.2, allocations: 20 kB / 0.9619 GB, free: 15.72 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002382/1.203, allocations: 0.5071 MB / 0.9624 GB, free: 15.21 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.726e-05/1.203, allocations: 19.98 kB / 0.9624 GB, free: 15.2 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003054/1.206, allocations: 381.7 kB / 0.9628 GB, free: 14.82 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002028/1.206, allocations: 80 kB / 0.9629 GB, free: 14.74 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 19
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (311):
 * Single equations (assignments): 311
 * 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.001328/1.207, allocations: 0.6494 MB / 0.9635 GB, free: 14.08 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006229/1.208, allocations: 233.2 kB / 0.9637 GB, free: 13.85 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005694/1.214, allocations: 3.112 MB / 0.9668 GB, free: 10.68 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.765e-05/1.214, allocations: 35.92 kB / 0.9668 GB, free: 10.65 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.192e-05/1.214, allocations: 0 / 0.9668 GB, free: 10.65 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004278/1.218, allocations: 2.657 MB / 0.9694 GB, free: 7.922 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0108/1.229, allocations: 6.217 MB / 0.9755 GB, free: 1.602 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.456e-06/1.229, allocations: 0 / 0.9755 GB, free: 1.602 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002629/1.232, allocations: 0.5423 MB / 0.976 GB, free: 1.059 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 2.258e-05/1.232, allocations: 8 kB / 0.976 GB, free: 1.051 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001831/1.232, allocations: 63.98 kB / 0.9761 GB, free: 0.9883 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.315e-05/1.232, allocations: 8 kB / 0.9761 GB, free: 0.9805 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.054e-06/1.232, allocations: 0 / 0.9761 GB, free: 0.9805 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.007234/1.239, allocations: 4.573 MB / 0.9805 GB, free: 12.33 MB / 0.7012 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001404/1.24, allocations: 0.6109 MB / 0.9811 GB, free: 11.7 MB / 0.7012 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003295/1.241, allocations: 48 kB / 0.9812 GB, free: 11.65 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002046/1.243, allocations: 230.5 kB / 0.9814 GB, free: 11.43 MB / 0.7012 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003364/1.243, allocations: 162 kB / 0.9816 GB, free: 11.27 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001738/1.243, allocations: 83.97 kB / 0.9816 GB, free: 11.18 MB / 0.7012 GB
Notification: Performance of sorting global known variables: time 0.001375/1.245, allocations: 0.95 MB / 0.9826 GB, free: 10.24 MB / 0.7012 GB
Notification: Performance of sort global known variables: time 3.01e-07/1.245, allocations: 4 kB / 0.9826 GB, free: 10.23 MB / 0.7012 GB
Notification: Performance of remove unused functions: time 0.002202/1.247, allocations: 0.9784 MB / 0.9835 GB, free: 9.254 MB / 0.7012 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 18 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,oriOutBot.mExc,oriOutTop.mExc,volOut.dynBal.medium.Xi[1],volOut.dynBal.U,volOut.dynBal.m,oriWesTop.mExc,dooOpeClo.mExcAB,dooOpeClo.mExcBA,oriEasTop.mExc,volTop.dynBal.medium.Xi[1],volTop.dynBal.U,volTop.dynBal.m,volWes.dynBal.medium.Xi[1],volWes.dynBal.U,volWes.dynBal.m)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (278):
 * Single equations (assignments): 278
 * 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 Backend phase and start with SimCode phase: time 0.00065/1.248, allocations: 375.4 kB / 0.9839 GB, free: 8.879 MB / 0.7012 GB
Notification: Performance of simCode: created initialization part: time 0.01257/1.26, allocations: 5.528 MB / 0.9893 GB, free: 3.32 MB / 0.7012 GB
Notification: Performance of simCode: created event and clocks part: time 7.284e-06/1.26, allocations: 5.125 kB / 0.9893 GB, free: 3.316 MB / 0.7012 GB
Notification: Performance of simCode: created simulation system equations: time 0.002261/1.263, allocations: 1.507 MB / 0.9908 GB, free: 1.805 MB / 0.7012 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005942/1.269, allocations: 1.346 MB / 0.9921 GB, free: 0.5312 MB / 0.7012 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0109/1.279, allocations: 7.379 MB / 0.9993 GB, free: 9.098 MB / 0.7169 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001678/1.281, allocations: 1.895 MB / 1.001 GB, free: 7.164 MB / 0.7169 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001219/1.282, allocations: 235.4 kB / 1.001 GB, free: 6.938 MB / 0.7169 GB
Notification: Performance of SimCode: time 5.01e-07/1.282, allocations: 0 / 1.001 GB, free: 6.938 MB / 0.7169 GB
Notification: Performance of Templates: time 0.3234/1.606, allocations: 42.8 MB / 1.043 GB, free: 300.3 MB / 0.7325 GB
make -j1 -f IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.makefile
(rm -f IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe ; mkfifo IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe >> ../files/IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.sim & ./IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe 2>&1)