Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.Movers.Validation.FlowControlled_m_flow.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.Movers.Validation.FlowControlled_m_flow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.Movers.Validation.FlowControlled_m_flow")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002523/0.002528, allocations: 356.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.527/1.527, allocations: 165.8 MB / 183.1 MB, free: 5.473 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.726/3.726, allocations: 410.8 MB / 0.6001 GB, free: 15.15 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.001705/0.001704, allocations: 190.1 kB / 0.6668 GB, free: 6.906 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0007078/0.0007081, allocations: 12 kB / 0.7429 GB, free: 8.949 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4333/0.4341, allocations: 67.44 MB / 0.8087 GB, free: 28.77 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Movers.Validation.FlowControlled_m_flow): time 0.8574/1.292, allocations: 287.3 MB / 1.089 GB, free: 9.445 MB / 0.5417 GB
Notification: Performance of NFInst.instExpressions: time 0.04856/1.34, allocations: 22.35 MB / 1.111 GB, free: 3.051 MB / 0.5573 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003607/1.344, allocations: 71.5 kB / 1.111 GB, free: 2.98 MB / 0.5573 GB
Notification: Performance of NFTyping.typeComponents: time 0.005714/1.35, allocations: 1.142 MB / 1.112 GB, free: 1.832 MB / 0.5573 GB
Notification: Performance of NFTyping.typeBindings: time 0.01653/1.366, allocations: 4.055 MB / 1.116 GB, free: 13.76 MB / 0.573 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01413/1.38, allocations: 3.255 MB / 1.119 GB, free: 10.54 MB / 0.573 GB
Notification: Performance of NFFlatten.flatten: time 0.013/1.393, allocations: 6.762 MB / 1.126 GB, free: 3.758 MB / 0.573 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004119/1.397, allocations: 1.571 MB / 1.128 GB, free: 2.164 MB / 0.573 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.008038/1.406, allocations: 2.431 MB / 1.13 GB, free: 15.72 MB / 0.5886 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004475/1.41, allocations: 1.689 MB / 1.132 GB, free: 14.03 MB / 0.5886 GB
Notification: Performance of NFPackage.collectConstants: time 0.0007765/1.411, allocations: 92 kB / 1.132 GB, free: 13.94 MB / 0.5886 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006872/1.418, allocations: 1.734 MB / 1.133 GB, free: 12.2 MB / 0.5886 GB
Notification: Performance of NFScalarize.scalarize: time 0.001847/1.42, allocations: 0.7475 MB / 1.134 GB, free: 11.45 MB / 0.5886 GB
Notification: Performance of NFVerifyModel.verify: time 0.001349/1.421, allocations: 0.5082 MB / 1.135 GB, free: 10.93 MB / 0.5886 GB
Notification: Performance of NFConvertDAE.convert: time 0.006904/1.428, allocations: 3.145 MB / 1.138 GB, free: 7.781 MB / 0.5886 GB
Notification: Performance of FrontEnd - DAE generated: time 9.167e-06/1.428, allocations: 12 kB / 1.138 GB, free: 7.77 MB / 0.5886 GB
Notification: Performance of FrontEnd: time 2.936e-06/1.428, allocations: 0 / 1.138 GB, free: 7.77 MB / 0.5886 GB
Notification: Performance of Transformations before backend: time 0.000109/1.428, allocations: 0 / 1.138 GB, free: 7.77 MB / 0.5886 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 341
 * Number of variables: 341
Notification: Performance of Generate backend data structure: time 0.007816/1.436, allocations: 2.508 MB / 1.14 GB, free: 5.199 MB / 0.5886 GB
Notification: Performance of prepare preOptimizeDAE: time 5.389e-05/1.436, allocations: 8.031 kB / 1.14 GB, free: 5.191 MB / 0.5886 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009367/1.446, allocations: 0.8789 MB / 1.141 GB, free: 4.309 MB / 0.5886 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005769/1.451, allocations: 1.734 MB / 1.143 GB, free: 2.5 MB / 0.5886 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000784/1.452, allocations: 182.6 kB / 1.143 GB, free: 2.32 MB / 0.5886 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005201/1.453, allocations: 148 kB / 1.143 GB, free: 2.176 MB / 0.5886 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006792/1.459, allocations: 1.706 MB / 1.145 GB, free: 424 kB / 0.5886 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.022e-05/1.46, allocations: 11.89 kB / 1.145 GB, free: 412 kB / 0.5886 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002598/1.46, allocations: 56 kB / 1.145 GB, free: 356 kB / 0.5886 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.485e-05/1.46, allocations: 46.61 kB / 1.145 GB, free: 308 kB / 0.5886 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.007259/1.467, allocations: 1.494 MB / 1.146 GB, free: 14.8 MB / 0.6042 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.01948/1.487, allocations: 7.844 MB / 1.154 GB, free: 6.359 MB / 0.6042 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004628/1.491, allocations: 1.156 MB / 1.155 GB, free: 5.16 MB / 0.6042 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002601/1.494, allocations: 0.5569 MB / 1.156 GB, free: 4.598 MB / 0.6042 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002929/1.494, allocations: 109.7 kB / 1.156 GB, free: 4.473 MB / 0.6042 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.639e-05/1.495, allocations: 47.16 kB / 1.156 GB, free: 4.418 MB / 0.6042 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001541/1.496, allocations: 203 kB / 1.156 GB, free: 4.219 MB / 0.6042 GB
Notification: Performance of pre-optimization done (n=77): time 4.188e-06/1.496, allocations: 0 / 1.156 GB, free: 4.219 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=77): time 0.007927/1.504, allocations: 1.542 MB / 1.157 GB, free: 2.668 MB / 0.6042 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.229e-05/1.504, allocations: 64.05 kB / 1.157 GB, free: 2.594 MB / 0.6042 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003983/1.508, allocations: 1.796 MB / 1.159 GB, free: 0.7617 MB / 0.6042 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002721/1.508, allocations: 41.7 kB / 1.159 GB, free: 0.7148 MB / 0.6042 GB
Notification: Performance of collectInitialEqns (initialization): time 0.3641/1.873, allocations: 0.9737 MB / 1.16 GB, free: 256.6 MB / 0.6042 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004771/1.873, allocations: 271.1 kB / 1.16 GB, free: 256.5 MB / 0.6042 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001031/1.873, allocations: 23 kB / 1.161 GB, free: 256.5 MB / 0.6042 GB
Notification: Performance of setup shared object (initialization): time 0.0001137/1.873, allocations: 347.9 kB / 1.161 GB, free: 256.2 MB / 0.6042 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00238/1.876, allocations: 477.8 kB / 1.161 GB, free: 256.2 MB / 0.6042 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002576/1.878, allocations: 0.8974 MB / 1.162 GB, free: 255.8 MB / 0.6042 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002506/1.881, allocations: 0.5646 MB / 1.163 GB, free: 255.7 MB / 0.6042 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.496e-05/1.881, allocations: 2.75 kB / 1.163 GB, free: 255.7 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=127) (initialization): time 0.006323/1.887, allocations: 1.489 MB / 1.164 GB, free: 255.2 MB / 0.6042 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000266/1.888, allocations: 384 kB / 1.165 GB, free: 254.8 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.497e-05/1.888, allocations: 9.547 kB / 1.165 GB, free: 254.8 MB / 0.6042 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.613e-05/1.888, allocations: 18.53 kB / 1.165 GB, free: 254.8 MB / 0.6042 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00289/1.891, allocations: 305.7 kB / 1.165 GB, free: 254.7 MB / 0.6042 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0004228/1.891, allocations: 0.6107 MB / 1.165 GB, free: 254.1 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003241/1.894, allocations: 181.4 kB / 1.166 GB, free: 254.1 MB / 0.6042 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002188/1.894, allocations: 55.41 kB / 1.166 GB, free: 254.1 MB / 0.6042 GB
Notification: Performance of prepare postOptimizeDAE: time 1.333e-05/1.895, allocations: 3.688 kB / 1.166 GB, free: 254.1 MB / 0.6042 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.004009/1.899, allocations: 1.177 MB / 1.167 GB, free: 253.7 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.086e-05/1.899, allocations: 8.969 kB / 1.167 GB, free: 253.7 MB / 0.6042 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.956e-05/1.899, allocations: 22.84 kB / 1.167 GB, free: 253.7 MB / 0.6042 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002015/1.901, allocations: 263.2 kB / 1.167 GB, free: 253.7 MB / 0.6042 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.162e-05/1.901, allocations: 20.78 kB / 1.167 GB, free: 253.7 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002773/1.903, allocations: 155.4 kB / 1.167 GB, free: 253.6 MB / 0.6042 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001573/1.904, allocations: 52.34 kB / 1.167 GB, free: 253.6 MB / 0.6042 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * 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 initialization (125):
 * Single equations (assignments): 122
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 1
 * 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: 1 {1}
 * Without analytic Jacobian: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001391/1.905, allocations: 0.5791 MB / 1.168 GB, free: 253.2 MB / 0.6042 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004765/1.906, allocations: 192.7 kB / 1.168 GB, free: 253.1 MB / 0.6042 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005393/1.911, allocations: 1.201 MB / 1.169 GB, free: 252.7 MB / 0.6042 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004698/1.916, allocations: 1.032 MB / 1.17 GB, free: 252.3 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.757e-06/1.916, allocations: 0 / 1.17 GB, free: 252.3 MB / 0.6042 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001067/1.917, allocations: 360.8 kB / 1.171 GB, free: 252 MB / 0.6042 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003483/1.917, allocations: 17.25 kB / 1.171 GB, free: 252 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002398/1.92, allocations: 152.6 kB / 1.171 GB, free: 252 MB / 0.6042 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001405/1.92, allocations: 50.33 kB / 1.171 GB, free: 252 MB / 0.6042 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005778/1.926, allocations: 1.227 MB / 1.172 GB, free: 251.6 MB / 0.6042 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.006756/1.932, allocations: 1.923 MB / 1.174 GB, free: 250.8 MB / 0.6042 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001955/1.934, allocations: 497 kB / 1.174 GB, free: 250.6 MB / 0.6042 GB
Notification: Performance of sorting global known variables: time 0.002361/1.937, allocations: 1.218 MB / 1.176 GB, free: 250.1 MB / 0.6042 GB
Notification: Performance of Backend: time 3.9e-07/1.937, allocations: 0 / 1.176 GB, free: 250.1 MB / 0.6042 GB
Notification: Performance of simCode: created initialization part: time 0.008201/1.945, allocations: 1.981 MB / 1.177 GB, free: 249.6 MB / 0.6042 GB
Notification: Performance of SimCode: time 0.01319/1.958, allocations: 5.319 MB / 1.183 GB, free: 245.4 MB / 0.6042 GB
Notification: Performance of Templates: time 0.1015/2.06, allocations: 44.95 MB / 1.227 GB, free: 200.8 MB / 0.6042 GB