Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.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.001202/0.001202, allocations: 80.94 kB / 19.94 MB, free: 4.547 MB / 18.57 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.001262/0.001262, allocations: 173 kB / 23.36 MB, free: 1.133 MB / 18.57 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.026/1.026, allocations: 177.1 MB / 203.8 MB, free: 5.484 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/package.mo): time 0.001803/0.001803, allocations: 245.4 kB / 260.5 MB, free: 12.7 MB / 250.7 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo): time 0.7266/0.7266, allocations: 138.4 MB / 402.2 MB, free: 0.5586 MB / 362.7 MB " [Timeout remaining time 179] Using package BuildingSystems with version 2.0.0-beta (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildingSystems master/package.mo) Using package NcDataReader2 with version 2.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/NcDataReader2 master/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: translateModel(BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance") translateModel(BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.262e-06/1.262e-06, allocations: 0 / 462.3 MB, free: 0.9336 MB / 410.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 1.034e-05/1.16e-05, allocations: 4.172 kB / 462.3 MB, free: 0.9297 MB / 410.7 MB Notification: Performance of NFInst.instantiate(BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance): time 0.1427/0.1427, allocations: 154.2 MB / 0.6021 GB, free: 8.238 MB / 0.5573 GB Notification: Performance of NFInst.instExpressions: time 0.008755/0.1515, allocations: 5.401 MB / 0.6074 GB, free: 2.836 MB / 0.5573 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.001161/0.1526, allocations: 99.19 kB / 0.6075 GB, free: 2.738 MB / 0.5573 GB Notification: Performance of NFTyping.typeComponents: time 0.0008782/0.1535, allocations: 464.3 kB / 0.6079 GB, free: 2.281 MB / 0.5573 GB Notification: Performance of NFTyping.typeBindings: time 0.002424/0.1559, allocations: 1.626 MB / 0.6095 GB, free: 0.6484 MB / 0.5573 GB Notification: Performance of NFTyping.typeClassSections: time 0.001995/0.1579, allocations: 1.289 MB / 0.6107 GB, free: 15.36 MB / 0.573 GB Notification: Performance of NFFlatten.flatten: time 0.004332/0.1622, allocations: 4.469 MB / 0.6151 GB, free: 10.88 MB / 0.573 GB Notification: Performance of NFFlatten.resolveConnections: time 0.001421/0.1637, allocations: 1.432 MB / 0.6165 GB, free: 9.406 MB / 0.573 GB Notification: Performance of NFEvalConstants.evaluate: time 0.003216/0.1669, allocations: 2.53 MB / 0.619 GB, free: 7.016 MB / 0.573 GB Notification: Performance of NFSimplifyModel.simplify: time 0.001577/0.1685, allocations: 1.333 MB / 0.6203 GB, free: 5.68 MB / 0.573 GB Notification: Performance of NFPackage.collectConstants: time 0.0002413/0.1687, allocations: 183.9 kB / 0.6205 GB, free: 5.5 MB / 0.573 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0007878/0.1695, allocations: 483 kB / 0.6209 GB, free: 5.027 MB / 0.573 GB Notification: Performance of NFScalarize.scalarize: time 0.0005089/0.17, allocations: 0.7673 MB / 0.6217 GB, free: 4.258 MB / 0.573 GB Notification: Performance of NFVerifyModel.verify: time 0.0006544/0.1707, allocations: 0.7327 MB / 0.6224 GB, free: 3.523 MB / 0.573 GB Notification: Performance of NFConvertDAE.convert: time 0.001679/0.1723, allocations: 1.841 MB / 0.6242 GB, free: 1.676 MB / 0.573 GB Notification: Performance of FrontEnd - DAE generated: time 4.468e-06/0.1723, allocations: 0 / 0.6242 GB, free: 1.676 MB / 0.573 GB Notification: Performance of FrontEnd: time 2.024e-06/0.1723, allocations: 0 / 0.6242 GB, free: 1.676 MB / 0.573 GB Notification: Performance of Transformations before backend: time 2.518e-05/0.1724, allocations: 0 / 0.6242 GB, free: 1.676 MB / 0.573 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.002167/0.1745, allocations: 2.039 MB / 0.6262 GB, free: 15.61 MB / 0.5886 GB Notification: Performance of prepare preOptimizeDAE: time 3.901e-05/0.1746, allocations: 12.75 kB / 0.6262 GB, free: 15.59 MB / 0.5886 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001455/0.176, allocations: 0.5856 MB / 0.6268 GB, free: 15.01 MB / 0.5886 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.001282/0.1773, allocations: 1.033 MB / 0.6278 GB, free: 13.92 MB / 0.5886 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.395e-05/0.1774, allocations: 108.8 kB / 0.6279 GB, free: 13.82 MB / 0.5886 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001521/0.1775, allocations: 156 kB / 0.628 GB, free: 13.66 MB / 0.5886 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001318/0.1789, allocations: 1.526 MB / 0.6295 GB, free: 11.94 MB / 0.5886 GB Notification: Performance of preOpt findStateOrder (simulation): time 3.185e-05/0.1789, allocations: 7.938 kB / 0.6295 GB, free: 11.93 MB / 0.5886 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 8.659e-05/0.179, allocations: 60.62 kB / 0.6296 GB, free: 11.87 MB / 0.5886 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.664e-05/0.179, allocations: 58.47 kB / 0.6296 GB, free: 11.81 MB / 0.5886 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.000604/0.1796, allocations: 0.501 MB / 0.6301 GB, free: 11.29 MB / 0.5886 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.00429/0.1839, allocations: 4.795 MB / 0.6348 GB, free: 6.359 MB / 0.5886 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0006846/0.1846, allocations: 0.7742 MB / 0.6356 GB, free: 5.551 MB / 0.5886 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0002892/0.1849, allocations: 290 kB / 0.6358 GB, free: 5.254 MB / 0.5886 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0001191/0.185, allocations: 36.98 kB / 0.6359 GB, free: 5.215 MB / 0.5886 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.42e-05/0.185, allocations: 55.5 kB / 0.6359 GB, free: 5.152 MB / 0.5886 GB Notification: Performance of preOpt simplifyInStream (simulation): time 0.0006337/0.1857, allocations: 311.1 kB / 0.6362 GB, free: 4.848 MB / 0.5886 GB Notification: Performance of pre-optimization done (n=103): time 2.495e-06/0.1857, allocations: 0 / 0.6362 GB, free: 4.848 MB / 0.5886 GB Notification: Performance of matching and sorting (n=109): time 0.003133/0.1888, allocations: 1.718 MB / 0.6379 GB, free: 3.109 MB / 0.5886 GB Notification: Performance of inlineWhenForInitialization (initialization): time 7.163e-05/0.1889, allocations: 167.2 kB / 0.6381 GB, free: 2.91 MB / 0.5886 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005628/0.1895, allocations: 0.8526 MB / 0.6389 GB, free: 2.047 MB / 0.5886 GB Notification: Performance of collectPreVariables (initialization): time 6.749e-05/0.1895, allocations: 49.7 kB / 0.6389 GB, free: 1.992 MB / 0.5886 GB Notification: Performance of collectInitialEqns (initialization): time 0.0002667/0.1898, allocations: 0.5735 MB / 0.6395 GB, free: 1.406 MB / 0.5886 GB Notification: Performance of collectInitialBindings (initialization): time 0.0002182/0.19, allocations: 412.4 kB / 0.6399 GB, free: 1 MB / 0.5886 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002193/0.1902, allocations: 230.7 kB / 0.6401 GB, free: 0.7695 MB / 0.5886 GB Notification: Performance of setup shared object (initialization): time 9.829e-05/0.1903, allocations: 365.8 kB / 0.6405 GB, free: 416 kB / 0.5886 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001954/0.1905, allocations: 145.4 kB / 0.6406 GB, free: 268 kB / 0.5886 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005317/0.1911, allocations: 0.5371 MB / 0.6411 GB, free: 15.59 MB / 0.6042 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.001453/0.1925, allocations: 1.451 MB / 0.6425 GB, free: 14.03 MB / 0.6042 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.488e-06/0.1925, allocations: 7.938 kB / 0.6425 GB, free: 14.02 MB / 0.6042 GB Notification: Performance of matching and sorting (n=160) (initialization): time 0.0007466/0.1933, allocations: 0.6953 MB / 0.6432 GB, free: 13.32 MB / 0.6042 GB Notification: Performance of prepare postOptimizeDAE: time 2.018e-05/0.1933, allocations: 11.94 kB / 0.6432 GB, free: 13.31 MB / 0.6042 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.749e-06/0.1933, allocations: 8 kB / 0.6432 GB, free: 13.3 MB / 0.6042 GB Notification: Performance of postOpt tearingSystem (initialization): time 2.119e-05/0.1933, allocations: 16 kB / 0.6433 GB, free: 13.29 MB / 0.6042 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00056/0.1939, allocations: 275.1 kB / 0.6435 GB, free: 13.02 MB / 0.6042 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.185e-05/0.1939, allocations: 16 kB / 0.6435 GB, free: 13 MB / 0.6042 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0004452/0.1943, allocations: 39.94 kB / 0.6436 GB, free: 12.96 MB / 0.6042 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.015e-05/0.1944, allocations: 47.98 kB / 0.6436 GB, free: 12.92 MB / 0.6042 GB Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization system: 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 (160): * Single equations (assignments): 160 * 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.0002662/0.1946, allocations: 203.9 kB / 0.6438 GB, free: 12.71 MB / 0.6042 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001681/0.1948, allocations: 135.1 kB / 0.6439 GB, free: 12.58 MB / 0.6042 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006324/0.1954, allocations: 0.5953 MB / 0.6445 GB, free: 11.98 MB / 0.6042 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.923e-06/0.1955, allocations: 12 kB / 0.6445 GB, free: 11.96 MB / 0.6042 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.454e-06/0.1955, allocations: 0 / 0.6445 GB, free: 11.96 MB / 0.6042 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0004299/0.1959, allocations: 0.5982 MB / 0.6451 GB, free: 11.3 MB / 0.6042 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002249/0.1981, allocations: 2.208 MB / 0.6473 GB, free: 8.895 MB / 0.6042 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.653e-06/0.1981, allocations: 8 kB / 0.6473 GB, free: 8.887 MB / 0.6042 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007219/0.1989, allocations: 244.5 kB / 0.6475 GB, free: 8.648 MB / 0.6042 GB Notification: Performance of postOpt tearingSystem (simulation): time 1.267e-05/0.1989, allocations: 20 kB / 0.6475 GB, free: 8.629 MB / 0.6042 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.242e-05/0.1989, allocations: 32 kB / 0.6476 GB, free: 8.598 MB / 0.6042 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.28e-05/0.1989, allocations: 23.92 kB / 0.6476 GB, free: 8.574 MB / 0.6042 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.173e-06/0.199, allocations: 15.94 kB / 0.6476 GB, free: 8.559 MB / 0.6042 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0009866/0.1999, allocations: 1.187 MB / 0.6488 GB, free: 7.207 MB / 0.6042 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0002902/0.2002, allocations: 191 kB / 0.6489 GB, free: 7.02 MB / 0.6042 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001031/0.2003, allocations: 39.98 kB / 0.649 GB, free: 6.98 MB / 0.6042 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004321/0.2008, allocations: 39.72 kB / 0.649 GB, free: 6.945 MB / 0.6042 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 8.875e-05/0.2009, allocations: 108.1 kB / 0.6491 GB, free: 6.84 MB / 0.6042 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.798e-05/0.2009, allocations: 39.98 kB / 0.6492 GB, free: 6.801 MB / 0.6042 GB Notification: Performance of sorting global known variables: time 0.0002968/0.2012, allocations: 425.3 kB / 0.6496 GB, free: 6.379 MB / 0.6042 GB Notification: Performance of sort global known variables: time 7.1e-08/0.2012, allocations: 0 / 0.6496 GB, free: 6.379 MB / 0.6042 GB Notification: Performance of remove unused functions: time 0.0007397/0.2019, allocations: 423.9 kB / 0.65 GB, free: 5.965 MB / 0.6042 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 (112): * Single equations (assignments): 112 * 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.0002547/0.2022, allocations: 160.1 kB / 0.6501 GB, free: 5.801 MB / 0.6042 GB Notification: Performance of simCode: created initialization part: time 0.0006445/0.2028, allocations: 0.5846 MB / 0.6507 GB, free: 5.215 MB / 0.6042 GB Notification: Performance of simCode: created event and clocks part: time 1.52e-05/0.2028, allocations: 17.5 kB / 0.6507 GB, free: 5.199 MB / 0.6042 GB Notification: Performance of simCode: created simulation system equations: time 0.0002706/0.2031, allocations: 290.9 kB / 0.651 GB, free: 4.918 MB / 0.6042 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001811/0.2049, allocations: 0.8192 MB / 0.6518 GB, free: 4.172 MB / 0.6042 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00338/0.2083, allocations: 2.815 MB / 0.6545 GB, free: 1.176 MB / 0.6042 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0002868/0.2086, allocations: 353.6 kB / 0.6549 GB, free: 0.8047 MB / 0.6042 GB Notification: Performance of simCode: alias equations: time 0.000587/0.2092, allocations: 225.3 kB / 0.6551 GB, free: 0.5898 MB / 0.6042 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001033/0.2102, allocations: 0.5359 MB / 0.6556 GB, free: 48 kB / 0.6042 GB Notification: Performance of SimCode: time 8.32e-07/0.2102, allocations: 0 / 0.6556 GB, free: 48 kB / 0.6042 GB Notification: Performance of Templates: time 0.2765/0.4867, allocations: 29.51 MB / 0.6844 GB, free: 78.37 MB / 0.6198 GB " [Timeout remaining time 660] make -j1 -f BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.makefile [Timeout 660] (rm -f BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe ; mkfifo BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe ; head -c 1048576 < BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe >> ../files/BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.sim & ./BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance -abortSlowSimulation -alarm=240 -lv LOG_STATS > BuildingSystems_BuildingSystems.Fluid.MixingVolumes.Validation.MoistureMixingConservationDynamicBalance.pipe 2>&1) [Timeout 240] [Calling sys.exit(0), Time elapsed: 4.654611815698445]