Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.Storage.Examples.Stratified.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.Examples.Stratified,tolerance=1e-08,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.Storage.Examples.Stratified")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002548/0.002548, 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.537/1.537, 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.96/3.96, 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.001766/0.001766, allocations: 190.8 kB / 0.6668 GB, free: 6.934 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004597/0.0004598, allocations: 19.94 kB / 0.7429 GB, free: 8.969 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3933/0.3938, allocations: 67.44 MB / 0.8087 GB, free: 28.79 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Storage.Examples.Stratified): time 0.06851/0.4623, allocations: 51.7 MB / 0.8592 GB, free: 8.941 MB / 410.7 MB
Notification: Performance of NFInst.instExpressions: time 0.03211/0.4945, allocations: 16.13 MB / 0.875 GB, free: 8.777 MB / 426.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00267/0.4972, allocations: 63.62 kB / 0.875 GB, free: 8.715 MB / 426.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.00352/0.5008, allocations: 0.7803 MB / 0.8758 GB, free: 7.93 MB / 426.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.009736/0.5105, allocations: 2.383 MB / 0.8781 GB, free: 5.535 MB / 426.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.007603/0.5182, allocations: 1.709 MB / 0.8798 GB, free: 3.848 MB / 426.7 MB
Notification: Performance of NFFlatten.flatten: time 0.04627/0.5644, allocations: 32.24 MB / 0.9113 GB, free: 3.508 MB / 458.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.03285/0.5973, allocations: 10.02 MB / 0.9211 GB, free: 9.41 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.02568/0.6231, allocations: 7.004 MB / 0.9279 GB, free: 2.379 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.4233/1.046, allocations: 7.545 MB / 0.9353 GB, free: 16.89 MB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.004136/1.051, allocations: 473.6 kB / 0.9357 GB, free: 16.89 MB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01001/1.061, allocations: 1.174 MB / 0.9369 GB, free: 16.89 MB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.005938/1.067, allocations: 2.102 MB / 0.9389 GB, free: 16.89 MB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.006382/1.073, allocations: 2.208 MB / 0.9411 GB, free: 16.86 MB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.02225/1.095, allocations: 14.36 MB / 0.9551 GB, free: 16.04 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 7.564e-06/1.095, allocations: 0 / 0.9551 GB, free: 16.04 MB / 474.7 MB
Notification: Performance of FrontEnd: time 2.866e-06/1.095, allocations: 0 / 0.9551 GB, free: 16.04 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 0.0006819/1.096, allocations: 5.5 kB / 0.9551 GB, free: 16.04 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2145
 * Number of variables: 2145
Notification: Performance of Generate backend data structure: time 0.03432/1.131, allocations: 9.487 MB / 0.9644 GB, free: 12.28 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.259e-05/1.131, allocations: 10.88 kB / 0.9644 GB, free: 12.28 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007433/1.138, allocations: 1.171 MB / 0.9655 GB, free: 11.73 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.02676/1.165, allocations: 8.815 MB / 0.9741 GB, free: 9.242 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008048/1.166, allocations: 0.6124 MB / 0.9747 GB, free: 9.223 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003719/1.169, allocations: 0.8155 MB / 0.9755 GB, free: 9.215 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03968/1.209, allocations: 12.46 MB / 0.9877 GB, free: 5.461 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004484/1.21, allocations: 24.97 kB / 0.9877 GB, free: 5.457 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003064/1.213, allocations: 364.5 kB / 0.9881 GB, free: 5.453 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003222/1.213, allocations: 251 kB / 0.9883 GB, free: 5.441 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03198/1.245, allocations: 10.36 MB / 0.9984 GB, free: 3.543 MB / 474.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.105/1.35, allocations: 48.84 MB / 1.046 GB, free: 9.211 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0157/1.366, allocations: 6.236 MB / 1.052 GB, free: 3.035 MB / 0.5105 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01274/1.379, allocations: 5.058 MB / 1.057 GB, free: 13.99 MB / 0.5261 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.002157/1.381, allocations: 0.8509 MB / 1.058 GB, free: 13.01 MB / 0.5261 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0102/1.391, allocations: 3.41 MB / 1.061 GB, free: 9.566 MB / 0.5261 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.005445/1.397, allocations: 0.5022 MB / 1.062 GB, free: 9.062 MB / 0.5261 GB
Notification: Performance of pre-optimization done (n=449): time 1.622e-05/1.397, allocations: 4 kB / 1.062 GB, free: 9.059 MB / 0.5261 GB
Notification: Performance of matching and sorting (n=449): time 0.02845/1.425, allocations: 8.843 MB / 1.07 GB, free: 192 kB / 0.5261 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001419/1.425, allocations: 154.3 kB / 1.071 GB, free: 12 kB / 0.5261 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01867/1.444, allocations: 7.247 MB / 1.078 GB, free: 8.785 MB / 0.5417 GB
Notification: Performance of collectPreVariables (initialization): time 0.001363/1.445, allocations: 109.7 kB / 1.078 GB, free: 8.672 MB / 0.5417 GB
Notification: Performance of collectInitialEqns (initialization): time 0.006827/1.452, allocations: 4.292 MB / 1.082 GB, free: 4.363 MB / 0.5417 GB
Notification: Performance of collectInitialBindings (initialization): time 0.003409/1.456, allocations: 1.555 MB / 1.083 GB, free: 2.82 MB / 0.5417 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0006446/1.456, allocations: 120 kB / 1.084 GB, free: 2.703 MB / 0.5417 GB
Notification: Performance of setup shared object (initialization): time 0.000499/1.457, allocations: 0.5523 MB / 1.084 GB, free: 2.145 MB / 0.5417 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008429/1.465, allocations: 2.987 MB / 1.087 GB, free: 15.15 MB / 0.5573 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01073/1.476, allocations: 4.876 MB / 1.092 GB, free: 9.465 MB / 0.5573 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01105/1.487, allocations: 4.025 MB / 1.096 GB, free: 5.48 MB / 0.5573 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.517e-05/1.487, allocations: 24 kB / 1.096 GB, free: 5.457 MB / 0.5573 GB
Notification: Performance of matching and sorting (n=754) (initialization): time 0.02646/1.514, allocations: 8.826 MB / 1.104 GB, free: 12.95 MB / 0.573 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001715/1.516, allocations: 1.895 MB / 1.106 GB, free: 10.26 MB / 0.573 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001023/1.516, allocations: 60 kB / 1.106 GB, free: 10.2 MB / 0.573 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001551/1.516, allocations: 96 kB / 1.106 GB, free: 10.11 MB / 0.573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01104/1.527, allocations: 1.366 MB / 1.108 GB, free: 8.832 MB / 0.573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0002082/1.527, allocations: 123.2 kB / 1.108 GB, free: 8.734 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01215/1.539, allocations: 0.6194 MB / 1.108 GB, free: 8.113 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001627/1.541, allocations: 279.9 kB / 1.109 GB, free: 7.84 MB / 0.573 GB
Notification: Performance of prepare postOptimizeDAE: time 1.504e-05/1.541, allocations: 4 kB / 1.109 GB, free: 7.836 MB / 0.573 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.02334/1.564, allocations: 8.252 MB / 1.117 GB, free: 15.94 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001637/1.565, allocations: 60 kB / 1.117 GB, free: 15.88 MB / 0.5886 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000156/1.565, allocations: 96 kB / 1.117 GB, free: 15.79 MB / 0.5886 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01057/1.575, allocations: 1.368 MB / 1.118 GB, free: 14.51 MB / 0.5886 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.000169/1.575, allocations: 126.3 kB / 1.118 GB, free: 14.41 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01108/1.587, allocations: 0.5962 MB / 1.119 GB, free: 13.81 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001415/1.588, allocations: 276 kB / 1.119 GB, free: 13.54 MB / 0.5886 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 264
 * Number of states: 0 ()
 * Number of discrete variables: 15 ($whenCondition1,$whenCondition2,TWat.a,TWat.b,TWat.last,$PRE.TWat.nextEvent,TWat.nextEvent,TWat.nextEventScaled,P.a,P.b,P.last,$PRE.P.nextEvent,P.nextEvent,P.nextEventScaled,dH.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (744):
 * Single equations (assignments): 742
 * Array equations: 0
 * Algorithm blocks: 2
 * 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.0104/1.598, allocations: 3.561 MB / 1.123 GB, free: 10.05 MB / 0.5886 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002696/1.601, allocations: 0.7102 MB / 1.123 GB, free: 9.336 MB / 0.5886 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02142/1.623, allocations: 7.1 MB / 1.13 GB, free: 2.211 MB / 0.5886 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.01757/1.64, allocations: 5.812 MB / 1.136 GB, free: 12.37 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.151e-05/1.64, allocations: 0 / 1.136 GB, free: 12.37 MB / 0.6042 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00764/1.648, allocations: 1.706 MB / 1.138 GB, free: 10.64 MB / 0.6042 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003145/1.651, allocations: 95.98 kB / 1.138 GB, free: 10.54 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.01113/1.662, allocations: 0.5139 MB / 1.138 GB, free: 10.03 MB / 0.6042 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001376/1.664, allocations: 247.1 kB / 1.138 GB, free: 9.789 MB / 0.6042 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02455/1.688, allocations: 7.086 MB / 1.145 GB, free: 2.723 MB / 0.6042 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.03225/1.721, allocations: 11.31 MB / 1.156 GB, free: 7.355 MB / 0.6198 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.008355/1.729, allocations: 3.185 MB / 1.16 GB, free: 4.164 MB / 0.6198 GB
Notification: Performance of sorting global known variables: time 0.8412/2.57, allocations: 5.047 MB / 1.165 GB, free: 233.7 MB / 0.6199 GB
Notification: Performance of Backend: time 8.71e-07/2.57, allocations: 0 / 1.165 GB, free: 233.7 MB / 0.6199 GB
Notification: Performance of simCode: created initialization part: time 0.03304/2.603, allocations: 9.47 MB / 1.174 GB, free: 231.6 MB / 0.6199 GB
Notification: Performance of SimCode: time 0.06887/2.672, allocations: 24.08 MB / 1.197 GB, free: 220.3 MB / 0.6199 GB
Notification: Performance of Templates: time 0.3799/3.052, allocations: 174.7 MB / 1.368 GB, free: 54.45 MB / 0.6199 GB