Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.Storage.Validation.HeatExchangerDynamics.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.Storage.Validation.HeatExchangerDynamics,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.Storage.Validation.HeatExchangerDynamics")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.003922/0.003922, allocations: 361 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.531/1.531, allocations: 165.8 MB / 183.1 MB, free: 5.434 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.828/3.828, allocations: 410.8 MB / 0.6001 GB, free: 15.06 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.001689/0.001689, allocations: 200.6 kB / 0.6668 GB, free: 6.895 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004716/0.0004719, allocations: 15.98 kB / 0.7429 GB, free: 8.934 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4088/0.4093, allocations: 67.44 MB / 0.8087 GB, free: 28.77 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Storage.Validation.HeatExchangerDynamics): time 0.1532/0.5626, allocations: 103.3 MB / 0.9096 GB, free: 5.215 MB / 458.7 MB
Notification: Performance of NFInst.instExpressions: time 0.5221/1.085, allocations: 29.58 MB / 0.9385 GB, free: 8.367 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00604/1.091, allocations: 129.9 kB / 0.9386 GB, free: 8.367 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.007097/1.098, allocations: 1.486 MB / 0.9401 GB, free: 8.363 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01808/1.116, allocations: 4.409 MB / 0.9444 GB, free: 8.309 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.01411/1.13, allocations: 3.305 MB / 0.9476 GB, free: 8.227 MB / 474.7 MB
Notification: Performance of NFFlatten.flatten: time 0.06905/1.199, allocations: 50.51 MB / 0.9969 GB, free: 11.41 MB / 490.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.03714/1.237, allocations: 15.69 MB / 1.012 GB, free: 14.43 MB / 0.4948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0302/1.267, allocations: 11.13 MB / 1.023 GB, free: 5.102 MB / 0.4948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.03056/1.297, allocations: 11.75 MB / 1.035 GB, free: 11.6 MB / 0.5105 GB
Notification: Performance of NFPackage.collectConstants: time 0.006109/1.304, allocations: 0.7617 MB / 1.035 GB, free: 10.84 MB / 0.5105 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01656/1.32, allocations: 2.154 MB / 1.037 GB, free: 9.023 MB / 0.5105 GB
Notification: Performance of NFScalarize.scalarize: time 0.01021/1.33, allocations: 3.331 MB / 1.041 GB, free: 6.73 MB / 0.5105 GB
Notification: Performance of NFVerifyModel.verify: time 0.01088/1.341, allocations: 3.705 MB / 1.044 GB, free: 3.285 MB / 0.5105 GB
Notification: Performance of NFConvertDAE.convert: time 0.04891/1.39, allocations: 27.55 MB / 1.071 GB, free: 8.34 MB / 0.5417 GB
Notification: Performance of FrontEnd - DAE generated: time 8.726e-06/1.39, allocations: 0 / 1.071 GB, free: 8.34 MB / 0.5417 GB
Notification: Performance of FrontEnd: time 2.885e-06/1.39, allocations: 0 / 1.071 GB, free: 8.34 MB / 0.5417 GB
Notification: Performance of Transformations before backend: time 0.001059/1.391, allocations: 0 / 1.071 GB, free: 8.34 MB / 0.5417 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 3345
 * Number of variables: 3345
Notification: Performance of Generate backend data structure: time 0.06365/1.455, allocations: 16 MB / 1.087 GB, free: 8.371 MB / 0.5573 GB
Notification: Performance of prepare preOptimizeDAE: time 6.541e-05/1.455, allocations: 12.02 kB / 1.087 GB, free: 8.359 MB / 0.5573 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01298/1.468, allocations: 1.957 MB / 1.089 GB, free: 6.383 MB / 0.5573 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.04806/1.516, allocations: 15.51 MB / 1.104 GB, free: 7.133 MB / 0.573 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001494/1.518, allocations: 1.004 MB / 1.105 GB, free: 6.133 MB / 0.573 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.005815/1.524, allocations: 1.335 MB / 1.106 GB, free: 4.801 MB / 0.573 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06697/1.591, allocations: 20.16 MB / 1.126 GB, free: 104 kB / 0.5886 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000658/1.591, allocations: 47.55 kB / 1.126 GB, free: 56 kB / 0.5886 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.004569/1.596, allocations: 0.5494 MB / 1.126 GB, free: 15.52 MB / 0.6042 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005671/1.597, allocations: 383.8 kB / 1.127 GB, free: 15.15 MB / 0.6042 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05994/1.657, allocations: 18.08 MB / 1.144 GB, free: 13.05 MB / 0.6198 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.9605/2.617, allocations: 86.41 MB / 1.229 GB, free: 173.1 MB / 0.6199 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02862/2.646, allocations: 12.93 MB / 1.241 GB, free: 162.6 MB / 0.6199 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01699/2.663, allocations: 7.427 MB / 1.249 GB, free: 155.2 MB / 0.6199 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 evalFunc (simulation): time 0.07959/2.742, allocations: 26.86 MB / 1.275 GB, free: 127.6 MB / 0.6199 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01651/2.759, allocations: 6.041 MB / 1.281 GB, free: 121.5 MB / 0.6199 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.009047/2.768, allocations: 0.8057 MB / 1.282 GB, free: 120.8 MB / 0.6199 GB
Notification: Performance of pre-optimization done (n=772): time 2.594e-05/2.768, allocations: 0 / 1.282 GB, free: 120.8 MB / 0.6199 GB
Notification: Performance of matching and sorting (n=772): time 0.2034/2.972, allocations: 32.25 MB / 1.313 GB, free: 88.43 MB / 0.6199 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001006/2.972, allocations: 159.1 kB / 1.313 GB, free: 88.25 MB / 0.6199 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02815/3, allocations: 12.09 MB / 1.325 GB, free: 76.19 MB / 0.6199 GB
Notification: Performance of collectPreVariables (initialization): time 0.00231/3.002, allocations: 197.7 kB / 1.325 GB, free: 75.99 MB / 0.6199 GB
Notification: Performance of collectInitialEqns (initialization): time 0.009256/3.012, allocations: 6.963 MB / 1.332 GB, free: 69.02 MB / 0.6199 GB
Notification: Performance of collectInitialBindings (initialization): time 0.004734/3.016, allocations: 2.696 MB / 1.335 GB, free: 66.35 MB / 0.6199 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002026/3.018, allocations: 232 kB / 1.335 GB, free: 66.12 MB / 0.6199 GB
Notification: Performance of setup shared object (initialization): time 0.0009881/3.019, allocations: 0.7737 MB / 1.336 GB, free: 65.34 MB / 0.6199 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.01636/3.036, allocations: 6.237 MB / 1.342 GB, free: 59.1 MB / 0.6199 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01916/3.055, allocations: 9.547 MB / 1.351 GB, free: 48.16 MB / 0.6199 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02034/3.075, allocations: 8.117 MB / 1.359 GB, free: 39.99 MB / 0.6199 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.159e-05/3.076, allocations: 28 kB / 1.359 GB, free: 39.96 MB / 0.6199 GB
Notification: Performance of matching and sorting (n=1474) (initialization): time 0.05407/3.13, allocations: 18.8 MB / 1.377 GB, free: 21.04 MB / 0.6199 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001925/3.132, allocations: 3.093 MB / 1.38 GB, free: 16.54 MB / 0.6199 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002257/3.132, allocations: 104 kB / 1.381 GB, free: 16.44 MB / 0.6199 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1272/3.259, allocations: 9.059 MB / 1.389 GB, free: 7.367 MB / 0.6199 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01866/3.278, allocations: 2.453 MB / 1.392 GB, free: 4.91 MB / 0.6199 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.8746/4.152, allocations: 10.37 MB / 1.402 GB, free: 213.2 MB / 0.62 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01976/4.172, allocations: 1.096 MB / 1.403 GB, free: 213 MB / 0.62 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002903/4.175, allocations: 0.5221 MB / 1.403 GB, free: 212.9 MB / 0.62 GB
Notification: Performance of prepare postOptimizeDAE: time 1.355e-05/4.175, allocations: 2.438 kB / 1.403 GB, free: 212.9 MB / 0.62 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.05104/4.226, allocations: 17.83 MB / 1.421 GB, free: 206.2 MB / 0.62 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002807/4.227, allocations: 104.3 kB / 1.421 GB, free: 206.2 MB / 0.62 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1267/4.353, allocations: 9.015 MB / 1.43 GB, free: 205.3 MB / 0.62 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01862/4.372, allocations: 2.448 MB / 1.432 GB, free: 204.7 MB / 0.62 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0184/4.39, allocations: 10.37 MB / 1.442 GB, free: 194.4 MB / 0.62 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01881/4.409, allocations: 1.071 MB / 1.443 GB, free: 193.4 MB / 0.62 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003004/4.412, allocations: 0.5155 MB / 1.444 GB, free: 192.9 MB / 0.62 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 448
 * Number of states: 0 ()
 * Number of discrete variables: 5 ($whenCondition1,$PRE.mHex_flow_in.T_start,mHex_flow_in.T_start,$PRE.mHex_flow_in.count,mHex_flow_in.count)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1286):
 * Single equations (assignments): 1282
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 14,(1,100.0%) 14}
 * Non-linear torn systems: 1 {16 144}
Notification: Performance of prepare postOptimizeDAE: time 0.01561/4.428, allocations: 5.953 MB / 1.45 GB, free: 187 MB / 0.62 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.005179/4.433, allocations: 1.157 MB / 1.451 GB, free: 186.2 MB / 0.62 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0407/4.474, allocations: 13.34 MB / 1.464 GB, free: 172.9 MB / 0.62 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.02964/4.504, allocations: 11.01 MB / 1.475 GB, free: 161.8 MB / 0.62 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.629e-05/4.504, allocations: 0 / 1.475 GB, free: 161.8 MB / 0.62 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01029/4.514, allocations: 2.917 MB / 1.477 GB, free: 158.9 MB / 0.62 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004621/4.519, allocations: 156 kB / 1.478 GB, free: 158.7 MB / 0.62 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01663/4.535, allocations: 0.7984 MB / 1.478 GB, free: 157.9 MB / 0.62 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.002145/4.538, allocations: 465.5 kB / 1.479 GB, free: 157.5 MB / 0.62 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.04895/4.587, allocations: 14.47 MB / 1.493 GB, free: 143.1 MB / 0.62 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.04572/4.632, allocations: 18.86 MB / 1.511 GB, free: 124.2 MB / 0.62 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01177/4.644, allocations: 5.489 MB / 1.517 GB, free: 118.7 MB / 0.62 GB
Notification: Performance of sorting global known variables: time 0.01974/4.664, allocations: 8.328 MB / 1.525 GB, free: 110.4 MB / 0.62 GB
Notification: Performance of Backend: time 1.442e-06/4.664, allocations: 0 / 1.525 GB, free: 110.4 MB / 0.62 GB
Notification: Performance of simCode: created initialization part: time 0.06037/4.724, allocations: 19.59 MB / 1.544 GB, free: 90.89 MB / 0.62 GB
Notification: Performance of SimCode: time 0.1055/4.83, allocations: 40.82 MB / 1.584 GB, free: 50.15 MB / 0.62 GB
Notification: Performance of Templates: time 1.521/6.351, allocations: 304.6 MB / 1.881 GB, free: 206.8 MB / 0.6356 GB