Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic.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/BuildSysPro 3.6.0/package.mo", uses=false)
Using package BuildSysPro with version 3.5.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0/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: "" <> buildModelFMU(BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Validation_OpenDoorBuoyancyDynamic",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic,fileNamePrefix="BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Validation_OpenDoorBuoyancyDynamic",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001132/0.001132, allocations: 108 kB / 15.6 MB, free: 128 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001078/0.001078, allocations: 197.2 kB / 16.53 MB, free: 6.668 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.144/1.144, allocations: 222.9 MB / 240.2 MB, free: 4.379 MB / 190.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0/package.mo): time 0.8352/0.8352, allocations: 143.1 MB / 433.4 MB, free: 11.46 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001109/0.001109, allocations: 63.91 kB / 0.5143 GB, free: 11.58 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1209/0.1221, allocations: 82.05 MB / 0.5944 GB, free: 9.5 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic): time 0.3932/0.5153, allocations: 245.9 MB / 0.8346 GB, free: 7.043 MB / 0.6544 GB
Notification: Performance of NFInst.instExpressions: time 0.01734/0.5326, allocations: 14.42 MB / 0.8486 GB, free: 8.59 MB / 0.67 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001808/0.5345, allocations: 71.62 kB / 0.8487 GB, free: 8.52 MB / 0.67 GB
Notification: Performance of NFTyping.typeComponents: time 0.002497/0.537, allocations: 1.258 MB / 0.8499 GB, free: 7.254 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.008639/0.5457, allocations: 4.672 MB / 0.8545 GB, free: 2.562 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003643/0.5493, allocations: 1.855 MB / 0.8563 GB, free: 0.7188 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.2764/0.8257, allocations: 6.223 MB / 0.8624 GB, free: 6.113 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002475/0.8282, allocations: 1.976 MB / 0.8643 GB, free: 5.961 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003357/0.8316, allocations: 2.142 MB / 0.8664 GB, free: 5.961 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00305/0.8346, allocations: 2.024 MB / 0.8684 GB, free: 5.957 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003132/0.835, allocations: 250.1 kB / 0.8686 GB, free: 5.957 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006132/0.8411, allocations: 4.512 MB / 0.873 GB, free: 5.957 MB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.0007588/0.8419, allocations: 1.051 MB / 0.8741 GB, free: 5.957 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.001395/0.8433, allocations: 1.447 MB / 0.8755 GB, free: 5.957 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.005842/0.8491, allocations: 5.415 MB / 0.8808 GB, free: 5.793 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 3.206e-06/0.8491, allocations: 0 / 0.8808 GB, free: 5.793 MB / 0.67 GB
Notification: Performance of FrontEnd: time 1.122e-06/0.8491, allocations: 0.875 kB / 0.8808 GB, free: 5.793 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 2.697e-05/0.8492, allocations: 1.219 kB / 0.8808 GB, free: 5.793 MB / 0.67 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 494
 * Number of variables: 494
Notification: Performance of Generate backend data structure: time 0.005849/0.855, allocations: 3.205 MB / 0.8839 GB, free: 20.43 MB / 0.6856 GB
Notification: Performance of prepare preOptimizeDAE: time 3.335e-05/0.8551, allocations: 8.969 kB / 0.8839 GB, free: 20.43 MB / 0.6856 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.000327/0.8554, allocations: 280.5 kB / 0.8842 GB, free: 20.38 MB / 0.6856 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003745/0.8592, allocations: 0.6703 MB / 0.8848 GB, free: 20.25 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002187/0.8614, allocations: 1.571 MB / 0.8864 GB, free: 19.79 MB / 0.6856 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 9.301e-05/0.8615, allocations: 145.1 kB / 0.8865 GB, free: 19.79 MB / 0.6856 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000269/0.8617, allocations: 196.9 kB / 0.8867 GB, free: 19.78 MB / 0.6856 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003378/0.8651, allocations: 2.419 MB / 0.889 GB, free: 19.65 MB / 0.6856 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.74e-05/0.8652, allocations: 4.625 kB / 0.889 GB, free: 19.65 MB / 0.6856 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001764/0.8654, allocations: 80.17 kB / 0.8891 GB, free: 19.65 MB / 0.6856 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.895e-05/0.8654, allocations: 96.5 kB / 0.8892 GB, free: 19.64 MB / 0.6856 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003498/0.869, allocations: 2.611 MB / 0.8918 GB, free: 19.61 MB / 0.6856 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.008746/0.8777, allocations: 8.114 MB / 0.8997 GB, free: 16.39 MB / 0.6856 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002541/0.8803, allocations: 2.357 MB / 0.902 GB, free: 16.28 MB / 0.6856 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001499/0.8818, allocations: 1.345 MB / 0.9033 GB, free: 16.23 MB / 0.6856 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001257/0.883, allocations: 1.556 MB / 0.9048 GB, free: 14.54 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.202e-05/0.8831, allocations: 62.62 kB / 0.9049 GB, free: 14.5 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=222): time 3.457e-06/0.8831, allocations: 2.906 kB / 0.9049 GB, free: 14.5 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=226): time 0.008312/0.8914, allocations: 6.86 MB / 0.9116 GB, free: 12.86 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.514e-05/0.8915, allocations: 159.1 kB / 0.9117 GB, free: 12.7 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001084/0.8926, allocations: 1.209 MB / 0.9129 GB, free: 12.54 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001143/0.8927, allocations: 65.23 kB / 0.913 GB, free: 12.51 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000344/0.8931, allocations: 0.7574 MB / 0.9137 GB, free: 12.22 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003939/0.8935, allocations: 0.7689 MB / 0.9145 GB, free: 11.72 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004132/0.8939, allocations: 347.3 kB / 0.9148 GB, free: 11.7 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 9.176e-05/0.894, allocations: 308.9 kB / 0.9151 GB, free: 11.39 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001013/0.895, allocations: 0.863 MB / 0.9159 GB, free: 11.39 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001145/0.8961, allocations: 1.126 MB / 0.917 GB, free: 11.2 MB / 0.6856 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: bouA.dynBal.medium.p = bouA.dynBal.p_start (bou.p = bouA.dynBal.p_start)
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: bouADis.dynBal.medium.p = bouADis.dynBal.p_start (bou1.p = bouADis.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.06298/0.9591, allocations: 11.71 MB / 0.9285 GB, free: 10.51 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.659e-06/0.9591, allocations: 0 / 0.9285 GB, free: 10.51 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=254) (initialization): time 0.002824/0.962, allocations: 2.404 MB / 0.9308 GB, free: 9.867 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 1.781e-05/0.962, allocations: 8 kB / 0.9308 GB, free: 9.859 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.065e-05/0.962, allocations: 4 kB / 0.9308 GB, free: 9.855 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.259e-05/0.962, allocations: 16 kB / 0.9308 GB, free: 9.84 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001511/0.9636, allocations: 425.1 kB / 0.9312 GB, free: 9.512 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.876e-05/0.9636, allocations: 16.56 kB / 0.9313 GB, free: 9.5 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001526/0.9651, allocations: 69.84 kB / 0.9313 GB, free: 9.465 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001396/0.9653, allocations: 72.97 kB / 0.9314 GB, free: 9.414 MB / 0.6856 GB
Warning: The initial conditions are over specified. The following 2 initial equations are redundant, so they are removed from the initialization system:
         bouA.dynBal.medium.p = bouA.dynBal.p_start
         bouADis.dynBal.medium.p = bouADis.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 21
 * 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 (254):
 * Single equations (assignments): 254
 * 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.0007707/0.966, allocations: 0.5659 MB / 0.932 GB, free: 9.117 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003386/0.9664, allocations: 197.7 kB / 0.9321 GB, free: 8.922 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00342/0.9698, allocations: 2.531 MB / 0.9346 GB, free: 7.297 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.551e-05/0.9698, allocations: 24 kB / 0.9346 GB, free: 7.273 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.865e-06/0.9699, allocations: 0 / 0.9346 GB, free: 7.273 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002543/0.9724, allocations: 2.099 MB / 0.9367 GB, free: 5.84 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.006734/0.9791, allocations: 5.359 MB / 0.9419 GB, free: 2.066 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.046e-05/0.9792, allocations: 11.94 kB / 0.9419 GB, free: 2.055 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001788/0.981, allocations: 460.1 kB / 0.9424 GB, free: 1.645 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.245e-05/0.981, allocations: 12.47 kB / 0.9424 GB, free: 1.633 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001119/0.9811, allocations: 64.05 kB / 0.9424 GB, free: 1.602 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.41e-05/0.9811, allocations: 19.19 kB / 0.9425 GB, free: 1.586 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.731e-06/0.9811, allocations: 11.09 kB / 0.9425 GB, free: 1.578 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004427/0.9856, allocations: 4.083 MB / 0.9465 GB, free: 14.6 MB / 0.7012 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007148/0.9863, allocations: 0.502 MB / 0.9469 GB, free: 14.19 MB / 0.7012 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002435/0.9865, allocations: 50.61 kB / 0.947 GB, free: 14.17 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001335/0.9879, allocations: 59.23 kB / 0.9471 GB, free: 14.14 MB / 0.7012 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000213/0.9881, allocations: 150.3 kB / 0.9472 GB, free: 14.02 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001323/0.9882, allocations: 80.05 kB / 0.9473 GB, free: 13.95 MB / 0.7012 GB
Notification: Performance of sorting global known variables: time 0.000876/0.9891, allocations: 0.8402 MB / 0.9481 GB, free: 13.33 MB / 0.7012 GB
Notification: Performance of sort global known variables: time 1.6e-07/0.9891, allocations: 0 / 0.9481 GB, free: 13.33 MB / 0.7012 GB
Notification: Performance of remove unused functions: time 0.001553/0.9907, allocations: 0.6844 MB / 0.9488 GB, free: 12.71 MB / 0.7012 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 28
 * Number of states: 12 (bouADis.dynBal.medium.Xi[1],bouADis.dynBal.U,bouBDis.dynBal.medium.Xi[1],bouBDis.dynBal.U,bouBDis.dynBal.m,bouA.dynBal.medium.Xi[1],bouA.dynBal.U,bouB.dynBal.medium.Xi[1],bouB.dynBal.U,bouB.dynBal.m,dooDis.mExcBA,dooDis.mExcAB)
 * 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 (230):
 * Single equations (assignments): 230
 * 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.005087/0.9958, allocations: 4.903 MB / 0.9536 GB, free: 9.004 MB / 0.7012 GB
Notification: Performance of simCode: created initialization part: time 0.004801/1.001, allocations: 3.347 MB / 0.9568 GB, free: 6.051 MB / 0.7012 GB
Notification: Performance of simCode: created event and clocks part: time 3.477e-06/1.001, allocations: 1.188 kB / 0.9568 GB, free: 6.051 MB / 0.7012 GB
Notification: Performance of simCode: created simulation system equations: time 0.001378/1.002, allocations: 1.171 MB / 0.958 GB, free: 4.941 MB / 0.7012 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004641/1.007, allocations: 1.179 MB / 0.9591 GB, free: 3.828 MB / 0.7012 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01942/1.026, allocations: 17.65 MB / 0.9764 GB, free: 1.191 MB / 0.7169 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001115/1.027, allocations: 1.655 MB / 0.978 GB, free: 15.5 MB / 0.7325 GB
Notification: Performance of simCode: alias equations: time 0.002085/1.029, allocations: 0.7586 MB / 0.9787 GB, free: 14.74 MB / 0.7325 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001057/1.03, allocations: 0.8343 MB / 0.9795 GB, free: 13.91 MB / 0.7325 GB
Notification: Performance of SimCode: time 4.11e-07/1.03, allocations: 0 / 0.9795 GB, free: 13.91 MB / 0.7325 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.07324/1.104, allocations: 56.16 MB / 1.034 GB, free: 6.551 MB / 0.7794 GB
Notification: Performance of buildModelFMU: Generate platform static: time 7.56/8.664, allocations: 8.516 kB / 1.034 GB, free: 6.543 MB / 0.7794 GB
(rm -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic.sim & python3 -m fmpy simulate  --output-file BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic_res.csv --start-time 0 --stop-time 14400 --timeout 50 --relative-tolerance 1e-08 --interface-type ModelExchange --solver CVode --output-interval 5.76 BuildSysPro_BuildSysPro_IBPSA_Airflow_Multizone_Validation_OpenDoorBuoyancyDynamic.fmu > BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Validation.OpenDoorBuoyancyDynamic.pipe 2>&1)