Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001404/0.001404, allocations: 118.9 kB / 17.8 MB, free: 5.121 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001574/0.001574, allocations: 187.8 kB / 20.75 MB, free: 2.184 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.418/1.418, allocations: 225.6 MB / 249.2 MB, free: 3.656 MB / 190.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.8368/0.8368, allocations: 128.4 MB / 432.4 MB, free: 13.14 MB / 350.1 MB " [Timeout remaining time 179] Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/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(IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger") translateModel(IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 0.00196/0.00196, allocations: 67.88 kB / 0.5987 GB, free: 15.82 MB / 0.4981 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.4018/0.4037, allocations: 83.27 MB / 0.6801 GB, free: 7.078 MB / 0.4981 GB Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger): time 0.7945/1.198, allocations: 329.9 MB / 1.002 GB, free: 4.875 MB / 0.6856 GB Notification: Performance of NFInst.instExpressions: time 0.04402/1.242, allocations: 30.17 MB / 1.032 GB, free: 8.832 MB / 0.7169 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.004703/1.247, allocations: 95.53 kB / 1.032 GB, free: 8.773 MB / 0.7169 GB Notification: Performance of NFTyping.typeComponents: time 0.007096/1.254, allocations: 2.182 MB / 1.034 GB, free: 6.586 MB / 0.7169 GB Notification: Performance of NFTyping.typeBindings: time 0.0153/1.269, allocations: 5.165 MB / 1.039 GB, free: 1.402 MB / 0.7169 GB Notification: Performance of NFTyping.typeClassSections: time 0.01006/1.279, allocations: 3.686 MB / 1.043 GB, free: 13.73 MB / 0.7325 GB Notification: Performance of NFFlatten.flatten: time 0.01134/1.291, allocations: 6.894 MB / 1.049 GB, free: 6.816 MB / 0.7325 GB Notification: Performance of NFFlatten.resolveConnections: time 0.004554/1.295, allocations: 2.418 MB / 1.052 GB, free: 4.34 MB / 0.7325 GB Notification: Performance of NFEvalConstants.evaluate: time 0.01625/1.312, allocations: 5.603 MB / 1.057 GB, free: 14.73 MB / 0.7481 GB Notification: Performance of NFSimplifyModel.simplify: time 0.006622/1.318, allocations: 2.54 MB / 1.06 GB, free: 12.2 MB / 0.7481 GB Notification: Performance of NFPackage.collectConstants: time 0.001463/1.32, allocations: 300 kB / 1.06 GB, free: 11.91 MB / 0.7481 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0125/1.332, allocations: 5.07 MB / 1.065 GB, free: 6.824 MB / 0.7481 GB Notification: Performance of NFScalarize.scalarize: time 0.002053/1.334, allocations: 1.098 MB / 1.066 GB, free: 5.723 MB / 0.7481 GB Notification: Performance of NFVerifyModel.verify: time 0.003378/1.338, allocations: 1.738 MB / 1.068 GB, free: 3.977 MB / 0.7481 GB Notification: Performance of NFConvertDAE.convert: time 0.01356/1.351, allocations: 5.801 MB / 1.073 GB, free: 14.16 MB / 0.7638 GB Notification: Performance of FrontEnd - DAE generated: time 7.735e-06/1.351, allocations: 0 / 1.073 GB, free: 14.16 MB / 0.7638 GB Notification: Performance of FrontEnd: time 2.806e-06/1.351, allocations: 0 / 1.073 GB, free: 14.16 MB / 0.7638 GB Notification: Performance of Transformations before backend: time 0.0001439/1.351, allocations: 0 / 1.073 GB, free: 14.16 MB / 0.7638 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 536 * Number of variables: 536 Notification: Performance of Generate backend data structure: time 0.009741/1.361, allocations: 3.769 MB / 1.077 GB, free: 10.3 MB / 0.7638 GB Notification: Performance of prepare preOptimizeDAE: time 5.06e-05/1.361, allocations: 12.03 kB / 1.077 GB, free: 10.29 MB / 0.7638 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01007/1.371, allocations: 1.196 MB / 1.078 GB, free: 9.086 MB / 0.7638 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.005655/1.377, allocations: 2.102 MB / 1.08 GB, free: 6.906 MB / 0.7638 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002579/1.377, allocations: 168.1 kB / 1.08 GB, free: 6.738 MB / 0.7638 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008614/1.378, allocations: 220 kB / 1.081 GB, free: 6.523 MB / 0.7638 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.008408/1.386, allocations: 2.987 MB / 1.083 GB, free: 3.441 MB / 0.7638 GB Notification: Performance of preOpt findStateOrder (simulation): time 5.047e-05/1.386, allocations: 3.938 kB / 1.083 GB, free: 3.438 MB / 0.7638 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003644/1.387, allocations: 92 kB / 1.084 GB, free: 3.348 MB / 0.7638 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001229/1.387, allocations: 92.12 kB / 1.084 GB, free: 3.254 MB / 0.7638 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008333/1.395, allocations: 3.104 MB / 1.087 GB, free: 136 kB / 0.7638 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.02862/1.424, allocations: 13.14 MB / 1.099 GB, free: 1.957 MB / 0.7794 GB Notification: Performance of preOpt comSubExp (simulation): time 0.004743/1.429, allocations: 1.618 MB / 1.101 GB, free: 336 kB / 0.7794 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.002113/1.431, allocations: 0.8033 MB / 1.102 GB, free: 15.52 MB / 0.795 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0007407/1.431, allocations: 324.1 kB / 1.102 GB, free: 15.15 MB / 0.795 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.276e-05/1.431, allocations: 55.56 kB / 1.102 GB, free: 15.09 MB / 0.795 GB Notification: Performance of preOpt simplifyInStream (simulation): time 0.002444/1.434, allocations: 418.4 kB / 1.103 GB, free: 14.68 MB / 0.795 GB Notification: Performance of pre-optimization done (n=110): time 7.424e-06/1.434, allocations: 4 kB / 1.103 GB, free: 14.68 MB / 0.795 GB Notification: Performance of matching and sorting (n=110): time 0.007828/1.442, allocations: 2.564 MB / 1.105 GB, free: 12.11 MB / 0.795 GB Notification: Performance of inlineWhenForInitialization (initialization): time 5.944e-05/1.442, allocations: 64.41 kB / 1.105 GB, free: 12.04 MB / 0.795 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005328/1.447, allocations: 2.525 MB / 1.108 GB, free: 9.492 MB / 0.795 GB Notification: Performance of collectPreVariables (initialization): time 0.0004262/1.448, allocations: 57.7 kB / 1.108 GB, free: 9.43 MB / 0.795 GB Notification: Performance of collectInitialEqns (initialization): time 0.001381/1.449, allocations: 1.287 MB / 1.109 GB, free: 8.129 MB / 0.795 GB Notification: Performance of collectInitialBindings (initialization): time 0.0006294/1.45, allocations: 412.5 kB / 1.109 GB, free: 7.723 MB / 0.795 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001139/1.451, allocations: 407.2 kB / 1.11 GB, free: 7.32 MB / 0.795 GB Notification: Performance of setup shared object (initialization): time 0.0001693/1.451, allocations: 313.1 kB / 1.11 GB, free: 7.012 MB / 0.795 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.002934/1.454, allocations: 1 MB / 1.111 GB, free: 6.008 MB / 0.795 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.00361/1.457, allocations: 1.62 MB / 1.113 GB, free: 4.117 MB / 0.795 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.005668/1.463, allocations: 2.349 MB / 1.115 GB, free: 1.574 MB / 0.795 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.804e-05/1.463, allocations: 4 kB / 1.115 GB, free: 1.57 MB / 0.795 GB Notification: Performance of matching and sorting (n=210) (initialization): time 0.008358/1.471, allocations: 2.904 MB / 1.118 GB, free: 14.64 MB / 0.8106 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001037/1.472, allocations: 75.39 kB / 1.118 GB, free: 14.56 MB / 0.8106 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.038e-05/1.472, allocations: 12 kB / 1.118 GB, free: 14.55 MB / 0.8106 GB Notification: Performance of postOpt tearingSystem (initialization): time 6.098e-05/1.472, allocations: 24 kB / 1.118 GB, free: 14.52 MB / 0.8106 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003391/1.475, allocations: 0.5735 MB / 1.118 GB, free: 13.95 MB / 0.8106 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001814/1.477, allocations: 2.383 MB / 1.121 GB, free: 11.47 MB / 0.8106 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003859/1.481, allocations: 283.9 kB / 1.121 GB, free: 11.2 MB / 0.8106 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004343/1.481, allocations: 103.9 kB / 1.121 GB, free: 11.09 MB / 0.8106 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004188/1.485, allocations: 1.437 MB / 1.122 GB, free: 9.652 MB / 0.8106 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003663/1.489, allocations: 1.616 MB / 1.124 GB, free: 7.773 MB / 0.8106 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.005536/1.495, allocations: 2.338 MB / 1.126 GB, free: 5.242 MB / 0.8106 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.691e-05/1.495, allocations: 4 kB / 1.126 GB, free: 5.238 MB / 0.8106 GB Notification: Performance of matching and sorting (n=210) (initialization_lambda0): time 0.008052/1.503, allocations: 2.896 MB / 1.129 GB, free: 2.32 MB / 0.8106 GB Notification: Performance of prepare postOptimizeDAE: time 9.06e-05/1.503, allocations: 75.34 kB / 1.129 GB, free: 2.234 MB / 0.8106 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.463e-05/1.503, allocations: 19.98 kB / 1.129 GB, free: 2.215 MB / 0.8106 GB Notification: Performance of postOpt tearingSystem (initialization): time 3.211e-05/1.503, allocations: 20 kB / 1.129 GB, free: 2.195 MB / 0.8106 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002807/1.506, allocations: 0.5228 MB / 1.13 GB, free: 1.672 MB / 0.8106 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 6.502e-05/1.506, allocations: 35.86 kB / 1.13 GB, free: 1.637 MB / 0.8106 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00372/1.509, allocations: 288.2 kB / 1.13 GB, free: 1.355 MB / 0.8106 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003513/1.51, allocations: 103.9 kB / 1.13 GB, free: 1.254 MB / 0.8106 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 59 * Number of states: 0 () * Number of discrete variables: 4 (masExcRev.i2_w,masExcRev.i1_w,masExcFor.i2_w,masExcFor.i1_w) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (208): * Single equations (assignments): 202 * Array equations: 0 * Algorithm blocks: 2 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 4 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 4 systems {1, 1, 1, 1} * Without analytic Jacobian (size): 0 systems Notification: Performance of prepare postOptimizeDAE: time 0.0007234/1.51, allocations: 287.8 kB / 1.13 GB, free: 0.9648 MB / 0.8106 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008187/1.511, allocations: 274.3 kB / 1.131 GB, free: 0.6953 MB / 0.8106 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0056/1.517, allocations: 1.991 MB / 1.133 GB, free: 14.68 MB / 0.8263 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.295e-05/1.517, allocations: 8 kB / 1.133 GB, free: 14.68 MB / 0.8263 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 9.648e-06/1.517, allocations: 7.938 kB / 1.133 GB, free: 14.67 MB / 0.8263 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.527e-05/1.517, allocations: 7.969 kB / 1.133 GB, free: 14.66 MB / 0.8263 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01059/1.528, allocations: 4.605 MB / 1.137 GB, free: 9.918 MB / 0.8263 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.81e-05/1.528, allocations: 8 kB / 1.137 GB, free: 9.91 MB / 0.8263 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001912/1.529, allocations: 304.3 kB / 1.138 GB, free: 9.613 MB / 0.8263 GB Notification: Performance of postOpt tearingSystem (simulation): time 2.28e-05/1.529, allocations: 12 kB / 1.138 GB, free: 9.602 MB / 0.8263 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001474/1.53, allocations: 31.98 kB / 1.138 GB, free: 9.57 MB / 0.8263 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001603/1.531, allocations: 2.363 MB / 1.14 GB, free: 7.113 MB / 0.8263 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.145e-05/1.531, allocations: 12.28 kB / 1.14 GB, free: 7.102 MB / 0.8263 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005105/1.536, allocations: 2.05 MB / 1.142 GB, free: 4.957 MB / 0.8263 GB Notification: Performance of postOpt removeConstants (simulation): time 0.001616/1.538, allocations: 0.5498 MB / 1.142 GB, free: 4.387 MB / 0.8263 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0007814/1.539, allocations: 24 kB / 1.142 GB, free: 4.363 MB / 0.8263 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002886/1.542, allocations: 185.4 kB / 1.143 GB, free: 4.184 MB / 0.8263 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002336/1.542, allocations: 88.72 kB / 1.143 GB, free: 4.098 MB / 0.8263 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002707/1.542, allocations: 59.95 kB / 1.143 GB, free: 4.039 MB / 0.8263 GB Notification: Performance of sorting global known variables: time 0.003993/1.546, allocations: 1.731 MB / 1.144 GB, free: 2.305 MB / 0.8263 GB Notification: Performance of sort global known variables: time 1.1e-07/1.546, allocations: 4 kB / 1.144 GB, free: 2.301 MB / 0.8263 GB Notification: Performance of remove unused functions: time 0.005174/1.551, allocations: 1.315 MB / 1.146 GB, free: 0.9844 MB / 0.8263 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 25 * 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 simulation (110): * Single equations (assignments): 106 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 4 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 4 systems {1, 1, 1, 1} * Without analytic Jacobian (size): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.001178/1.552, allocations: 365.9 kB / 1.146 GB, free: 0.6367 MB / 0.8263 GB Notification: Performance of simCode: created initialization part: time 0.01116/1.564, allocations: 3.572 MB / 1.15 GB, free: 13.02 MB / 0.8419 GB Notification: Performance of simCode: created event and clocks part: time 2.317e-05/1.564, allocations: 4 kB / 1.15 GB, free: 13.02 MB / 0.8419 GB Notification: Performance of simCode: created simulation system equations: time 0.002563/1.566, allocations: 0.8081 MB / 1.15 GB, free: 12.21 MB / 0.8419 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.007949/1.574, allocations: 1.204 MB / 1.152 GB, free: 11.02 MB / 0.8419 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01114/1.585, allocations: 4.447 MB / 1.156 GB, free: 6.453 MB / 0.8419 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002239/1.588, allocations: 1.676 MB / 1.158 GB, free: 4.742 MB / 0.8419 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001514/1.589, allocations: 235.3 kB / 1.158 GB, free: 4.512 MB / 0.8419 GB Notification: Performance of SimCode: time 1.683e-06/1.589, allocations: 4 kB / 1.158 GB, free: 4.508 MB / 0.8419 GB Notification: Performance of Templates: time 0.4706/2.06, allocations: 87.51 MB / 1.243 GB, free: 366.9 MB / 0.8888 GB " [Timeout remaining time 658] make -j1 -f IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.makefile [Timeout 660] (rm -f IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.sim & ./IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.Interfaces.Examples.ReverseFlowMassExchanger.pipe 2>&1) [Timeout 480] [Calling sys.exit(0), Time elapsed: 15.981334856012836] Failed to read output from testmodel.py, exit status != 0: 2.0692234861198813 2.077090341 0.723396706 Calling exit ...