Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.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.001744/0.001744, allocations: 89.47 kB / 19.44 MB, free: 340 kB / 13.93 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.002242/0.002242, allocations: 177.2 kB / 22.76 MB, free: 1.66 MB / 18.57 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.537/1.537, allocations: 177.1 MB / 203.1 MB, free: 5.617 MB / 186.7 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:34:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Examples/PPD12/Structure.mo:531:21-535:42:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/package.mo): time 1.334/1.334, allocations: 154.8 MB / 414.3 MB, free: 480 kB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS master/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 178]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 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(IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy")
translateModel(IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.553e-06/1.553e-06, allocations: 0 / 0.5198 GB, free: 2.312 MB / 490.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.183e-05/3.338e-05, allocations: 4.141 kB / 0.5199 GB, free: 2.309 MB / 490.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy): time 0.6929/0.6929, allocations: 315.5 MB / 0.8279 GB, free: 9.535 MB / 0.6355 GB
Notification: Performance of NFInst.instExpressions: time 0.03054/0.7235, allocations: 19.15 MB / 0.8466 GB, free: 12.75 MB / 0.6511 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005278/0.7287, allocations: 115.5 kB / 0.8468 GB, free: 12.7 MB / 0.6511 GB
Notification: Performance of NFTyping.typeComponents: time 0.007214/0.736, allocations: 2.07 MB / 0.8488 GB, free: 11.57 MB / 0.6511 GB
Notification: Performance of NFTyping.typeBindings: time 0.02414/0.7601, allocations: 7.907 MB / 0.8565 GB, free: 6.117 MB / 0.6511 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009962/0.7701, allocations: 3.355 MB / 0.8598 GB, free: 3.547 MB / 0.6511 GB
Notification: Performance of NFFlatten.flatten: time 0.01542/0.7855, allocations: 9.441 MB / 0.869 GB, free: 10.85 MB / 0.6667 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005373/0.7909, allocations: 2.508 MB / 0.8714 GB, free: 8.285 MB / 0.6667 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01408/0.8049, allocations: 4.953 MB / 0.8763 GB, free: 3.32 MB / 0.6667 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.007698/0.8126, allocations: 3.093 MB / 0.8793 GB, free: 272 kB / 0.6667 GB
Notification: Performance of NFPackage.collectConstants: time 0.00126/0.8139, allocations: 408 kB / 0.8797 GB, free: 15.86 MB / 0.6823 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01572/0.8296, allocations: 5.864 MB / 0.8854 GB, free: 9.984 MB / 0.6823 GB
Notification: Performance of NFScalarize.scalarize: time 0.003185/0.8328, allocations: 1.812 MB / 0.8872 GB, free: 8.168 MB / 0.6823 GB
Notification: Performance of NFVerifyModel.verify: time 0.004096/0.8369, allocations: 1.688 MB / 0.8888 GB, free: 6.477 MB / 0.6823 GB
Notification: Performance of NFConvertDAE.convert: time 0.01858/0.8555, allocations: 8.417 MB / 0.8971 GB, free: 14.04 MB / 0.698 GB
Notification: Performance of FrontEnd - DAE generated: time 7.795e-06/0.8555, allocations: 7.938 kB / 0.8971 GB, free: 14.03 MB / 0.698 GB
Notification: Performance of FrontEnd: time 1.643e-06/0.8555, allocations: 4 kB / 0.8971 GB, free: 14.02 MB / 0.698 GB
Notification: Performance of Transformations before backend: time 0.0001363/0.8556, allocations: 0 / 0.8971 GB, free: 14.02 MB / 0.698 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 885
 * Number of variables: 885
Notification: Performance of Generate backend data structure: time 0.01381/0.8694, allocations: 5.269 MB / 0.9022 GB, free: 8.688 MB / 0.698 GB
Notification: Performance of prepare preOptimizeDAE: time 4.894e-05/0.8695, allocations: 8.031 kB / 0.9022 GB, free: 8.68 MB / 0.698 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01736/0.8868, allocations: 2.639 MB / 0.9048 GB, free: 6.031 MB / 0.698 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006398/0.8932, allocations: 2.206 MB / 0.9069 GB, free: 3.773 MB / 0.698 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002881/0.8935, allocations: 254.5 kB / 0.9072 GB, free: 3.523 MB / 0.698 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007991/0.8943, allocations: 339.9 kB / 0.9075 GB, free: 3.191 MB / 0.698 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009982/0.9043, allocations: 4.662 MB / 0.9121 GB, free: 14.33 MB / 0.7136 GB
Notification: Performance of preOpt findStateOrder (simulation): time 8.674e-05/0.9044, allocations: 0 / 0.9121 GB, free: 14.33 MB / 0.7136 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004359/0.9048, allocations: 136 kB / 0.9122 GB, free: 14.2 MB / 0.7136 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002651/0.9051, allocations: 214.6 kB / 0.9124 GB, free: 13.98 MB / 0.7136 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01092/0.916, allocations: 4.484 MB / 0.9168 GB, free: 9.492 MB / 0.7136 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.02635/0.9424, allocations: 11.92 MB / 0.9284 GB, free: 13.43 MB / 0.7292 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009767/0.9521, allocations: 4.137 MB / 0.9325 GB, free: 9.293 MB / 0.7292 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004986/0.9571, allocations: 1.958 MB / 0.9344 GB, free: 7.332 MB / 0.7292 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004085/0.9575, allocations: 103 kB / 0.9345 GB, free: 7.23 MB / 0.7292 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.06e-05/0.9576, allocations: 77.48 kB / 0.9345 GB, free: 7.145 MB / 0.7292 GB
Notification: Performance of pre-optimization done (n=392): time 6.522e-06/0.9576, allocations: 0 / 0.9345 GB, free: 7.145 MB / 0.7292 GB
Notification: Performance of matching and sorting (n=392): time 0.01416/0.9718, allocations: 5.049 MB / 0.9395 GB, free: 2.074 MB / 0.7292 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001094/0.9719, allocations: 94.48 kB / 0.9396 GB, free: 1.969 MB / 0.7292 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00302/0.9749, allocations: 1.843 MB / 0.9414 GB, free: 120 kB / 0.7292 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004044/0.9753, allocations: 89.7 kB / 0.9414 GB, free: 24 kB / 0.7292 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001388/0.9767, allocations: 1.397 MB / 0.9428 GB, free: 14.61 MB / 0.7448 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001927/0.9786, allocations: 1.48 MB / 0.9443 GB, free: 13.12 MB / 0.7448 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001661/0.9803, allocations: 0.7275 MB / 0.945 GB, free: 12.38 MB / 0.7448 GB
Notification: Performance of setup shared object (initialization): time 0.0002358/0.9805, allocations: 476.8 kB / 0.9454 GB, free: 11.91 MB / 0.7448 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003971/0.9845, allocations: 1.495 MB / 0.9469 GB, free: 10.41 MB / 0.7448 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004607/0.9891, allocations: 2.095 MB / 0.9489 GB, free: 8.215 MB / 0.7448 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009236/0.9983, allocations: 3.924 MB / 0.9528 GB, free: 4.184 MB / 0.7448 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.432e-05/0.9983, allocations: 0 / 0.9528 GB, free: 4.184 MB / 0.7448 GB
Notification: Performance of matching and sorting (n=472) (initialization): time 0.2545/1.253, allocations: 4.276 MB / 0.9569 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of prepare postOptimizeDAE: time 3.859e-05/1.253, allocations: 5.5 kB / 0.9569 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.727e-05/1.253, allocations: 8.938 kB / 0.9569 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.844e-05/1.253, allocations: 21.41 kB / 0.957 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005864/1.259, allocations: 0.8792 MB / 0.9578 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 8.058e-05/1.259, allocations: 25.88 kB / 0.9579 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004961/1.264, allocations: 397.2 kB / 0.9582 GB, free: 292.8 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008382/1.265, allocations: 136.1 kB / 0.9584 GB, free: 292.8 MB / 0.7448 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 22
 * 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 (472):
 * Single equations (assignments): 472
 * 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.001713/1.266, allocations: 0.5534 MB / 0.9589 GB, free: 292.5 MB / 0.7448 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001147/1.268, allocations: 414.6 kB / 0.9593 GB, free: 292.5 MB / 0.7448 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001061/1.269, allocations: 442.1 kB / 0.9597 GB, free: 292.5 MB / 0.7448 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.317e-05/1.269, allocations: 41.97 kB / 0.9598 GB, free: 292.5 MB / 0.7448 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.899e-05/1.269, allocations: 0 / 0.9598 GB, free: 292.5 MB / 0.7448 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.00694/1.276, allocations: 3.412 MB / 0.9631 GB, free: 292.2 MB / 0.7448 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01577/1.291, allocations: 7.693 MB / 0.9706 GB, free: 290.3 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.383e-05/1.291, allocations: 2.938 kB / 0.9706 GB, free: 290.3 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005104/1.297, allocations: 0.8423 MB / 0.9714 GB, free: 290.2 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (simulation): time 4.907e-05/1.297, allocations: 18.31 kB / 0.9714 GB, free: 290.2 MB / 0.7448 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004461/1.297, allocations: 95.55 kB / 0.9715 GB, free: 290.2 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.004e-05/1.297, allocations: 20.62 kB / 0.9716 GB, free: 290.1 MB / 0.7448 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.257e-06/1.297, allocations: 3.969 kB / 0.9716 GB, free: 290.1 MB / 0.7448 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01214/1.309, allocations: 6.044 MB / 0.9775 GB, free: 288.1 MB / 0.7448 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002924/1.312, allocations: 0.7779 MB / 0.9782 GB, free: 287.7 MB / 0.7448 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008537/1.313, allocations: 75.16 kB / 0.9783 GB, free: 287.6 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00381/1.317, allocations: 194.8 kB / 0.9785 GB, free: 287.6 MB / 0.7448 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005648/1.317, allocations: 242 kB / 0.9787 GB, free: 287.6 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003042/1.318, allocations: 124.4 kB / 0.9788 GB, free: 287.5 MB / 0.7448 GB
Notification: Performance of sorting global known variables: time 0.001551/1.319, allocations: 1.08 MB / 0.9799 GB, free: 287.1 MB / 0.7448 GB
Notification: Performance of sort global known variables: time 1.1e-07/1.319, allocations: 0.7656 kB / 0.9799 GB, free: 287.1 MB / 0.7448 GB
Notification: Performance of remove unused functions: time 0.004255/1.323, allocations: 1.366 MB / 0.9812 GB, free: 286.9 MB / 0.7448 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 20 (volBotEas.dynBal.medium.Xi[1],volBotEas.dynBal.U,volBotEas.dynBal.m,oriOutBot.mExc,oriOutTop.mExc,oriWesTop.mExc,dooOpeClo.mExcAB,dooOpeClo.mExcBA,volBotWes.dynBal.medium.Xi[1],volBotWes.dynBal.U,volBotWes.dynBal.m,oriEasTop.mExc,volTopEas.dynBal.medium.Xi[1],volTopEas.dynBal.U,volTopEas.dynBal.m,volTopWes.dynBal.medium.Xi[1],volTopWes.dynBal.U,volTopWes.dynBal.m,dooOpeCloTop.mExcAB,dooOpeCloTop.mExcBA)
 * 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 (392):
 * Single equations (assignments): 392
 * 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.0008947/1.324, allocations: 397.4 kB / 0.9816 GB, free: 286.7 MB / 0.7448 GB
Notification: Performance of simCode: created initialization part: time 0.009325/1.334, allocations: 3.809 MB / 0.9853 GB, free: 285.8 MB / 0.7448 GB
Notification: Performance of simCode: created event and clocks part: time 1.028e-05/1.334, allocations: 4.188 kB / 0.9853 GB, free: 285.8 MB / 0.7448 GB
Notification: Performance of simCode: created simulation system equations: time 0.00481/1.339, allocations: 2.011 MB / 0.9873 GB, free: 285.3 MB / 0.7448 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.008766/1.347, allocations: 1.569 MB / 0.9888 GB, free: 285 MB / 0.7448 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01493/1.362, allocations: 7.218 MB / 0.9959 GB, free: 281.8 MB / 0.7448 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001961/1.364, allocations: 1.578 MB / 0.9974 GB, free: 281.5 MB / 0.7448 GB
Notification: Performance of simCode: alias equations: time 0.00555/1.37, allocations: 1.163 MB / 0.9985 GB, free: 281.4 MB / 0.7448 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00158/1.371, allocations: 280.7 kB / 0.9988 GB, free: 281.3 MB / 0.7448 GB
Notification: Performance of SimCode: time 2.234e-06/1.371, allocations: 4 kB / 0.9988 GB, free: 281.3 MB / 0.7448 GB
Notification: Performance of Templates: time 0.09506/1.466, allocations: 72.31 MB / 1.069 GB, free: 218.5 MB / 0.7448 GB
"
[Timeout remaining time 659]
make -j1 -f IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.makefile [Timeout 660]
(rm -f IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.pipe ; mkfifo IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.pipe >> ../files/IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.sim & ./IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy -abortSlowSimulation -alarm=240  -lv LOG_STATS > IDEAS_dev_IDEAS.Airflow.Multizone.Examples.ReverseBuoyancy.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 10.45607580395881]