Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_master_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness.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 Complex with version 3.2.3 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Using package Buildings with version 9.0.0 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo)
Running command: translateModel(Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Buildings_master_Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 9.0.0-master/package.mo): time 4.692/4.692, allocations: 0.5623 GB / 0.5776 GB, free: 66.91 MB / 330.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002694/0.002694, allocations: 357.5 kB / 0.6446 GB, free: 21.47 MB / 330.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 4.202/4.202, allocations: 410.7 MB / 1.113 GB, free: 15.75 MB / 0.6198 GB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001874/0.001874, allocations: 184.5 kB / 1.226 GB, free: 11.68 MB / 0.7292 GB
Notification: Performance of FrontEnd - loaded program: time 0.0006479/0.0006482, allocations: 12.22 kB / 1.349 GB, free: 320.4 MB / 0.8073 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2043/0.205, allocations: 115.3 MB / 1.461 GB, free: 220.7 MB / 0.8073 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.MassExchangers.Examples.ConstantEffectiveness): time 0.251/0.4561, allocations: 191.8 MB / 1.649 GB, free: 28.59 MB / 0.8073 GB
Notification: Performance of NFInst.instExpressions: time 0.01286/0.469, allocations: 7.237 MB / 1.656 GB, free: 21.34 MB / 0.8073 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001366/0.4705, allocations: 35.75 kB / 1.656 GB, free: 21.31 MB / 0.8073 GB
Notification: Performance of NFTyping.typeComponents: time 0.002404/0.4729, allocations: 0.6406 MB / 1.656 GB, free: 20.66 MB / 0.8073 GB
Notification: Performance of NFTyping.typeBindings: time 0.007644/0.4805, allocations: 2.269 MB / 1.659 GB, free: 18.39 MB / 0.8073 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00439/0.485, allocations: 1.126 MB / 1.66 GB, free: 17.28 MB / 0.8073 GB
Notification: Performance of NFFlatten.flatten: time 0.004362/0.4893, allocations: 3.182 MB / 1.663 GB, free: 14.09 MB / 0.8073 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001526/0.4909, allocations: 0.7842 MB / 1.664 GB, free: 13.29 MB / 0.8073 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003393/0.4943, allocations: 1.318 MB / 1.665 GB, free: 11.96 MB / 0.8073 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001582/0.4959, allocations: 0.856 MB / 1.666 GB, free: 11.11 MB / 0.8073 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001667/0.4961, allocations: 51.94 kB / 1.666 GB, free: 11.05 MB / 0.8073 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003513/0.4996, allocations: 1.216 MB / 1.667 GB, free: 9.836 MB / 0.8073 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004274/0.5001, allocations: 338.8 kB / 1.667 GB, free: 9.504 MB / 0.8073 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003899/0.5005, allocations: 265.1 kB / 1.667 GB, free: 9.238 MB / 0.8073 GB
Notification: Performance of NFConvertDAE.convert: time 0.002435/0.5029, allocations: 1.613 MB / 1.669 GB, free: 7.621 MB / 0.8073 GB
Notification: Performance of FrontEnd - DAE generated: time 5.791e-06/0.503, allocations: 4 kB / 1.669 GB, free: 7.617 MB / 0.8073 GB
Notification: Performance of FrontEnd: time 1.834e-06/0.503, allocations: 0 / 1.669 GB, free: 7.617 MB / 0.8073 GB
Notification: Performance of Transformations before backend: time 1.463e-05/0.503, allocations: 0 / 1.669 GB, free: 7.617 MB / 0.8073 GB
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.006007/0.509, allocations: 1.583 MB / 1.671 GB, free: 5.977 MB / 0.8073 GB
Notification: Performance of prepare preOptimizeDAE: time 4.459e-05/0.5091, allocations: 8.75 kB / 1.671 GB, free: 5.969 MB / 0.8073 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005117/0.5142, allocations: 0.5915 MB / 1.671 GB, free: 5.375 MB / 0.8073 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002466/0.5167, allocations: 0.922 MB / 1.672 GB, free: 4.41 MB / 0.8073 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001158/0.5179, allocations: 193.9 kB / 1.672 GB, free: 4.219 MB / 0.8073 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002211/0.5181, allocations: 91.94 kB / 1.672 GB, free: 4.129 MB / 0.8073 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004285/0.5224, allocations: 1.264 MB / 1.674 GB, free: 2.859 MB / 0.8073 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.18e-05/0.5225, allocations: 0 / 1.674 GB, free: 2.859 MB / 0.8073 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001552/0.5226, allocations: 35.98 kB / 1.674 GB, free: 2.824 MB / 0.8073 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.151e-05/0.5227, allocations: 49.94 kB / 1.674 GB, free: 2.777 MB / 0.8073 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006218/0.5289, allocations: 1.608 MB / 1.675 GB, free: 1.164 MB / 0.8073 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.6236/1.153, allocations: 4.081 MB / 1.679 GB, free: 217.7 MB / 0.8073 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004816/1.157, allocations: 1.042 MB / 1.68 GB, free: 217.7 MB / 0.8073 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003028/1.161, allocations: 0.6993 MB / 1.681 GB, free: 217.7 MB / 0.8073 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001763/1.162, allocations: 0.6409 MB / 1.682 GB, free: 217.1 MB / 0.8073 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.572e-05/1.162, allocations: 41.45 kB / 1.682 GB, free: 217.1 MB / 0.8073 GB
Notification: Performance of pre-optimization done (n=76): time 4.248e-06/1.162, allocations: 3.906 kB / 1.682 GB, free: 217.1 MB / 0.8073 GB
Notification: Performance of matching and sorting (n=76): time 0.009464/1.172, allocations: 1.907 MB / 1.683 GB, free: 216.7 MB / 0.8073 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.187e-05/1.172, allocations: 62.47 kB / 1.684 GB, free: 216.6 MB / 0.8073 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001641/1.174, allocations: 0.8136 MB / 1.684 GB, free: 216.3 MB / 0.8073 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002385/1.174, allocations: 39.55 kB / 1.684 GB, free: 216.2 MB / 0.8073 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004193/1.174, allocations: 445.4 kB / 1.685 GB, free: 215.9 MB / 0.8073 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002992/1.175, allocations: 255.7 kB / 1.685 GB, free: 215.7 MB / 0.8073 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 9.705e-05/1.175, allocations: 17.59 kB / 1.685 GB, free: 215.7 MB / 0.8073 GB
Notification: Performance of setup shared object (initialization): time 8.539e-05/1.175, allocations: 338.9 kB / 1.685 GB, free: 215.4 MB / 0.8073 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002717/1.178, allocations: 0.5627 MB / 1.686 GB, free: 215.3 MB / 0.8073 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002842/1.18, allocations: 0.6938 MB / 1.687 GB, free: 215 MB / 0.8073 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002748/1.183, allocations: 0.622 MB / 1.687 GB, free: 214.8 MB / 0.8073 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.841e-06/1.183, allocations: 5.094 kB / 1.687 GB, free: 214.8 MB / 0.8073 GB
Notification: Performance of matching and sorting (n=87) (initialization): time 0.006264/1.189, allocations: 1.418 MB / 1.689 GB, free: 214.4 MB / 0.8073 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001087/1.19, allocations: 157 kB / 1.689 GB, free: 214.2 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.232e-05/1.19, allocations: 7.906 kB / 1.689 GB, free: 214.2 MB / 0.8073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.046e-05/1.19, allocations: 3.781 kB / 1.689 GB, free: 214.2 MB / 0.8073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002988/1.193, allocations: 278.5 kB / 1.689 GB, free: 214.2 MB / 0.8073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0006768/1.193, allocations: 1.192 MB / 1.69 GB, free: 213 MB / 0.8073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002298/1.196, allocations: 193.9 kB / 1.69 GB, free: 212.9 MB / 0.8073 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001604/1.196, allocations: 41.89 kB / 1.69 GB, free: 212.9 MB / 0.8073 GB
Notification: Performance of prepare postOptimizeDAE: time 1.29e-05/1.196, allocations: 6.5 kB / 1.69 GB, free: 212.9 MB / 0.8073 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.00597/1.202, allocations: 1.311 MB / 1.692 GB, free: 212.6 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.699e-06/1.202, allocations: 2.375 kB / 1.692 GB, free: 212.6 MB / 0.8073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.259e-05/1.202, allocations: 4.406 kB / 1.692 GB, free: 212.6 MB / 0.8073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00234/1.204, allocations: 255.8 kB / 1.692 GB, free: 212.5 MB / 0.8073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.315e-05/1.204, allocations: 7.312 kB / 1.692 GB, free: 212.5 MB / 0.8073 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002193/1.207, allocations: 196.8 kB / 1.692 GB, free: 212.5 MB / 0.8073 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001442/1.207, allocations: 33.7 kB / 1.692 GB, free: 212.5 MB / 0.8073 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.000802/1.207, allocations: 375.6 kB / 1.693 GB, free: 212.2 MB / 0.8073 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000257/1.208, allocations: 107.7 kB / 1.693 GB, free: 212.1 MB / 0.8073 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006091/1.214, allocations: 1.443 MB / 1.694 GB, free: 211.7 MB / 0.8073 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.75e-05/1.214, allocations: 9.156 kB / 1.694 GB, free: 211.7 MB / 0.8073 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.071e-06/1.214, allocations: 2.047 kB / 1.694 GB, free: 211.7 MB / 0.8073 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007957/1.215, allocations: 300 kB / 1.694 GB, free: 211.5 MB / 0.8073 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002231/1.215, allocations: 11.22 kB / 1.694 GB, free: 211.5 MB / 0.8073 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002226/1.217, allocations: 163.2 kB / 1.694 GB, free: 211.5 MB / 0.8073 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001982/1.217, allocations: 51.7 kB / 1.695 GB, free: 211.4 MB / 0.8073 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00737/1.225, allocations: 1.462 MB / 1.696 GB, free: 211 MB / 0.8073 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.009175/1.234, allocations: 2.227 MB / 1.698 GB, free: 210.1 MB / 0.8073 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002757/1.237, allocations: 0.6034 MB / 1.699 GB, free: 209.9 MB / 0.8073 GB
Notification: Performance of sorting global known variables: time 0.00123/1.238, allocations: 0.5619 MB / 1.699 GB, free: 209.7 MB / 0.8073 GB
Notification: Performance of Backend: time 7.71e-07/1.238, allocations: 0 / 1.699 GB, free: 209.7 MB / 0.8073 GB
Notification: Performance of simCode: created initialization part: time 0.009301/1.247, allocations: 2.032 MB / 1.701 GB, free: 209 MB / 0.8073 GB
Notification: Performance of SimCode: time 0.0061/1.254, allocations: 2.638 MB / 1.704 GB, free: 207.4 MB / 0.8073 GB
Notification: Performance of Templates: time 0.06322/1.317, allocations: 26.81 MB / 1.73 GB, free: 188.1 MB / 0.8073 GB