Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.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/Buildings 11.0.0-master/package.mo", uses=false)
Using package Buildings with version 11.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.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(Buildings.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|dooAB.vAB|dooAB.vBA|dooAC.vAB|dooAC.vBA|dooBC.vAB|dooBC.vBA",fileNamePrefix="Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors")
translateModel(Buildings.Airflow.Multizone.Examples.ClosedDoors,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|dooAB.vAB|dooAB.vBA|dooAC.vAB|dooAC.vBA|dooBC.vAB|dooBC.vBA",fileNamePrefix="Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001354/0.001354, allocations: 111.5 kB / 16.37 MB, free: 6.012 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.001325/0.001326, allocations: 189.5 kB / 17.31 MB, free: 5.613 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.331/1.331, allocations: 222.9 MB / 241 MB, free: 15.18 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.0.0-master/package.mo): time 2.388/2.388, allocations: 442.6 MB / 0.7165 GB, free: 14.64 MB / 0.5919 GB
Notification: Performance of FrontEnd - loaded program: time 0.001329/0.001329, allocations: 63.88 kB / 0.8773 GB, free: 10.98 MB / 0.6544 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3536/0.3549, allocations: 153 MB / 1.027 GB, free: 2.055 MB / 0.795 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Examples.ClosedDoors): time 0.8009/1.156, allocations: 218.5 MB / 1.24 GB, free: 2.797 MB / 0.9356 GB
Notification: Performance of NFInst.instExpressions: time 0.02418/1.18, allocations: 20.42 MB / 1.26 GB, free: 14.31 MB / 0.9669 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002494/1.183, allocations: 51.75 kB / 1.26 GB, free: 14.26 MB / 0.9669 GB
Notification: Performance of NFTyping.typeComponents: time 0.003032/1.186, allocations: 1.255 MB / 1.261 GB, free: 13 MB / 0.9669 GB
Notification: Performance of NFTyping.typeBindings: time 0.009717/1.195, allocations: 4.079 MB / 1.265 GB, free: 8.902 MB / 0.9669 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003936/1.199, allocations: 1.55 MB / 1.267 GB, free: 7.359 MB / 0.9669 GB
Notification: Performance of NFFlatten.flatten: time 0.008656/1.208, allocations: 6.965 MB / 1.274 GB, free: 384 kB / 0.9669 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004312/1.212, allocations: 2.357 MB / 1.276 GB, free: 13.98 MB / 0.9825 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004994/1.217, allocations: 2.364 MB / 1.278 GB, free: 11.61 MB / 0.9825 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005535/1.223, allocations: 2.454 MB / 1.281 GB, free: 9.156 MB / 0.9825 GB
Notification: Performance of NFPackage.collectConstants: time 0.001322/1.224, allocations: 344 kB / 1.281 GB, free: 8.82 MB / 0.9825 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007963/1.232, allocations: 3.465 MB / 1.284 GB, free: 5.348 MB / 0.9825 GB
Notification: Performance of NFScalarize.scalarize: time 0.002107/1.234, allocations: 1.457 MB / 1.286 GB, free: 3.887 MB / 0.9825 GB
Notification: Performance of NFVerifyModel.verify: time 0.003726/1.238, allocations: 1.859 MB / 1.288 GB, free: 2.02 MB / 0.9825 GB
Notification: Performance of NFConvertDAE.convert: time 0.009398/1.248, allocations: 5.769 MB / 1.293 GB, free: 12.24 MB / 0.9981 GB
Notification: Performance of FrontEnd - DAE generated: time 4.96e-06/1.248, allocations: 4 kB / 1.293 GB, free: 12.23 MB / 0.9981 GB
Notification: Performance of FrontEnd: time 1.523e-06/1.248, allocations: 0 / 1.293 GB, free: 12.23 MB / 0.9981 GB
Notification: Performance of Transformations before backend: time 0.0001609/1.248, allocations: 0 / 1.293 GB, free: 12.23 MB / 0.9981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 697
 * Number of variables: 697
Notification: Performance of Generate backend data structure: time 0.01078/1.259, allocations: 3.945 MB / 1.297 GB, free: 8.227 MB / 0.9981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.32e-05/1.259, allocations: 8.031 kB / 1.297 GB, free: 8.219 MB / 0.9981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005141/1.264, allocations: 0.8275 MB / 1.298 GB, free: 7.387 MB / 0.9981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004053/1.268, allocations: 1.933 MB / 1.3 GB, free: 5.395 MB / 0.9981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001375/1.268, allocations: 205.4 kB / 1.3 GB, free: 5.191 MB / 0.9981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004436/1.268, allocations: 267.9 kB / 1.3 GB, free: 4.93 MB / 0.9981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005211/1.274, allocations: 3.283 MB / 1.303 GB, free: 1.605 MB / 0.9981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.414e-05/1.274, allocations: 3.906 kB / 1.303 GB, free: 1.602 MB / 0.9981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002436/1.274, allocations: 116 kB / 1.304 GB, free: 1.488 MB / 0.9981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001045/1.274, allocations: 121.4 kB / 1.304 GB, free: 1.367 MB / 0.9981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006775/1.281, allocations: 4.623 MB / 1.308 GB, free: 12.73 MB / 1.014 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.01783/1.299, allocations: 10.92 MB / 1.319 GB, free: 1.129 MB / 1.014 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.005815/1.305, allocations: 4.866 MB / 1.324 GB, free: 12.22 MB / 1.029 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003324/1.308, allocations: 2.52 MB / 1.326 GB, free: 9.695 MB / 1.029 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001042/1.309, allocations: 0.8643 MB / 1.327 GB, free: 8.664 MB / 1.029 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.058e-05/1.309, allocations: 85.69 kB / 1.327 GB, free: 8.57 MB / 1.029 GB
Notification: Performance of pre-optimization done (n=399): time 5.109e-06/1.309, allocations: 0 / 1.327 GB, free: 8.57 MB / 1.029 GB
Notification: Performance of matching and sorting (n=399): time 0.009487/1.319, allocations: 6.015 MB / 1.333 GB, free: 2.469 MB / 1.029 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.9e-05/1.319, allocations: 94.53 kB / 1.333 GB, free: 2.363 MB / 1.029 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001618/1.32, allocations: 1.232 MB / 1.334 GB, free: 1.125 MB / 1.029 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002347/1.321, allocations: 93.7 kB / 1.334 GB, free: 1.027 MB / 1.029 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000525/1.321, allocations: 0.8096 MB / 1.335 GB, free: 212 kB / 1.029 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001012/1.322, allocations: 1.332 MB / 1.336 GB, free: 14.85 MB / 1.045 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007841/1.323, allocations: 0.5468 MB / 1.337 GB, free: 14.3 MB / 1.045 GB
Notification: Performance of setup shared object (initialization): time 0.0001113/1.323, allocations: 305.1 kB / 1.337 GB, free: 14 MB / 1.045 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002684/1.326, allocations: 1.599 MB / 1.339 GB, free: 12.39 MB / 1.045 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002976/1.329, allocations: 1.905 MB / 1.341 GB, free: 10.45 MB / 1.045 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005735/1.334, allocations: 3.834 MB / 1.344 GB, free: 6.551 MB / 1.045 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.224e-05/1.335, allocations: 4 kB / 1.344 GB, free: 6.547 MB / 1.045 GB
Notification: Performance of matching and sorting (n=430) (initialization): time 0.006323/1.341, allocations: 4.191 MB / 1.348 GB, free: 2.332 MB / 1.045 GB
Notification: Performance of prepare postOptimizeDAE: time 2.702e-05/1.341, allocations: 11.94 kB / 1.348 GB, free: 2.32 MB / 1.045 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.542e-05/1.341, allocations: 4 kB / 1.348 GB, free: 2.316 MB / 1.045 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.186e-05/1.341, allocations: 16 kB / 1.348 GB, free: 2.301 MB / 1.045 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002755/1.344, allocations: 0.6831 MB / 1.349 GB, free: 1.617 MB / 1.045 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.329e-05/1.344, allocations: 23.92 kB / 1.349 GB, free: 1.594 MB / 1.045 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002147/1.346, allocations: 237.3 kB / 1.349 GB, free: 1.359 MB / 1.045 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002236/1.346, allocations: 116 kB / 1.349 GB, free: 1.246 MB / 1.045 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 14
 * 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 (430):
 * Single equations (assignments): 430
 * 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.001157/1.347, allocations: 0.7273 MB / 1.35 GB, free: 0.5078 MB / 1.045 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004362/1.348, allocations: 241.1 kB / 1.35 GB, free: 276 kB / 1.045 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006906/1.355, allocations: 4.636 MB / 1.355 GB, free: 11.59 MB / 1.061 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.83e-05/1.355, allocations: 43.94 kB / 1.355 GB, free: 11.54 MB / 1.061 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.132e-05/1.355, allocations: 0 / 1.355 GB, free: 11.54 MB / 1.061 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.005582/1.36, allocations: 3.961 MB / 1.359 GB, free: 7.523 MB / 1.061 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01182/1.372, allocations: 8.265 MB / 1.367 GB, free: 15.17 MB / 1.076 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.188e-05/1.372, allocations: 3.984 kB / 1.367 GB, free: 15.17 MB / 1.076 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002755/1.375, allocations: 0.6554 MB / 1.368 GB, free: 14.51 MB / 1.076 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0004357/1.375, allocations: 142.4 kB / 1.368 GB, free: 14.36 MB / 1.076 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002136/1.376, allocations: 83.92 kB / 1.368 GB, free: 14.28 MB / 1.076 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003522/1.379, allocations: 5.043 MB / 1.373 GB, free: 8.82 MB / 1.076 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.193e-06/1.379, allocations: 8.031 kB / 1.373 GB, free: 8.812 MB / 1.076 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.009821/1.389, allocations: 6.816 MB / 1.379 GB, free: 1.938 MB / 1.076 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001804/1.391, allocations: 0.7705 MB / 1.38 GB, free: 1.145 MB / 1.076 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003411/1.391, allocations: 67.98 kB / 1.38 GB, free: 1.078 MB / 1.076 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001734/1.393, allocations: 87.31 kB / 1.38 GB, free: 0.9961 MB / 1.076 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004015/1.393, allocations: 222.8 kB / 1.381 GB, free: 0.7773 MB / 1.076 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002183/1.394, allocations: 112 kB / 1.381 GB, free: 0.668 MB / 1.076 GB
Notification: Performance of sorting global known variables: time 0.00111/1.395, allocations: 0.8645 MB / 1.381 GB, free: 15.8 MB / 1.092 GB
Notification: Performance of sort global known variables: time 2.31e-07/1.395, allocations: 0 / 1.381 GB, free: 15.8 MB / 1.092 GB
Notification: Performance of remove unused functions: time 0.002135/1.397, allocations: 1.073 MB / 1.383 GB, free: 14.73 MB / 1.092 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 15 (dooAB.mExcAB,dooAB.mExcBA,volA.dynBal.medium.Xi[1],volA.dynBal.U,volA.dynBal.m,volB.dynBal.medium.Xi[1],volB.dynBal.U,volB.dynBal.m,volC.dynBal.medium.Xi[1],volC.dynBal.U,volC.dynBal.m,dooAC.mExcAB,dooAC.mExcBA,dooBC.mExcAB,dooBC.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 (393):
 * Single equations (assignments): 390
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(1,2,100.0%), (1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0005942/1.397, allocations: 343.1 kB / 1.383 GB, free: 14.38 MB / 1.092 GB
Notification: Performance of simCode: created initialization part: time 0.005782/1.403, allocations: 4.055 MB / 1.387 GB, free: 10.32 MB / 1.092 GB
Notification: Performance of simCode: created event and clocks part: time 4.218e-06/1.403, allocations: 0 / 1.387 GB, free: 10.32 MB / 1.092 GB
Notification: Performance of simCode: created simulation system equations: time 0.003386/1.407, allocations: 2.555 MB / 1.389 GB, free: 7.723 MB / 1.092 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004379/1.411, allocations: 1.083 MB / 1.39 GB, free: 6.66 MB / 1.092 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00962/1.421, allocations: 6.584 MB / 1.397 GB, free: 28 kB / 1.092 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001676/1.422, allocations: 1.943 MB / 1.399 GB, free: 14.04 MB / 1.107 GB
Notification: Performance of simCode: alias equations: time 0.003403/1.426, allocations: 1.219 MB / 1.4 GB, free: 12.82 MB / 1.107 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008588/1.427, allocations: 243.3 kB / 1.4 GB, free: 12.58 MB / 1.107 GB
Notification: Performance of SimCode: time 6.21e-07/1.427, allocations: 4 kB / 1.4 GB, free: 12.57 MB / 1.107 GB
Notification: Performance of Templates: time 0.07221/1.499, allocations: 47.6 MB / 1.447 GB, free: 13.48 MB / 1.154 GB
make -j1 -f Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.makefile
(rm -f Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe ; mkfifo Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe ; head -c 1048576 < Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe >> ../files/Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.sim & ./Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors  -abortSlowSimulation -alarm=480 -s cvode -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.pipe 2>&1)
diffSimulationResults("Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Airflow_Multizone_Examples_ClosedDoors.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Airflow.Multizone.Examples.ClosedDoors.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches