Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.HeatExchangers.Examples.AirHeater_T.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.HeatExchangers.Examples.AirHeater_T,tolerance=1e-08,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.HeatExchangers.Examples.AirHeater_T")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.003939/0.003945, allocations: 359.9 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.511/1.511, allocations: 165.8 MB / 183.1 MB, free: 5.438 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.883/3.883, allocations: 410.8 MB / 0.6001 GB, free: 15.14 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.003053/0.003052, allocations: 204.1 kB / 0.6668 GB, free: 6.895 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.00048/0.0004804, allocations: 16 kB / 0.7429 GB, free: 8.945 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4634/0.4639, allocations: 67.44 MB / 0.8087 GB, free: 28.77 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.HeatExchangers.Examples.AirHeater_T): time 0.973/1.437, allocations: 210.8 MB / 1.015 GB, free: 10.59 MB / 490.7 MB
Notification: Performance of NFInst.instExpressions: time 0.04543/1.482, allocations: 18.04 MB / 1.032 GB, free: 3.156 MB / 490.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003795/1.486, allocations: 71.84 kB / 1.032 GB, free: 3.125 MB / 490.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.005554/1.492, allocations: 1.048 MB / 1.033 GB, free: 2.289 MB / 490.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.01495/1.507, allocations: 3.467 MB / 1.037 GB, free: 15.46 MB / 0.4948 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01399/1.521, allocations: 3.109 MB / 1.04 GB, free: 12.38 MB / 0.4948 GB
Notification: Performance of NFFlatten.flatten: time 0.01146/1.532, allocations: 5.617 MB / 1.045 GB, free: 6.746 MB / 0.4948 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003824/1.536, allocations: 1.386 MB / 1.047 GB, free: 5.336 MB / 0.4948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006988/1.543, allocations: 2.022 MB / 1.049 GB, free: 3.305 MB / 0.4948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004111/1.547, allocations: 1.486 MB / 1.05 GB, free: 1.812 MB / 0.4948 GB
Notification: Performance of NFPackage.collectConstants: time 0.0006627/1.548, allocations: 84 kB / 1.05 GB, free: 1.73 MB / 0.4948 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.00714/1.555, allocations: 1.793 MB / 1.052 GB, free: 15.93 MB / 0.5105 GB
Notification: Performance of NFScalarize.scalarize: time 0.001598/1.557, allocations: 0.6306 MB / 1.052 GB, free: 15.3 MB / 0.5105 GB
Notification: Performance of NFVerifyModel.verify: time 0.001154/1.558, allocations: 464.5 kB / 1.053 GB, free: 14.84 MB / 0.5105 GB
Notification: Performance of NFConvertDAE.convert: time 0.007044/1.565, allocations: 2.946 MB / 1.056 GB, free: 11.89 MB / 0.5105 GB
Notification: Performance of FrontEnd - DAE generated: time 1.144e-05/1.565, allocations: 7.984 kB / 1.056 GB, free: 11.88 MB / 0.5105 GB
Notification: Performance of FrontEnd: time 2.565e-06/1.565, allocations: 0 / 1.056 GB, free: 11.88 MB / 0.5105 GB
Notification: Performance of Transformations before backend: time 9.03e-05/1.565, allocations: 0 / 1.056 GB, free: 11.88 MB / 0.5105 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 334
 * Number of variables: 334
Notification: Performance of Generate backend data structure: time 0.007792/1.573, allocations: 2.443 MB / 1.058 GB, free: 9.371 MB / 0.5105 GB
Notification: Performance of prepare preOptimizeDAE: time 5.157e-05/1.573, allocations: 12.03 kB / 1.058 GB, free: 9.359 MB / 0.5105 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008245/1.582, allocations: 0.8128 MB / 1.059 GB, free: 8.543 MB / 0.5105 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005487/1.587, allocations: 1.592 MB / 1.061 GB, free: 6.902 MB / 0.5105 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000734/1.588, allocations: 166.8 kB / 1.061 GB, free: 6.738 MB / 0.5105 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004781/1.588, allocations: 151.9 kB / 1.061 GB, free: 6.59 MB / 0.5105 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005801/1.594, allocations: 1.695 MB / 1.062 GB, free: 4.816 MB / 0.5105 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.366e-05/1.594, allocations: 0 / 1.062 GB, free: 4.816 MB / 0.5105 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002587/1.595, allocations: 67.98 kB / 1.063 GB, free: 4.75 MB / 0.5105 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.891e-05/1.595, allocations: 50.53 kB / 1.063 GB, free: 4.699 MB / 0.5105 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00652/1.601, allocations: 1.489 MB / 1.064 GB, free: 3.203 MB / 0.5105 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.0218/1.623, allocations: 7.608 MB / 1.071 GB, free: 11.04 MB / 0.5261 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.006768/1.63, allocations: 1.663 MB / 1.073 GB, free: 9.242 MB / 0.5261 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002728/1.633, allocations: 0.5397 MB / 1.074 GB, free: 8.703 MB / 0.5261 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.002967/1.636, allocations: 0.818 MB / 1.074 GB, free: 7.738 MB / 0.5261 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.003061/1.639, allocations: 0.5866 MB / 1.075 GB, free: 7.137 MB / 0.5261 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001529/1.64, allocations: 127.2 kB / 1.075 GB, free: 7.012 MB / 0.5261 GB
Notification: Performance of pre-optimization done (n=84): time 5.721e-06/1.64, allocations: 4 kB / 1.075 GB, free: 7.008 MB / 0.5261 GB
Notification: Performance of matching and sorting (n=84): time 0.009708/1.65, allocations: 1.667 MB / 1.077 GB, free: 5.312 MB / 0.5261 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001153/1.65, allocations: 137.5 kB / 1.077 GB, free: 5.148 MB / 0.5261 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004697/1.655, allocations: 1.62 MB / 1.078 GB, free: 3.48 MB / 0.5261 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003248/1.655, allocations: 49.7 kB / 1.079 GB, free: 3.426 MB / 0.5261 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001588/1.657, allocations: 0.9283 MB / 1.079 GB, free: 2.488 MB / 0.5261 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000602/1.658, allocations: 295.6 kB / 1.08 GB, free: 2.195 MB / 0.5261 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001313/1.658, allocations: 20 kB / 1.08 GB, free: 2.176 MB / 0.5261 GB
Notification: Performance of setup shared object (initialization): time 0.0002445/1.658, allocations: 357.9 kB / 1.08 GB, free: 1.82 MB / 0.5261 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002618/1.661, allocations: 470.3 kB / 1.081 GB, free: 1.363 MB / 0.5261 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003063/1.664, allocations: 0.8274 MB / 1.081 GB, free: 384 kB / 0.5261 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002907/1.667, allocations: 0.5784 MB / 1.082 GB, free: 15.79 MB / 0.5417 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.19e-05/1.667, allocations: 0 / 1.082 GB, free: 15.79 MB / 0.5417 GB
Notification: Performance of matching and sorting (n=138) (initialization): time 0.007182/1.674, allocations: 1.485 MB / 1.083 GB, free: 14.29 MB / 0.5417 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004546/1.674, allocations: 365.8 kB / 1.084 GB, free: 13.81 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.712e-05/1.674, allocations: 15.98 kB / 1.084 GB, free: 13.8 MB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.947e-05/1.674, allocations: 12 kB / 1.084 GB, free: 13.79 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003081/1.678, allocations: 279.6 kB / 1.084 GB, free: 13.51 MB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.666e-05/1.678, allocations: 23.92 kB / 1.084 GB, free: 13.49 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003737/1.681, allocations: 164.4 kB / 1.084 GB, free: 13.33 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002303/1.682, allocations: 51.98 kB / 1.084 GB, free: 13.28 MB / 0.5417 GB
Notification: Performance of prepare postOptimizeDAE: time 2.298e-05/1.682, allocations: 4 kB / 1.084 GB, free: 13.27 MB / 0.5417 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.00672/1.688, allocations: 1.298 MB / 1.085 GB, free: 11.97 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.796e-05/1.689, allocations: 15.98 kB / 1.085 GB, free: 11.96 MB / 0.5417 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.145e-05/1.689, allocations: 12 kB / 1.085 GB, free: 11.95 MB / 0.5417 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002935/1.692, allocations: 275.8 kB / 1.086 GB, free: 11.68 MB / 0.5417 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.979e-05/1.692, allocations: 19.94 kB / 1.086 GB, free: 11.66 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002904/1.695, allocations: 160.4 kB / 1.086 GB, free: 11.5 MB / 0.5417 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002046/1.695, allocations: 51.98 kB / 1.086 GB, free: 11.45 MB / 0.5417 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 37
 * Number of states: 0 ()
 * Number of discrete variables: 6 ($whenCondition1,$PRE.TSet.T_start,TSet.T_start,$PRE.TSet.count,TSet.count,conPI.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (134):
 * Single equations (assignments): 130
 * Array equations: 2
 * Algorithm blocks: 1
 * Record equations: 1
 * 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.001501/1.696, allocations: 0.5397 MB / 1.087 GB, free: 10.89 MB / 0.5417 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005473/1.697, allocations: 189.7 kB / 1.087 GB, free: 10.71 MB / 0.5417 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00494/1.702, allocations: 1.216 MB / 1.088 GB, free: 9.477 MB / 0.5417 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004163/1.706, allocations: 1.285 MB / 1.089 GB, free: 8.102 MB / 0.5417 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.235e-06/1.706, allocations: 0 / 1.089 GB, free: 8.102 MB / 0.5417 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00121/1.707, allocations: 350.7 kB / 1.089 GB, free: 7.738 MB / 0.5417 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003508/1.708, allocations: 16 kB / 1.089 GB, free: 7.723 MB / 0.5417 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002172/1.71, allocations: 143.8 kB / 1.09 GB, free: 7.582 MB / 0.5417 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002117/1.71, allocations: 56.91 kB / 1.09 GB, free: 7.527 MB / 0.5417 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005907/1.716, allocations: 1.267 MB / 1.091 GB, free: 6.25 MB / 0.5417 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.006742/1.723, allocations: 1.903 MB / 1.093 GB, free: 4.328 MB / 0.5417 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00188/1.725, allocations: 0.4919 MB / 1.093 GB, free: 3.836 MB / 0.5417 GB
Notification: Performance of sorting global known variables: time 0.00261/1.727, allocations: 1.089 MB / 1.094 GB, free: 2.723 MB / 0.5417 GB
Notification: Performance of Backend: time 1.202e-06/1.727, allocations: 0 / 1.094 GB, free: 2.723 MB / 0.5417 GB
Notification: Performance of simCode: created initialization part: time 0.009463/1.737, allocations: 2.503 MB / 1.097 GB, free: 360 kB / 0.5417 GB
Notification: Performance of SimCode: time 0.01443/1.751, allocations: 5.12 MB / 1.102 GB, free: 11.17 MB / 0.5573 GB
Notification: Performance of Templates: time 0.1131/1.864, allocations: 39.97 MB / 1.141 GB, free: 3.539 MB / 0.5886 GB