Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.conf.json
Using package ModelicaServices with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package IBPSA with version 3.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo)
Using package Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002508/0.002512, allocations: 355.9 kB / 15.94 MB, free: 6.207 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 1.42/1.42, allocations: 165.8 MB / 182.5 MB, free: 5.387 MB / 138.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 3.947/3.947, allocations: 410.8 MB / 0.5995 GB, free: 15.18 MB / 298.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001712/0.001712, allocations: 186 kB / 0.6662 GB, free: 6.941 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004752/0.0004755, allocations: 16 kB / 0.7423 GB, free: 8.984 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3349/0.3355, allocations: 67.45 MB / 0.8081 GB, free: 28.8 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance): time 0.6252/0.9607, allocations: 199 MB / 1.003 GB, free: 14.25 MB / 490.7 MB
Notification: Performance of NFInst.instExpressions: time 0.0207/0.9814, allocations: 11.88 MB / 1.014 GB, free: 10.98 MB / 490.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003246/0.9847, allocations: 62.47 kB / 1.014 GB, free: 10.95 MB / 490.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003337/0.9881, allocations: 0.7879 MB / 1.015 GB, free: 10.39 MB / 490.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01086/0.999, allocations: 2.82 MB / 1.018 GB, free: 8.578 MB / 490.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006129/1.005, allocations: 1.556 MB / 1.019 GB, free: 7.953 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.007508/1.013, allocations: 5.126 MB / 1.024 GB, free: 6.102 MB / 490.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002714/1.015, allocations: 1.29 MB / 1.026 GB, free: 5.551 MB / 490.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00484/1.02, allocations: 1.722 MB / 1.027 GB, free: 4.797 MB / 490.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003173/1.024, allocations: 1.438 MB / 1.029 GB, free: 3.68 MB / 490.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004733/1.024, allocations: 76 kB / 1.029 GB, free: 3.605 MB / 490.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003598/1.028, allocations: 0.8442 MB / 1.029 GB, free: 2.898 MB / 490.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001098/1.029, allocations: 0.584 MB / 1.03 GB, free: 2.426 MB / 490.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0008387/1.03, allocations: 385.3 kB / 1.03 GB, free: 2.152 MB / 490.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.004138/1.034, allocations: 2.151 MB / 1.033 GB, free: 228 kB / 490.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.842e-06/1.034, allocations: 0 / 1.033 GB, free: 228 kB / 490.7 MB
Notification: Performance of FrontEnd: time 8.739e-06/1.034, allocations: 4 kB / 1.033 GB, free: 224 kB / 490.7 MB
Notification: Performance of Transformations before backend: time 4.497e-05/1.034, allocations: 0 / 1.033 GB, free: 224 kB / 490.7 MB
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.00447/1.038, allocations: 1.87 MB / 1.034 GB, free: 14.43 MB / 0.4948 GB
Notification: Performance of prepare preOptimizeDAE: time 4.125e-05/1.038, allocations: 8.031 kB / 1.034 GB, free: 14.42 MB / 0.4948 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004176/1.043, allocations: 498.2 kB / 1.035 GB, free: 13.95 MB / 0.4948 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003021/1.046, allocations: 1.196 MB / 1.036 GB, free: 12.93 MB / 0.4948 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001126/1.046, allocations: 111.4 kB / 1.036 GB, free: 12.87 MB / 0.4948 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003212/1.046, allocations: 152.5 kB / 1.036 GB, free: 12.77 MB / 0.4948 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003968/1.05, allocations: 1.743 MB / 1.038 GB, free: 11.43 MB / 0.4948 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.296e-05/1.05, allocations: 7.938 kB / 1.038 GB, free: 11.42 MB / 0.4948 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001875/1.05, allocations: 62.25 kB / 1.038 GB, free: 11.41 MB / 0.4948 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.63e-05/1.051, allocations: 50.55 kB / 1.038 GB, free: 11.36 MB / 0.4948 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00334/1.054, allocations: 1.386 MB / 1.039 GB, free: 10.41 MB / 0.4948 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.01284/1.067, allocations: 7.299 MB / 1.047 GB, free: 3.863 MB / 0.4948 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002335/1.069, allocations: 1.021 MB / 1.048 GB, free: 3.09 MB / 0.4948 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001607/1.071, allocations: 0.6905 MB / 1.048 GB, free: 2.391 MB / 0.4948 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003173/1.071, allocations: 110.1 kB / 1.048 GB, free: 2.266 MB / 0.4948 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.875e-05/1.071, allocations: 55.52 kB / 1.048 GB, free: 2.199 MB / 0.4948 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001659/1.073, allocations: 306.7 kB / 1.049 GB, free: 1.898 MB / 0.4948 GB
Notification: Performance of pre-optimization done (n=106): time 5.992e-06/1.073, allocations: 0 / 1.049 GB, free: 1.898 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=112): time 0.01106/1.084, allocations: 4.28 MB / 1.053 GB, free: 13.54 MB / 0.5105 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001286/1.084, allocations: 171.2 kB / 1.053 GB, free: 13.34 MB / 0.5105 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00235/1.087, allocations: 1.234 MB / 1.054 GB, free: 12.09 MB / 0.5105 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002636/1.087, allocations: 53.83 kB / 1.054 GB, free: 12.03 MB / 0.5105 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0007746/1.088, allocations: 0.6967 MB / 1.055 GB, free: 11.33 MB / 0.5105 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006268/1.088, allocations: 398.2 kB / 1.055 GB, free: 10.93 MB / 0.5105 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001072/1.088, allocations: 31.94 kB / 1.055 GB, free: 10.9 MB / 0.5105 GB
Notification: Performance of setup shared object (initialization): time 0.0002222/1.089, allocations: 359.8 kB / 1.056 GB, free: 10.55 MB / 0.5105 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00126/1.09, allocations: 0.5075 MB / 1.056 GB, free: 10.04 MB / 0.5105 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001669/1.092, allocations: 0.8391 MB / 1.057 GB, free: 9.074 MB / 0.5105 GB
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)
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: vol.dynBal.medium.p = vol.dynBal.p_start (sin.p = vol.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.01382/1.105, allocations: 3.465 MB / 1.06 GB, free: 5.562 MB / 0.5105 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.523e-06/1.106, allocations: 4 kB / 1.06 GB, free: 5.559 MB / 0.5105 GB
Notification: Performance of matching and sorting (n=169) (initialization): time 0.003967/1.109, allocations: 1.592 MB / 1.062 GB, free: 3.961 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 2.972e-05/1.11, allocations: 12.22 kB / 1.062 GB, free: 3.949 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.082e-05/1.11, allocations: 8 kB / 1.062 GB, free: 3.941 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.62e-05/1.11, allocations: 16 kB / 1.062 GB, free: 3.926 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001695/1.111, allocations: 283.5 kB / 1.062 GB, free: 3.648 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.8e-05/1.111, allocations: 27.98 kB / 1.062 GB, free: 3.621 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001836/1.113, allocations: 93.61 kB / 1.062 GB, free: 3.531 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001657/1.113, allocations: 55.98 kB / 1.062 GB, free: 3.477 MB / 0.5105 GB
Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization sytem:
         vol2.dynBal.medium.p = vol2.dynBal.p_start
         vol1.dynBal.medium.p = vol1.dynBal.p_start
         vol.dynBal.medium.p = vol.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 (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001083/1.115, allocations: 439.1 kB / 1.063 GB, free: 3.039 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005415/1.115, allocations: 166 kB / 1.063 GB, free: 2.875 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002994/1.118, allocations: 1.255 MB / 1.064 GB, free: 1.605 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.729e-05/1.118, allocations: 12 kB / 1.064 GB, free: 1.594 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.826e-06/1.118, allocations: 4 kB / 1.064 GB, free: 1.59 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002305/1.12, allocations: 1.225 MB / 1.065 GB, free: 304 kB / 0.5105 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.008863/1.129, allocations: 3.726 MB / 1.069 GB, free: 12.37 MB / 0.5261 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.956e-05/1.129, allocations: 12.25 kB / 1.069 GB, free: 12.36 MB / 0.5261 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001643/1.131, allocations: 255.6 kB / 1.069 GB, free: 12.11 MB / 0.5261 GB
Notification: Performance of postOpt tearingSystem (simulation): time 2.889e-05/1.131, allocations: 16 kB / 1.069 GB, free: 12.09 MB / 0.5261 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001192/1.131, allocations: 39.98 kB / 1.069 GB, free: 12.05 MB / 0.5261 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.3e-05/1.131, allocations: 17.8 kB / 1.069 GB, free: 12.04 MB / 0.5261 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.938e-05/1.131, allocations: 15.94 kB / 1.069 GB, free: 12.02 MB / 0.5261 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004311/1.136, allocations: 2.191 MB / 1.072 GB, free: 9.676 MB / 0.5261 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00111/1.137, allocations: 355 kB / 1.072 GB, free: 9.309 MB / 0.5261 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003213/1.137, allocations: 39.98 kB / 1.072 GB, free: 9.27 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001609/1.139, allocations: 95.78 kB / 1.072 GB, free: 9.176 MB / 0.5261 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001951/1.139, allocations: 100.3 kB / 1.072 GB, free: 9.078 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001334/1.139, allocations: 51.98 kB / 1.072 GB, free: 9.027 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.00167/1.141, allocations: 0.8328 MB / 1.073 GB, free: 8.195 MB / 0.5261 GB
Notification: Performance of sort global known variables: time 1.012e-06/1.141, allocations: 0 / 1.073 GB, free: 8.195 MB / 0.5261 GB
Notification: Performance of remove unused functions: time 0.002022/1.143, allocations: 432 kB / 1.073 GB, free: 7.773 MB / 0.5261 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 44
 * Number of states: 12 (vol.dynBal.U,vol.dynBal.mXi[1],vol1.dynBal.U,vol1.dynBal.mXi[1],vol2.dynBal.U,vol2.dynBal.mXi[1],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 (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.000642/1.144, allocations: 246.8 kB / 1.074 GB, free: 7.535 MB / 0.5261 GB
Notification: Performance of simCode: created initialization part: time 0.00284/1.146, allocations: 1.207 MB / 1.075 GB, free: 6.328 MB / 0.5261 GB
Notification: Performance of simCode: created event and clocks part: time 9.786e-06/1.146, allocations: 0 / 1.075 GB, free: 6.328 MB / 0.5261 GB
Notification: Performance of simCode: created simulation system equations: time 0.001244/1.148, allocations: 0.5407 MB / 1.075 GB, free: 5.793 MB / 0.5261 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00558/1.153, allocations: 0.892 MB / 1.076 GB, free: 4.914 MB / 0.5261 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.007789/1.161, allocations: 4.005 MB / 1.08 GB, free: 0.7422 MB / 0.5261 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001581/1.163, allocations: 1.403 MB / 1.082 GB, free: 15.31 MB / 0.5417 GB
Notification: Performance of simCode: alias equations: time 0.002197/1.165, allocations: 377.2 kB / 1.082 GB, free: 14.94 MB / 0.5417 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000572/1.166, allocations: 123.4 kB / 1.082 GB, free: 14.82 MB / 0.5417 GB
Notification: Performance of SimCode: time 1.64e-06/1.166, allocations: 0 / 1.082 GB, free: 14.82 MB / 0.5417 GB
Notification: Performance of Templates: time 0.07511/1.241, allocations: 27.78 MB / 1.109 GB, free: 3.336 MB / 0.5573 GB