Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.BaseClasses.Validation.MassFlowRateMultiplier.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.BaseClasses.Validation.MassFlowRateMultiplier,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.BaseClasses.Validation.MassFlowRateMultiplier")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002582/0.002585, allocations: 359.3 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.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.837/3.837, allocations: 410.8 MB / 0.6001 GB, free: 15.1 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.001739/0.001739, allocations: 193 kB / 0.6668 GB, free: 6.922 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004565/0.0004569, allocations: 15.94 kB / 0.7429 GB, free: 8.949 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3905/0.391, allocations: 67.43 MB / 0.8087 GB, free: 28.79 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.BaseClasses.Validation.MassFlowRateMultiplier): time 0.01108/0.4021, allocations: 9.209 MB / 0.8177 GB, free: 19.54 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.003183/0.4054, allocations: 2.013 MB / 0.8197 GB, free: 17.52 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0001996/0.4056, allocations: 11.88 kB / 0.8197 GB, free: 17.51 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0004623/0.4061, allocations: 99.41 kB / 0.8198 GB, free: 17.41 MB / 378.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.0007226/0.4068, allocations: 250.6 kB / 0.82 GB, free: 17.17 MB / 378.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001237/0.4081, allocations: 354 kB / 0.8204 GB, free: 16.83 MB / 378.7 MB
Notification: Performance of NFFlatten.flatten: time 0.0005761/0.4086, allocations: 0.4994 MB / 0.8209 GB, free: 16.33 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002376/0.4089, allocations: 106.4 kB / 0.821 GB, free: 16.23 MB / 378.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0003642/0.4093, allocations: 171.2 kB / 0.8211 GB, free: 16.06 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0001817/0.4095, allocations: 111.5 kB / 0.8212 GB, free: 15.95 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 2.642e-05/0.4095, allocations: 4 kB / 0.8212 GB, free: 15.95 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.000328/0.4098, allocations: 103.7 kB / 0.8213 GB, free: 15.85 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 6.285e-05/0.4099, allocations: 35.83 kB / 0.8214 GB, free: 15.81 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 4.184e-05/0.41, allocations: 57.64 kB / 0.8214 GB, free: 15.75 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0002632/0.4102, allocations: 155.7 kB / 0.8216 GB, free: 15.6 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 4.117e-06/0.4103, allocations: 0 / 0.8216 GB, free: 15.6 MB / 378.7 MB
Notification: Performance of FrontEnd: time 1.743e-06/0.4103, allocations: 0 / 0.8216 GB, free: 15.6 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 4.489e-06/0.4103, allocations: 0 / 0.8216 GB, free: 15.6 MB / 378.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 23
 * Number of variables: 23
Notification: Performance of Generate backend data structure: time 0.0006093/0.4109, allocations: 450.4 kB / 0.822 GB, free: 15.11 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.202e-05/0.411, allocations: 8.031 kB / 0.822 GB, free: 15.1 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 5.274e-05/0.411, allocations: 23.81 kB / 0.822 GB, free: 15.08 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0002758/0.4113, allocations: 242.6 kB / 0.8223 GB, free: 14.8 MB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 1.73e-05/0.4113, allocations: 11.94 kB / 0.8223 GB, free: 14.79 MB / 378.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 3.126e-05/0.4114, allocations: 12 kB / 0.8223 GB, free: 14.78 MB / 378.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0004009/0.4118, allocations: 144.9 kB / 0.8224 GB, free: 14.62 MB / 378.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.04e-05/0.4118, allocations: 0 / 0.8224 GB, free: 14.62 MB / 378.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 1.758e-05/0.4118, allocations: 8 kB / 0.8224 GB, free: 14.62 MB / 378.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.732e-06/0.4119, allocations: 4 kB / 0.8224 GB, free: 14.61 MB / 378.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0003979/0.4123, allocations: 95.92 kB / 0.8225 GB, free: 14.52 MB / 378.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.0008576/0.4131, allocations: 0.5471 MB / 0.8231 GB, free: 13.92 MB / 378.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.000262/0.4134, allocations: 97.11 kB / 0.8232 GB, free: 13.8 MB / 378.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002098/0.4136, allocations: 69.78 kB / 0.8232 GB, free: 13.73 MB / 378.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002605/0.4139, allocations: 332.4 kB / 0.8235 GB, free: 13.33 MB / 378.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.059e-05/0.414, allocations: 34.56 kB / 0.8236 GB, free: 13.29 MB / 378.7 MB
Notification: Performance of pre-optimization done (n=7): time 1.663e-06/0.414, allocations: 0 / 0.8236 GB, free: 13.29 MB / 378.7 MB
Notification: Performance of matching and sorting (n=7): time 0.0006921/0.4147, allocations: 185.4 kB / 0.8238 GB, free: 13.1 MB / 378.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.262e-05/0.4147, allocations: 77.11 kB / 0.8238 GB, free: 13.01 MB / 378.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0001772/0.4149, allocations: 178.8 kB / 0.824 GB, free: 12.82 MB / 378.7 MB
Notification: Performance of collectPreVariables (initialization): time 1.891e-05/0.4149, allocations: 25.7 kB / 0.824 GB, free: 12.79 MB / 378.7 MB
Notification: Performance of collectInitialEqns (initialization): time 6.596e-05/0.415, allocations: 120.1 kB / 0.8241 GB, free: 12.67 MB / 378.7 MB
Notification: Performance of collectInitialBindings (initialization): time 2.763e-05/0.415, allocations: 27.64 kB / 0.8242 GB, free: 12.64 MB / 378.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 6.863e-06/0.4151, allocations: 0 / 0.8242 GB, free: 12.64 MB / 378.7 MB
Notification: Performance of setup shared object (initialization): time 6.319e-05/0.4151, allocations: 330.9 kB / 0.8245 GB, free: 12.31 MB / 378.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001766/0.4153, allocations: 39.91 kB / 0.8245 GB, free: 12.27 MB / 378.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002151/0.4155, allocations: 79.33 kB / 0.8246 GB, free: 12.18 MB / 378.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0001892/0.4157, allocations: 43.97 kB / 0.8246 GB, free: 12.14 MB / 378.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.365e-06/0.4158, allocations: 0 / 0.8246 GB, free: 12.14 MB / 378.7 MB
Notification: Performance of matching and sorting (n=7) (initialization): time 0.0004414/0.4162, allocations: 141.4 kB / 0.8248 GB, free: 12 MB / 378.7 MB
Notification: Performance of prepare postOptimizeDAE: time 1.648e-05/0.4162, allocations: 8 kB / 0.8248 GB, free: 11.99 MB / 378.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.31e-06/0.4163, allocations: 0 / 0.8248 GB, free: 11.99 MB / 378.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 1.354e-05/0.4163, allocations: 0 / 0.8248 GB, free: 11.99 MB / 378.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003526/0.4166, allocations: 52.02 kB / 0.8248 GB, free: 11.94 MB / 378.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 6.432e-06/0.4167, allocations: 0 / 0.8248 GB, free: 11.94 MB / 378.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0002179/0.4169, allocations: 19.89 kB / 0.8248 GB, free: 11.92 MB / 378.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.358e-05/0.4169, allocations: 4 kB / 0.8249 GB, free: 11.91 MB / 378.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 3
 * 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 (7):
 * Single equations (assignments): 7
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0001066/0.417, allocations: 133.8 kB / 0.825 GB, free: 11.77 MB / 378.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 6.018e-05/0.4171, allocations: 15.77 kB / 0.825 GB, free: 11.76 MB / 378.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0002779/0.4174, allocations: 134.5 kB / 0.8251 GB, free: 11.62 MB / 378.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.402e-06/0.4174, allocations: 7.938 kB / 0.8251 GB, free: 11.62 MB / 378.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.156e-06/0.4174, allocations: 0 / 0.8251 GB, free: 11.62 MB / 378.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 6.89e-05/0.4175, allocations: 96.91 kB / 0.8252 GB, free: 11.5 MB / 378.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 3.324e-05/0.4176, allocations: 8 kB / 0.8252 GB, free: 11.5 MB / 378.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002072/0.4178, allocations: 11.98 kB / 0.8252 GB, free: 11.48 MB / 378.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 4.556e-05/0.4178, allocations: 7.922 kB / 0.8252 GB, free: 11.48 MB / 378.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0007364/0.4186, allocations: 356.3 kB / 0.8256 GB, free: 11.12 MB / 378.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.0005099/0.4191, allocations: 413.9 kB / 0.826 GB, free: 10.7 MB / 378.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0002199/0.4193, allocations: 72.14 kB / 0.8261 GB, free: 10.63 MB / 378.7 MB
Notification: Performance of sorting global known variables: time 0.0001183/0.4195, allocations: 144 kB / 0.8262 GB, free: 10.49 MB / 378.7 MB
Notification: Performance of Backend: time 7.01e-07/0.4195, allocations: 0 / 0.8262 GB, free: 10.49 MB / 378.7 MB
Notification: Performance of simCode: created initialization part: time 0.0009939/0.4205, allocations: 208.4 kB / 0.8264 GB, free: 10.29 MB / 378.7 MB
Notification: Performance of SimCode: time 0.0009998/0.4215, allocations: 0.8102 MB / 0.8272 GB, free: 9.43 MB / 378.7 MB
Notification: Performance of Templates: time 0.00815/0.4297, allocations: 2.863 MB / 0.83 GB, free: 6.605 MB / 378.7 MB