Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.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.0009165/0.0009165, allocations: 80.55 kB / 19.96 MB, free: 4.598 MB / 18.57 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.0009428/0.0009428, allocations: 173.2 kB / 23.24 MB, free: 1.324 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 0.9245/0.9245, allocations: 177.1 MB / 203.5 MB, free: 5.754 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 1.782/1.782, allocations: 389.6 MB / 0.6343 GB, free: 12.59 MB / 0.6042 GB
"
[Timeout remaining time 178]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.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.Airflow.Multizone.Examples.ReverseBuoyancy3Zones,fileNamePrefix="Buildings_13_Buildings_Airflow_Multizone_Examples_ReverseBuoyancy3Zones",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones,fileNamePrefix="Buildings_13_Buildings_Airflow_Multizone_Examples_ReverseBuoyancy3Zones",fmuType="me",version="2.0",platforms={"static"}) [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 2.104e-06/2.104e-06, allocations: 0 / 1.001 GB, free: 12.41 MB / 0.9167 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.969e-05/2.179e-05, allocations: 6.281 kB / 1.001 GB, free: 12.4 MB / 0.9167 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones): time 0.7242/0.7243, allocations: 262.3 MB / 1.257 GB, free: 11.62 MB / 1.104 GB
Notification: Performance of NFInst.instExpressions: time 0.01992/0.7442, allocations: 16.04 MB / 1.272 GB, free: 11.54 MB / 1.12 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003699/0.7479, allocations: 107.2 kB / 1.273 GB, free: 11.43 MB / 1.12 GB
Notification: Performance of NFTyping.typeComponents: time 0.003674/0.7516, allocations: 1.813 MB / 1.274 GB, free: 9.609 MB / 1.12 GB
Notification: Performance of NFTyping.typeBindings: time 0.01533/0.7669, allocations: 6.969 MB / 1.281 GB, free: 2.609 MB / 1.12 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006935/0.7738, allocations: 3.058 MB / 1.284 GB, free: 15.55 MB / 1.135 GB
Notification: Performance of NFFlatten.flatten: time 0.008288/0.7821, allocations: 7.996 MB / 1.292 GB, free: 7.527 MB / 1.135 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002437/0.7845, allocations: 1.986 MB / 1.294 GB, free: 5.488 MB / 1.135 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005622/0.7902, allocations: 4.111 MB / 1.298 GB, free: 1.367 MB / 1.135 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003209/0.7934, allocations: 2.548 MB / 1.3 GB, free: 14.83 MB / 1.151 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003245/0.7937, allocations: 332 kB / 1.301 GB, free: 14.5 MB / 1.151 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.00987/0.8036, allocations: 5.167 MB / 1.306 GB, free: 9.32 MB / 1.151 GB
Notification: Performance of NFScalarize.scalarize: time 0.001431/0.805, allocations: 1.437 MB / 1.307 GB, free: 7.879 MB / 1.151 GB
Notification: Performance of NFVerifyModel.verify: time 0.001922/0.8069, allocations: 1.372 MB / 1.308 GB, free: 6.504 MB / 1.151 GB
Notification: Performance of NFConvertDAE.convert: time 0.01307/0.82, allocations: 7.035 MB / 1.315 GB, free: 15.45 MB / 1.167 GB
Notification: Performance of FrontEnd - DAE generated: time 4.899e-06/0.82, allocations: 0 / 1.315 GB, free: 15.45 MB / 1.167 GB
Notification: Performance of FrontEnd: time 1.994e-06/0.82, allocations: 0 / 1.315 GB, free: 15.45 MB / 1.167 GB
Notification: Performance of Transformations before backend: time 4.095e-05/0.82, allocations: 0 / 1.315 GB, free: 15.45 MB / 1.167 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 690
 * Number of variables: 690
Notification: Performance of Generate backend data structure: time 0.005969/0.826, allocations: 4.112 MB / 1.319 GB, free: 11.28 MB / 1.167 GB
Notification: Performance of prepare preOptimizeDAE: time 4.693e-05/0.8261, allocations: 9.344 kB / 1.319 GB, free: 11.27 MB / 1.167 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003748/0.8264, allocations: 379.6 kB / 1.32 GB, free: 10.89 MB / 1.167 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005919/0.8323, allocations: 1.59 MB / 1.321 GB, free: 9.301 MB / 1.167 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002746/0.8351, allocations: 1.868 MB / 1.323 GB, free: 7.379 MB / 1.167 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002004/0.8353, allocations: 197.1 kB / 1.323 GB, free: 7.184 MB / 1.167 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003112/0.8356, allocations: 263.9 kB / 1.324 GB, free: 6.926 MB / 1.167 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004799/0.8404, allocations: 4.256 MB / 1.328 GB, free: 2.469 MB / 1.167 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.431e-05/0.8404, allocations: 4 kB / 1.328 GB, free: 2.465 MB / 1.167 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002311/0.8407, allocations: 104 kB / 1.328 GB, free: 2.363 MB / 1.167 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001068/0.8408, allocations: 165.1 kB / 1.328 GB, free: 2.199 MB / 1.167 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005167/0.846, allocations: 4.248 MB / 1.332 GB, free: 13.94 MB / 1.182 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.01045/0.8564, allocations: 9.06 MB / 1.341 GB, free: 4.73 MB / 1.182 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004426/0.8608, allocations: 3.581 MB / 1.344 GB, free: 1.164 MB / 1.182 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002426/0.8633, allocations: 2.005 MB / 1.346 GB, free: 15.15 MB / 1.198 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001155/0.8634, allocations: 42.17 kB / 1.346 GB, free: 15.11 MB / 1.198 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.064e-05/0.8634, allocations: 64.75 kB / 1.347 GB, free: 15.03 MB / 1.198 GB
Notification: Performance of pre-optimization done (n=278): time 3.777e-06/0.8634, allocations: 0 / 1.347 GB, free: 15.03 MB / 1.198 GB
Notification: Performance of matching and sorting (n=278): time 0.006618/0.87, allocations: 4.784 MB / 1.351 GB, free: 10.2 MB / 1.198 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.574e-05/0.8701, allocations: 81.59 kB / 1.351 GB, free: 10.1 MB / 1.198 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001641/0.8717, allocations: 1.435 MB / 1.353 GB, free: 8.66 MB / 1.198 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001728/0.8719, allocations: 73.7 kB / 1.353 GB, free: 8.582 MB / 1.198 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005669/0.8725, allocations: 1.048 MB / 1.354 GB, free: 7.523 MB / 1.198 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007907/0.8733, allocations: 0.9836 MB / 1.355 GB, free: 6.531 MB / 1.198 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005669/0.8738, allocations: 449.4 kB / 1.355 GB, free: 6.086 MB / 1.198 GB
Notification: Performance of setup shared object (initialization): time 8.673e-05/0.8739, allocations: 301.1 kB / 1.355 GB, free: 5.789 MB / 1.198 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001922/0.8758, allocations: 1.481 MB / 1.357 GB, free: 4.305 MB / 1.198 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002102/0.8779, allocations: 1.753 MB / 1.359 GB, free: 2.488 MB / 1.198 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003763/0.8817, allocations: 3.317 MB / 1.362 GB, free: 15.08 MB / 1.214 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.284e-06/0.8817, allocations: 7.5 kB / 1.362 GB, free: 15.08 MB / 1.214 GB
Notification: Performance of matching and sorting (n=332) (initialization): time 0.004665/0.8864, allocations: 3.767 MB / 1.366 GB, free: 11.27 MB / 1.214 GB
Notification: Performance of prepare postOptimizeDAE: time 2.149e-05/0.8864, allocations: 4 kB / 1.366 GB, free: 11.27 MB / 1.214 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.339e-05/0.8864, allocations: 4.781 kB / 1.366 GB, free: 11.26 MB / 1.214 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.482e-05/0.8864, allocations: 16 kB / 1.366 GB, free: 11.25 MB / 1.214 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001787/0.8882, allocations: 0.6241 MB / 1.366 GB, free: 10.62 MB / 1.214 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.96e-05/0.8882, allocations: 19.88 kB / 1.366 GB, free: 10.6 MB / 1.214 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001674/0.8899, allocations: 286.2 kB / 1.366 GB, free: 10.32 MB / 1.214 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001477/0.8901, allocations: 91.97 kB / 1.367 GB, free: 10.23 MB / 1.214 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 (332):
 * Single equations (assignments): 332
 * 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.001115/0.8912, allocations: 0.6417 MB / 1.367 GB, free: 9.578 MB / 1.214 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003193/0.8915, allocations: 246.5 kB / 1.367 GB, free: 9.336 MB / 1.214 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0003231/0.8918, allocations: 331 kB / 1.368 GB, free: 9.004 MB / 1.214 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.431e-05/0.8918, allocations: 28 kB / 1.368 GB, free: 8.977 MB / 1.214 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.368e-05/0.8919, allocations: 0 / 1.368 GB, free: 8.977 MB / 1.214 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004003/0.8959, allocations: 3.25 MB / 1.371 GB, free: 5.656 MB / 1.214 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.008367/0.9042, allocations: 6.656 MB / 1.377 GB, free: 14.9 MB / 1.229 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.064e-06/0.9042, allocations: 4 kB / 1.377 GB, free: 14.9 MB / 1.229 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00183/0.9061, allocations: 0.6164 MB / 1.378 GB, free: 14.28 MB / 1.229 GB
Notification: Performance of postOpt tearingSystem (simulation): time 9.228e-06/0.9061, allocations: 8 kB / 1.378 GB, free: 14.27 MB / 1.229 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001466/0.9062, allocations: 63.95 kB / 1.378 GB, free: 14.21 MB / 1.229 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.228e-06/0.9062, allocations: 12 kB / 1.378 GB, free: 14.2 MB / 1.229 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.824e-06/0.9062, allocations: 0 / 1.378 GB, free: 14.2 MB / 1.229 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.006815/0.9131, allocations: 5.625 MB / 1.384 GB, free: 8.492 MB / 1.229 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001021/0.9141, allocations: 0.599 MB / 1.384 GB, free: 7.891 MB / 1.229 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002594/0.9143, allocations: 55.94 kB / 1.384 GB, free: 7.836 MB / 1.229 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001202/0.9155, allocations: 134.5 kB / 1.384 GB, free: 7.707 MB / 1.229 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002364/0.9158, allocations: 166.8 kB / 1.385 GB, free: 7.543 MB / 1.229 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001352/0.9159, allocations: 87.95 kB / 1.385 GB, free: 7.457 MB / 1.229 GB
Notification: Performance of sorting global known variables: time 0.0009496/0.9169, allocations: 0.9584 MB / 1.386 GB, free: 6.504 MB / 1.229 GB
Notification: Performance of sort global known variables: time 2.6e-07/0.9169, allocations: 0 / 1.386 GB, free: 6.504 MB / 1.229 GB
Notification: Performance of remove unused functions: time 0.001724/0.9186, allocations: 1.03 MB / 1.387 GB, free: 5.473 MB / 1.229 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.006926/0.9255, allocations: 6.404 MB / 1.393 GB, free: 14.98 MB / 1.245 GB
Notification: Performance of simCode: created initialization part: time 0.004364/0.9299, allocations: 3.329 MB / 1.396 GB, free: 11.62 MB / 1.245 GB
Notification: Performance of simCode: created event and clocks part: time 3.737e-06/0.9299, allocations: 4 kB / 1.396 GB, free: 11.62 MB / 1.245 GB
Notification: Performance of simCode: created simulation system equations: time 0.001976/0.9319, allocations: 1.708 MB / 1.398 GB, free: 9.906 MB / 1.245 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003173/0.935, allocations: 1.365 MB / 1.399 GB, free: 8.574 MB / 1.245 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02431/0.9593, allocations: 21.79 MB / 1.42 GB, free: 2.426 MB / 1.26 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001001/0.9603, allocations: 1.342 MB / 1.422 GB, free: 1.043 MB / 1.26 GB
Notification: Performance of simCode: alias equations: time 0.00245/0.9628, allocations: 0.9458 MB / 1.423 GB, free: 92 kB / 1.26 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001298/0.9641, allocations: 1.117 MB / 1.424 GB, free: 14.97 MB / 1.276 GB
Notification: Performance of SimCode: time 1.453e-06/0.9641, allocations: 0 / 1.424 GB, free: 14.97 MB / 1.276 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1426/1.107, allocations: 81.65 MB / 1.503 GB, free: 14.7 MB / 1.354 GB
Notification: Performance of buildModelFMU: Generate platform static: time 9.483/10.59, allocations: 7.984 kB / 1.503 GB, free: 14.69 MB / 1.354 GB
"
[Timeout remaining time 289]
(rm -f Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe ; mkfifo Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe ; head -c 1048576 < Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe >> ../files/Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.sim & (cd run_OMSimulator && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator  --stripRoot --tempDir=temp_Buildings_13_Buildings_Airflow_Multizone_Examples_ReverseBuoyancy3Zones_fmu --startTime=0 --stopTime=3600 --stepSize=1.44 --timeout=50 --tolerance=1e-06 ../Buildings_13_Buildings_Airflow_Multizone_Examples_ReverseBuoyancy3Zones.fmu) > Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones.pipe 2>&1) [Timeout 52.5]
(rm -f Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones_fmpy.pipe ; mkfifo Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones_fmpy.pipe ; head -c 1048576 < Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones_fmpy.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones_fmpy.sim & (cd run_fmpy && python3 -m fmpy simulate --output-file ../Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones_fmpy_res.csv --start-time 0 --stop-time 3600 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 1.44 ../Buildings_13_Buildings_Airflow_Multizone_Examples_ReverseBuoyancy3Zones.fmu) > Buildings_13_Buildings.Airflow.Multizone.Examples.ReverseBuoyancy3Zones_fmpy.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 15.183513497002423]