Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.MassExchangers.Validation.ConstantEffectivenessZeroFlowBothStreams.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.MassExchangers.Validation.ConstantEffectivenessZeroFlowBothStreams,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.MassExchangers.Validation.ConstantEffectivenessZeroFlowBothStreams")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.003707/0.003707, allocations: 357.5 kB / 16.59 MB, free: 5.562 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.573/1.573, allocations: 165.8 MB / 183.1 MB, free: 5.363 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.96/3.96, allocations: 410.8 MB / 0.6001 GB, free: 15.2 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.001715/0.001714, allocations: 204.6 kB / 0.6668 GB, free: 6.914 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004617/0.0004618, allocations: 8 kB / 0.7429 GB, free: 8.961 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4069/0.4074, allocations: 67.44 MB / 0.8087 GB, free: 28.77 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MassExchangers.Validation.ConstantEffectivenessZeroFlowBothStreams): time 0.6972/1.105, allocations: 191.8 MB / 0.996 GB, free: 15.14 MB / 490.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01503/1.12, allocations: 7.234 MB / 1.003 GB, free: 13.9 MB / 490.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002111/1.122, allocations: 37.09 kB / 1.003 GB, free: 13.89 MB / 490.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003277/1.125, allocations: 0.6366 MB / 1.004 GB, free: 13.52 MB / 490.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01114/1.136, allocations: 2.271 MB / 1.006 GB, free: 12.48 MB / 490.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00803/1.144, allocations: 1.125 MB / 1.007 GB, free: 12.18 MB / 490.7 MB
Notification: Performance of NFFlatten.flatten: time 0.008324/1.153, allocations: 3.185 MB / 1.01 GB, free: 11.1 MB / 490.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003223/1.156, allocations: 0.7841 MB / 1.011 GB, free: 10.77 MB / 490.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006715/1.163, allocations: 1.308 MB / 1.012 GB, free: 10.27 MB / 490.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003524/1.166, allocations: 0.8706 MB / 1.013 GB, free: 9.945 MB / 490.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006593/1.167, allocations: 44.5 kB / 1.013 GB, free: 9.945 MB / 490.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006969/1.174, allocations: 1.212 MB / 1.014 GB, free: 9.578 MB / 490.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001246/1.176, allocations: 340.1 kB / 1.015 GB, free: 9.422 MB / 490.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001109/1.177, allocations: 272 kB / 1.015 GB, free: 9.352 MB / 490.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.005473/1.182, allocations: 1.611 MB / 1.016 GB, free: 8.578 MB / 490.7 MB
Notification: Performance of FrontEnd - DAE generated: time 8.045e-06/1.182, allocations: 0 / 1.016 GB, free: 8.578 MB / 490.7 MB
Notification: Performance of FrontEnd: time 3.116e-06/1.182, allocations: 4 kB / 1.016 GB, free: 8.574 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 6.84e-05/1.182, allocations: 0 / 1.016 GB, free: 8.574 MB / 490.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 209
 * Number of variables: 209
Notification: Performance of Generate backend data structure: time 0.01102/1.193, allocations: 1.583 MB / 1.018 GB, free: 7.504 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 6.295e-05/1.194, allocations: 8.688 kB / 1.018 GB, free: 7.504 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008771/1.202, allocations: 0.5885 MB / 1.019 GB, free: 7.273 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004927/1.207, allocations: 0.9283 MB / 1.019 GB, free: 6.773 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001976/1.209, allocations: 196.6 kB / 1.02 GB, free: 6.727 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005433/1.21, allocations: 84.86 kB / 1.02 GB, free: 6.727 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.008172/1.218, allocations: 1.274 MB / 1.021 GB, free: 6.379 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.958e-05/1.218, allocations: 0 / 1.021 GB, free: 6.379 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003055/1.219, allocations: 32.53 kB / 1.021 GB, free: 6.379 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001411/1.219, allocations: 48.77 kB / 1.021 GB, free: 6.371 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01179/1.231, allocations: 1.607 MB / 1.023 GB, free: 5.605 MB / 490.7 MB
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.01804/1.249, allocations: 4.099 MB / 1.027 GB, free: 2.164 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00769/1.257, allocations: 1.045 MB / 1.028 GB, free: 1.383 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004473/1.261, allocations: 0.6966 MB / 1.028 GB, free: 0.8672 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.002781/1.264, allocations: 0.6416 MB / 1.029 GB, free: 128 kB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.241e-05/1.264, allocations: 41.72 kB / 1.029 GB, free: 84 kB / 490.7 MB
Notification: Performance of pre-optimization done (n=76): time 5.891e-06/1.264, allocations: 0 / 1.029 GB, free: 84 kB / 490.7 MB
Notification: Performance of matching and sorting (n=76): time 0.01481/1.279, allocations: 1.911 MB / 1.031 GB, free: 14.6 MB / 0.4948 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.947e-05/1.279, allocations: 60.06 kB / 1.031 GB, free: 14.52 MB / 0.4948 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002682/1.282, allocations: 0.8174 MB / 1.032 GB, free: 13.89 MB / 0.4948 GB
Notification: Performance of collectPreVariables (initialization): time 0.000408/1.282, allocations: 45.25 kB / 1.032 GB, free: 13.85 MB / 0.4948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008342/1.283, allocations: 437 kB / 1.032 GB, free: 13.58 MB / 0.4948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000725/1.284, allocations: 261.7 kB / 1.032 GB, free: 13.36 MB / 0.4948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001636/1.284, allocations: 14.12 kB / 1.032 GB, free: 13.36 MB / 0.4948 GB
Notification: Performance of setup shared object (initialization): time 0.0002537/1.284, allocations: 332.7 kB / 1.033 GB, free: 13.04 MB / 0.4948 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004389/1.289, allocations: 0.5683 MB / 1.033 GB, free: 12.61 MB / 0.4948 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004645/1.294, allocations: 0.6929 MB / 1.034 GB, free: 12.04 MB / 0.4948 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.004465/1.298, allocations: 0.6124 MB / 1.035 GB, free: 11.59 MB / 0.4948 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.082e-05/1.298, allocations: 0 / 1.035 GB, free: 11.59 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=87) (initialization): time 0.009907/1.308, allocations: 1.43 MB / 1.036 GB, free: 10.47 MB / 0.4948 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002111/1.308, allocations: 155.3 kB / 1.036 GB, free: 10.3 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.299e-05/1.308, allocations: 1.859 kB / 1.036 GB, free: 10.3 MB / 0.4948 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.804e-05/1.308, allocations: 4 kB / 1.036 GB, free: 10.29 MB / 0.4948 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004687/1.313, allocations: 289.7 kB / 1.036 GB, free: 10.02 MB / 0.4948 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001389/1.315, allocations: 1.181 MB / 1.038 GB, free: 8.809 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003577/1.318, allocations: 196.2 kB / 1.038 GB, free: 8.629 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004183/1.319, allocations: 36.28 kB / 1.038 GB, free: 8.598 MB / 0.4948 GB
Notification: Performance of prepare postOptimizeDAE: time 2.092e-05/1.319, allocations: 7.938 kB / 1.038 GB, free: 8.59 MB / 0.4948 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.009395/1.328, allocations: 1.318 MB / 1.039 GB, free: 7.594 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.467e-05/1.328, allocations: 0 / 1.039 GB, free: 7.594 MB / 0.4948 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.749e-05/1.328, allocations: 8.062 kB / 1.039 GB, free: 7.586 MB / 0.4948 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003709/1.332, allocations: 259.5 kB / 1.039 GB, free: 7.34 MB / 0.4948 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.84e-05/1.332, allocations: 7.984 kB / 1.039 GB, free: 7.332 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003447/1.335, allocations: 195.5 kB / 1.04 GB, free: 7.152 MB / 0.4948 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003518/1.336, allocations: 38.16 kB / 1.04 GB, free: 7.121 MB / 0.4948 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 5
 * Number of states: 0 ()
 * Number of discrete variables: 2 (hex.i1_w,hex.i2_w)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (86):
 * Single equations (assignments): 83
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 2
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 2 {1,1}
 * Without analytic Jacobian: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001388/1.337, allocations: 369.1 kB / 1.04 GB, free: 6.844 MB / 0.4948 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005625/1.338, allocations: 114.2 kB / 1.04 GB, free: 6.734 MB / 0.4948 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009796/1.348, allocations: 1.429 MB / 1.041 GB, free: 5.68 MB / 0.4948 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.905e-05/1.348, allocations: 3.984 kB / 1.041 GB, free: 5.676 MB / 0.4948 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.843e-06/1.348, allocations: 0 / 1.041 GB, free: 5.676 MB / 0.4948 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001364/1.349, allocations: 304.4 kB / 1.042 GB, free: 5.418 MB / 0.4948 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003666/1.35, allocations: 21.23 kB / 1.042 GB, free: 5.406 MB / 0.4948 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003592/1.353, allocations: 155.7 kB / 1.042 GB, free: 5.266 MB / 0.4948 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002984/1.354, allocations: 43.89 kB / 1.042 GB, free: 5.227 MB / 0.4948 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01127/1.365, allocations: 1.469 MB / 1.043 GB, free: 4.066 MB / 0.4948 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01442/1.379, allocations: 2.232 MB / 1.046 GB, free: 2.352 MB / 0.4948 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.004288/1.384, allocations: 0.6012 MB / 1.046 GB, free: 1.91 MB / 0.4948 GB
Notification: Performance of sorting global known variables: time 0.001736/1.385, allocations: 0.5566 MB / 1.047 GB, free: 1.484 MB / 0.4948 GB
Notification: Performance of Backend: time 1.142e-06/1.386, allocations: 1.969 kB / 1.047 GB, free: 1.484 MB / 0.4948 GB
Notification: Performance of simCode: created initialization part: time 0.01529/1.401, allocations: 2.044 MB / 1.049 GB, free: 15.83 MB / 0.5105 GB
Notification: Performance of SimCode: time 0.009996/1.411, allocations: 2.63 MB / 1.051 GB, free: 13.32 MB / 0.5105 GB
Notification: Performance of Templates: time 0.1006/1.512, allocations: 26.85 MB / 1.077 GB, free: 2.734 MB / 0.5261 GB