Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.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: translateModel(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance")
translateModel(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.000975/0.0009753, allocations: 92.16 kB / 16.02 MB, free: 80 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.00126/0.00126, allocations: 172.9 kB / 16.92 MB, free: 6.652 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.423/1.423, allocations: 223.3 MB / 241 MB, free: 5.352 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 1.02/1.02, allocations: 143.2 MB / 435.3 MB, free: 13.71 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003451/0.0003451, allocations: 8.188 kB / 0.517 GB, free: 11.24 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1842/0.1846, allocations: 83.41 MB / 0.5985 GB, free: 7.785 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance): time 0.56/0.7446, allocations: 217.4 MB / 0.8108 GB, free: 2.609 MB / 0.6231 GB
Notification: Performance of NFInst.instExpressions: time 0.01852/0.7632, allocations: 12.11 MB / 0.8227 GB, free: 6.477 MB / 0.6387 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002723/0.766, allocations: 67.62 kB / 0.8227 GB, free: 6.41 MB / 0.6387 GB
Notification: Performance of NFTyping.typeComponents: time 0.00266/0.7687, allocations: 0.7108 MB / 0.8234 GB, free: 5.695 MB / 0.6387 GB
Notification: Performance of NFTyping.typeBindings: time 0.008198/0.7769, allocations: 2.685 MB / 0.8261 GB, free: 3 MB / 0.6387 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004049/0.7809, allocations: 1.295 MB / 0.8273 GB, free: 1.719 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.007596/0.7886, allocations: 6.095 MB / 0.8333 GB, free: 11.61 MB / 0.6544 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002911/0.7915, allocations: 1.445 MB / 0.8347 GB, free: 10.13 MB / 0.6544 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006345/0.7979, allocations: 2.148 MB / 0.8368 GB, free: 7.98 MB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004109/0.802, allocations: 1.518 MB / 0.8383 GB, free: 6.473 MB / 0.6544 GB
Notification: Performance of NFPackage.collectConstants: time 0.0006316/0.8026, allocations: 84 kB / 0.8383 GB, free: 6.391 MB / 0.6544 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006013/0.8087, allocations: 2.443 MB / 0.8407 GB, free: 3.941 MB / 0.6544 GB
Notification: Performance of NFScalarize.scalarize: time 0.001503/0.8102, allocations: 0.5957 MB / 0.8413 GB, free: 3.344 MB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.002338/0.8126, allocations: 0.9304 MB / 0.8422 GB, free: 2.402 MB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 0.004091/0.8167, allocations: 2.513 MB / 0.8447 GB, free: 15.88 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 5.711e-06/0.8167, allocations: 3.938 kB / 0.8447 GB, free: 15.88 MB / 0.67 GB
Notification: Performance of FrontEnd: time 1.854e-06/0.8167, allocations: 0 / 0.8447 GB, free: 15.88 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 0.0001194/0.8168, allocations: 0 / 0.8447 GB, free: 15.88 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: 351
 * Number of variables: 351
Notification: Performance of Generate backend data structure: time 0.004759/0.8216, allocations: 1.897 MB / 0.8465 GB, free: 13.93 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.446e-05/0.8217, allocations: 12.03 kB / 0.8465 GB, free: 13.92 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003497/0.8252, allocations: 0.5011 MB / 0.847 GB, free: 13.41 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002929/0.8281, allocations: 1.263 MB / 0.8483 GB, free: 12.09 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001159/0.8283, allocations: 110.7 kB / 0.8484 GB, free: 11.98 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004132/0.8287, allocations: 152 kB / 0.8485 GB, free: 11.84 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003408/0.8321, allocations: 1.758 MB / 0.8502 GB, free: 9.969 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.119e-05/0.8322, allocations: 0 / 0.8502 GB, free: 9.969 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001731/0.8323, allocations: 60 kB / 0.8503 GB, free: 9.91 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.499e-05/0.8324, allocations: 54.53 kB / 0.8503 GB, free: 9.855 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002496/0.8349, allocations: 1.403 MB / 0.8517 GB, free: 8.445 MB / 0.67 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.01022/0.8452, allocations: 7.316 MB / 0.8589 GB, free: 0.75 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.3276/1.173, allocations: 1.048 MB / 0.8599 GB, free: 209.5 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001172/1.174, allocations: 0.6933 MB / 0.8606 GB, free: 209.4 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002298/1.174, allocations: 115.8 kB / 0.8607 GB, free: 209.3 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.288e-05/1.174, allocations: 49.62 kB / 0.8607 GB, free: 209.3 MB / 0.67 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.00149/1.176, allocations: 303.6 kB / 0.861 GB, free: 209.3 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=106): time 3.917e-06/1.176, allocations: 2.391 kB / 0.861 GB, free: 209.3 MB / 0.67 GB
Notification: Performance of matching and sorting (n=112): time 0.007581/1.183, allocations: 4.428 MB / 0.8653 GB, free: 208 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.316e-05/1.183, allocations: 168.2 kB / 0.8655 GB, free: 207.8 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001453/1.185, allocations: 1.26 MB / 0.8667 GB, free: 207.6 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001116/1.185, allocations: 53.36 kB / 0.8668 GB, free: 207.6 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004258/1.185, allocations: 0.7107 MB / 0.8675 GB, free: 207.4 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002742/1.186, allocations: 399.7 kB / 0.8678 GB, free: 207.1 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001419/1.186, allocations: 116.9 kB / 0.868 GB, free: 207.1 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001052/1.186, allocations: 356.3 kB / 0.8683 GB, free: 206.8 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0008488/1.187, allocations: 0.5175 MB / 0.8688 GB, free: 206.8 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0008851/1.188, allocations: 0.8457 MB / 0.8696 GB, free: 206.4 MB / 0.67 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: vol.dynBal.medium.p = vol.dynBal.p_start (sin.p = vol.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: vol1.dynBal.medium.p = vol1.dynBal.p_start (sin.p = vol1.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: vol2.dynBal.medium.p = vol2.dynBal.p_start (sin.p = vol2.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.01059/1.198, allocations: 4.774 MB / 0.8743 GB, free: 204.5 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.835e-06/1.198, allocations: 2.438 kB / 0.8743 GB, free: 204.5 MB / 0.67 GB
Notification: Performance of matching and sorting (n=169) (initialization): time 0.002403/1.201, allocations: 1.601 MB / 0.8759 GB, free: 203.9 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 2.355e-05/1.201, allocations: 10.34 kB / 0.8759 GB, free: 203.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.618e-05/1.201, allocations: 10.16 kB / 0.8759 GB, free: 203.9 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.286e-05/1.201, allocations: 14.78 kB / 0.8759 GB, free: 203.9 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001069/1.202, allocations: 287 kB / 0.8762 GB, free: 203.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.57e-05/1.202, allocations: 25.75 kB / 0.8762 GB, free: 203.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001646/1.204, allocations: 109.1 kB / 0.8763 GB, free: 203.8 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001012/1.204, allocations: 61.7 kB / 0.8763 GB, free: 203.8 MB / 0.67 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization sytem:
         vol.dynBal.medium.p = vol.dynBal.p_start
         vol1.dynBal.medium.p = vol1.dynBal.p_start
         vol2.dynBal.medium.p = vol2.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 37
 * Number of states: 0 ()
 * Number of discrete variables: 6 (intMasVapOut.local_reset,intEntIn.local_reset,intEntOut.local_reset,intMasFloIn.local_reset,intMasFloOut.local_reset,intMasFloVapIn.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (169):
 * Single equations (assignments): 169
 * 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.0007443/1.205, allocations: 428.8 kB / 0.8768 GB, free: 203.5 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002955/1.205, allocations: 162.8 kB / 0.8769 GB, free: 203.4 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00188/1.207, allocations: 1.28 MB / 0.8782 GB, free: 202.9 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.007e-05/1.207, allocations: 9.125 kB / 0.8782 GB, free: 202.9 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.077e-06/1.207, allocations: 6.938 kB / 0.8782 GB, free: 202.9 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001287/1.208, allocations: 1.233 MB / 0.8794 GB, free: 202.2 MB / 0.67 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.005021/1.213, allocations: 3.737 MB / 0.883 GB, free: 200.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.375e-05/1.213, allocations: 14 kB / 0.883 GB, free: 200.2 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001025/1.214, allocations: 245.8 kB / 0.8833 GB, free: 200.2 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.655e-05/1.214, allocations: 19.75 kB / 0.8833 GB, free: 200.2 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.584e-05/1.214, allocations: 45.78 kB / 0.8833 GB, free: 200.1 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.489e-05/1.214, allocations: 22.22 kB / 0.8834 GB, free: 200.1 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.127e-06/1.214, allocations: 14.44 kB / 0.8834 GB, free: 200.1 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002404/1.217, allocations: 2.102 MB / 0.8854 GB, free: 198.9 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006504/1.217, allocations: 354.9 kB / 0.8858 GB, free: 198.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001802/1.218, allocations: 44 kB / 0.8858 GB, free: 198.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001443/1.219, allocations: 101.7 kB / 0.8859 GB, free: 198.6 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001339/1.219, allocations: 94.44 kB / 0.886 GB, free: 198.6 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.845e-05/1.219, allocations: 51.02 kB / 0.886 GB, free: 198.6 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.001016/1.22, allocations: 0.8536 MB / 0.8869 GB, free: 198.3 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.71e-07/1.22, allocations: 1.406 kB / 0.8869 GB, free: 198.3 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.001435/1.222, allocations: 434.7 kB / 0.8873 GB, free: 198.2 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 44
 * Number of states: 12 (vol.dynBal.medium.Xi[1],vol.dynBal.U,vol1.dynBal.medium.Xi[1],vol1.dynBal.U,vol2.dynBal.medium.Xi[1],vol2.dynBal.U,intMasFloVapIn.y,intMasFloOut.y,intMasFloIn.y,intEntOut.y,intEntIn.y,intMasVapOut.y)
 * 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 (115):
 * Single equations (assignments): 115
 * 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.0005552/1.222, allocations: 244.7 kB / 0.8875 GB, free: 198.1 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.002012/1.224, allocations: 1.209 MB / 0.8887 GB, free: 197.7 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 7.944e-06/1.224, allocations: 2.812 kB / 0.8887 GB, free: 197.7 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.0008644/1.225, allocations: 0.5437 MB / 0.8892 GB, free: 197.5 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00657/1.232, allocations: 0.8974 MB / 0.8901 GB, free: 197.3 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005946/1.238, allocations: 4.235 MB / 0.8943 GB, free: 194.7 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009369/1.239, allocations: 1.367 MB / 0.8956 GB, free: 193.9 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000607/1.239, allocations: 134.5 kB / 0.8957 GB, free: 193.9 MB / 0.67 GB
Notification: Performance of SimCode: time 6.61e-07/1.239, allocations: 5.219 kB / 0.8957 GB, free: 193.9 MB / 0.67 GB
Notification: Performance of Templates: time 0.05342/1.293, allocations: 24.94 MB / 0.9201 GB, free: 188.7 MB / 0.67 GB
make -j1 -f BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.makefile
(rm -f BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.sim & ./BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe 2>&1)