Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.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.001348/0.001348, allocations: 118.9 kB / 17.17 MB, free: 5.977 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.001462/0.001462, allocations: 211.3 kB / 20.13 MB, free: 3.035 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.521/1.521, allocations: 230.6 MB / 253.5 MB, free: 15.1 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.111/3.111, allocations: 0.49 GB / 0.7923 GB, free: 1.316 MB / 0.6231 GB
"
[Timeout remaining time 176]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.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.Validation.ThreeRoomsContamDiscretizedDoor,fileNamePrefix="Buildings_12_Buildings_Airflow_Multizone_Validation_ThreeRoomsContamDiscretizedDoor",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor,fileNamePrefix="Buildings_12_Buildings_Airflow_Multizone_Validation_ThreeRoomsContamDiscretizedDoor",fmuType="cs",version="2.0",platforms={"static"}) [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.003055/0.003055, allocations: 67.91 kB / 1.15 GB, free: 15.36 MB / 0.9356 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.216/1.219, allocations: 176.1 MB / 1.321 GB, free: 24.73 MB / 1.061 GB
Notification: Performance of NFInst.instantiate(Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor): time 0.3825/1.602, allocations: 303.6 MB / 1.618 GB, free: 9.344 MB / 1.295 GB
Notification: Performance of NFInst.instExpressions: time 0.02646/1.628, allocations: 15.34 MB / 1.633 GB, free: 9.969 MB / 1.311 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00363/1.632, allocations: 99.31 kB / 1.633 GB, free: 9.871 MB / 1.311 GB
Notification: Performance of NFTyping.typeComponents: time 0.005207/1.637, allocations: 1.822 MB / 1.635 GB, free: 8.039 MB / 1.311 GB
Notification: Performance of NFTyping.typeBindings: time 0.01798/1.655, allocations: 6.103 MB / 1.641 GB, free: 1.91 MB / 1.311 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009986/1.665, allocations: 2.852 MB / 1.644 GB, free: 15.06 MB / 1.326 GB
Notification: Performance of NFFlatten.flatten: time 0.01281/1.678, allocations: 7.121 MB / 1.65 GB, free: 7.918 MB / 1.326 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004475/1.682, allocations: 2.255 MB / 1.653 GB, free: 5.613 MB / 1.326 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007097/1.689, allocations: 2.975 MB / 1.656 GB, free: 2.629 MB / 1.326 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006441/1.696, allocations: 2.757 MB / 1.658 GB, free: 15.87 MB / 1.342 GB
Notification: Performance of NFPackage.collectConstants: time 0.001383/1.697, allocations: 340 kB / 1.659 GB, free: 15.54 MB / 1.342 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01479/1.712, allocations: 6.079 MB / 1.665 GB, free: 9.445 MB / 1.342 GB
Notification: Performance of NFScalarize.scalarize: time 0.003209/1.715, allocations: 1.512 MB / 1.666 GB, free: 7.93 MB / 1.342 GB
Notification: Performance of NFVerifyModel.verify: time 0.004232/1.719, allocations: 1.914 MB / 1.668 GB, free: 6.008 MB / 1.342 GB
Notification: Performance of NFConvertDAE.convert: time 0.01756/1.737, allocations: 6.865 MB / 1.675 GB, free: 15.12 MB / 1.357 GB
Notification: Performance of FrontEnd - DAE generated: time 7.374e-06/1.737, allocations: 0 / 1.675 GB, free: 15.12 MB / 1.357 GB
Notification: Performance of FrontEnd: time 2.575e-06/1.737, allocations: 4 kB / 1.675 GB, free: 15.12 MB / 1.357 GB
Notification: Performance of Transformations before backend: time 0.0001876/1.737, allocations: 0 / 1.675 GB, free: 15.12 MB / 1.357 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 747
 * Number of variables: 747
Notification: Performance of Generate backend data structure: time 0.01427/1.751, allocations: 4.231 MB / 1.679 GB, free: 10.81 MB / 1.357 GB
Notification: Performance of prepare preOptimizeDAE: time 5.541e-05/1.751, allocations: 11.98 kB / 1.679 GB, free: 10.8 MB / 1.357 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0001062/1.751, allocations: 73.59 kB / 1.679 GB, free: 10.73 MB / 1.357 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0007688/1.752, allocations: 413 kB / 1.679 GB, free: 10.31 MB / 1.357 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006205/1.758, allocations: 0.8565 MB / 1.68 GB, free: 9.453 MB / 1.357 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004547/1.763, allocations: 1.846 MB / 1.682 GB, free: 7.551 MB / 1.357 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001914/1.763, allocations: 213.5 kB / 1.682 GB, free: 7.34 MB / 1.357 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007009/1.764, allocations: 287.9 kB / 1.682 GB, free: 7.059 MB / 1.357 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007994/1.772, allocations: 3.707 MB / 1.686 GB, free: 3.258 MB / 1.357 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.208e-05/1.772, allocations: 3.938 kB / 1.686 GB, free: 3.254 MB / 1.357 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003264/1.772, allocations: 120 kB / 1.686 GB, free: 3.137 MB / 1.357 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002117/1.772, allocations: 165.6 kB / 1.686 GB, free: 2.973 MB / 1.357 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007979/1.78, allocations: 3.867 MB / 1.69 GB, free: 15.09 MB / 1.373 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.02306/1.804, allocations: 13.42 MB / 1.703 GB, free: 0.6055 MB / 1.373 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.005343/1.809, allocations: 3.049 MB / 1.706 GB, free: 13.53 MB / 1.389 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002907/1.812, allocations: 1.627 MB / 1.708 GB, free: 11.9 MB / 1.389 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.002003/1.814, allocations: 1.588 MB / 1.709 GB, free: 9.98 MB / 1.389 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.034e-05/1.814, allocations: 60.81 kB / 1.709 GB, free: 9.91 MB / 1.389 GB
Notification: Performance of pre-optimization done (n=285): time 4.499e-06/1.814, allocations: 0 / 1.709 GB, free: 9.91 MB / 1.389 GB
Notification: Performance of matching and sorting (n=285): time 0.008285/1.822, allocations: 4.063 MB / 1.713 GB, free: 5.801 MB / 1.389 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.597e-05/1.822, allocations: 85.59 kB / 1.713 GB, free: 5.703 MB / 1.389 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002474/1.825, allocations: 1.444 MB / 1.715 GB, free: 4.254 MB / 1.389 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002509/1.825, allocations: 73.7 kB / 1.715 GB, free: 4.176 MB / 1.389 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009338/1.826, allocations: 1.068 MB / 1.716 GB, free: 3.098 MB / 1.389 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001147/1.827, allocations: 1.015 MB / 1.717 GB, free: 2.074 MB / 1.389 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007852/1.828, allocations: 441.5 kB / 1.717 GB, free: 1.637 MB / 1.389 GB
Notification: Performance of setup shared object (initialization): time 0.0001469/1.828, allocations: 309.1 kB / 1.718 GB, free: 1.332 MB / 1.389 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00213/1.83, allocations: 1.188 MB / 1.719 GB, free: 144 kB / 1.389 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002384/1.832, allocations: 1.445 MB / 1.72 GB, free: 14.65 MB / 1.404 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004956/1.837, allocations: 2.802 MB / 1.723 GB, free: 11.77 MB / 1.404 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.307e-05/1.837, allocations: 4 kB / 1.723 GB, free: 11.77 MB / 1.404 GB
Notification: Performance of matching and sorting (n=333) (initialization): time 0.006196/1.844, allocations: 3.198 MB / 1.726 GB, free: 8.531 MB / 1.404 GB
Notification: Performance of prepare postOptimizeDAE: time 3.247e-05/1.844, allocations: 5.875 kB / 1.726 GB, free: 8.527 MB / 1.404 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.694e-05/1.844, allocations: 4 kB / 1.726 GB, free: 8.523 MB / 1.404 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.845e-05/1.844, allocations: 19.94 kB / 1.726 GB, free: 8.504 MB / 1.404 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002488/1.846, allocations: 0.5387 MB / 1.727 GB, free: 7.965 MB / 1.404 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.333e-05/1.846, allocations: 15.94 kB / 1.727 GB, free: 7.949 MB / 1.404 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002354/1.849, allocations: 230.2 kB / 1.727 GB, free: 7.723 MB / 1.404 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000218/1.849, allocations: 87.98 kB / 1.727 GB, free: 7.637 MB / 1.404 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 (333):
 * Single equations (assignments): 333
 * 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.001422/1.85, allocations: 0.6904 MB / 1.728 GB, free: 6.938 MB / 1.404 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006165/1.851, allocations: 250.4 kB / 1.728 GB, free: 6.691 MB / 1.404 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006124/1.857, allocations: 3.211 MB / 1.731 GB, free: 3.426 MB / 1.404 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.821e-05/1.857, allocations: 31.98 kB / 1.731 GB, free: 3.395 MB / 1.404 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.252e-05/1.857, allocations: 3.938 kB / 1.731 GB, free: 3.391 MB / 1.404 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004492/1.862, allocations: 2.65 MB / 1.734 GB, free: 0.6914 MB / 1.404 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01217/1.874, allocations: 6.635 MB / 1.74 GB, free: 9.879 MB / 1.42 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.337e-05/1.874, allocations: 4 kB / 1.74 GB, free: 9.875 MB / 1.42 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002689/1.876, allocations: 0.5384 MB / 1.741 GB, free: 9.336 MB / 1.42 GB
Notification: Performance of postOpt tearingSystem (simulation): time 2.438e-05/1.876, allocations: 15.98 kB / 1.741 GB, free: 9.32 MB / 1.42 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002156/1.877, allocations: 63.98 kB / 1.741 GB, free: 9.258 MB / 1.42 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.064e-05/1.877, allocations: 20.06 kB / 1.741 GB, free: 9.238 MB / 1.42 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.099e-06/1.877, allocations: 7.938 kB / 1.741 GB, free: 9.23 MB / 1.42 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00843/1.885, allocations: 4.841 MB / 1.745 GB, free: 4.262 MB / 1.42 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001534/1.887, allocations: 0.6315 MB / 1.746 GB, free: 3.609 MB / 1.42 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003636/1.887, allocations: 52 kB / 1.746 GB, free: 3.559 MB / 1.42 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00165/1.889, allocations: 83.11 kB / 1.746 GB, free: 3.48 MB / 1.42 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003693/1.889, allocations: 173.9 kB / 1.746 GB, free: 3.309 MB / 1.42 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001802/1.889, allocations: 87.91 kB / 1.746 GB, free: 3.223 MB / 1.42 GB
Notification: Performance of sorting global known variables: time 0.001551/1.891, allocations: 0.9891 MB / 1.747 GB, free: 2.238 MB / 1.42 GB
Notification: Performance of sort global known variables: time 1e-07/1.891, allocations: 0 / 1.747 GB, free: 2.238 MB / 1.42 GB
Notification: Performance of remove unused functions: time 0.002667/1.893, allocations: 1.022 MB / 1.748 GB, free: 1.215 MB / 1.42 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 11
 * Number of states: 18 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,volEas.dynBal.mC[1],oriOutBot.mExc,oriOutTop.mExc,oriWesTop.mExc,dooOpeClo.mExcAB,dooOpeClo.mExcBA,volWes.dynBal.medium.Xi[1],volWes.dynBal.U,volWes.dynBal.m,volWes.dynBal.mC[1],oriEasTop.mExc,volTop.dynBal.medium.Xi[1],volTop.dynBal.U,volTop.dynBal.m,volTop.dynBal.mC[1])
 * 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 (285):
 * Single equations (assignments): 285
 * 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.009604/1.903, allocations: 5.674 MB / 1.754 GB, free: 11.41 MB / 1.436 GB
Notification: Performance of simCode: created initialization part: time 0.005495/1.909, allocations: 2.761 MB / 1.757 GB, free: 8.621 MB / 1.436 GB
Notification: Performance of simCode: created event and clocks part: time 8.015e-06/1.909, allocations: 4.062 kB / 1.757 GB, free: 8.617 MB / 1.436 GB
Notification: Performance of simCode: created simulation system equations: time 0.002539/1.911, allocations: 1.441 MB / 1.758 GB, free: 7.172 MB / 1.436 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006955/1.918, allocations: 1.322 MB / 1.759 GB, free: 5.871 MB / 1.436 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03651/1.955, allocations: 20.77 MB / 1.78 GB, free: 0.7148 MB / 1.451 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001938/1.956, allocations: 1.986 MB / 1.782 GB, free: 14.7 MB / 1.467 GB
Notification: Performance of simCode: alias equations: time 0.003235/1.96, allocations: 0.9261 MB / 1.782 GB, free: 13.76 MB / 1.467 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002005/1.962, allocations: 1.12 MB / 1.784 GB, free: 12.64 MB / 1.467 GB
Notification: Performance of SimCode: time 1.392e-06/1.962, allocations: 0 / 1.784 GB, free: 12.64 MB / 1.467 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.132/2.094, allocations: 70.66 MB / 1.853 GB, free: 6.797 MB / 1.529 GB
Notification: Performance of buildModelFMU: Generate platform static: time 14.22/16.32, allocations: 8.047 kB / 1.853 GB, free: 6.789 MB / 1.529 GB
"
[Timeout remaining time 284]
(rm -f Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; mkfifo Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe ; head -c 1048576 < Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe >> ../files/Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor_res.mat --tempDir=temp_Buildings_12_Buildings_Airflow_Multizone_Validation_ThreeRoomsContamDiscretizedDoor_fmu --startTime=0 --stopTime=3600 --stepSize=1.44 --timeout=400 --tolerance=1e-06 Buildings_12_Buildings_Airflow_Multizone_Validation_ThreeRoomsContamDiscretizedDoor.fmu > Buildings_12_Buildings.Airflow.Multizone.Validation.ThreeRoomsContamDiscretizedDoor.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 23.219860451994464]
Failed to read output from testmodel.py, exit status != 0:
16.49856561998604 16.517175038 14.77613363
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f2ff70c33a0>