Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.FMI.Validation.HeaterFan_noReverseFlow.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.FMI.Validation.HeaterFan_noReverseFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.FMI.Validation.HeaterFan_noReverseFlow")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.00258/0.002585, allocations: 358.8 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.73/1.73, allocations: 165.8 MB / 183.1 MB, free: 5.492 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 4.523/4.523, allocations: 410.8 MB / 0.6001 GB, free: 15.17 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.001767/0.001767, allocations: 197.5 kB / 0.6668 GB, free: 6.938 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0006816/0.0006819, allocations: 12 kB / 0.7429 GB, free: 8.977 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4907/0.4914, allocations: 67.45 MB / 0.8087 GB, free: 28.8 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.FMI.Validation.HeaterFan_noReverseFlow): time 0.9191/1.411, allocations: 270.2 MB / 1.073 GB, free: 10.58 MB / 0.5261 GB
Notification: Performance of NFInst.instExpressions: time 0.07526/1.486, allocations: 26.81 MB / 1.099 GB, free: 15.71 MB / 0.5574 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003387/1.489, allocations: 63.62 kB / 1.099 GB, free: 15.65 MB / 0.5574 GB
Notification: Performance of NFTyping.typeComponents: time 0.005072/1.494, allocations: 0.9123 MB / 1.1 GB, free: 14.73 MB / 0.5574 GB
Notification: Performance of NFTyping.typeBindings: time 0.01364/1.508, allocations: 3.088 MB / 1.103 GB, free: 11.63 MB / 0.5574 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01297/1.521, allocations: 2.885 MB / 1.106 GB, free: 8.758 MB / 0.5574 GB
Notification: Performance of NFFlatten.flatten: time 0.01084/1.532, allocations: 4.892 MB / 1.11 GB, free: 3.855 MB / 0.5574 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003373/1.535, allocations: 1.209 MB / 1.112 GB, free: 2.613 MB / 0.5574 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005753/1.541, allocations: 1.633 MB / 1.113 GB, free: 0.9727 MB / 0.5574 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00332/1.545, allocations: 1.136 MB / 1.114 GB, free: 15.83 MB / 0.573 GB
Notification: Performance of NFPackage.collectConstants: time 0.000499/1.545, allocations: 64 kB / 1.114 GB, free: 15.77 MB / 0.573 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005595/1.551, allocations: 1.333 MB / 1.116 GB, free: 14.43 MB / 0.573 GB
Notification: Performance of NFScalarize.scalarize: time 0.001323/1.552, allocations: 474.3 kB / 1.116 GB, free: 13.97 MB / 0.573 GB
Notification: Performance of NFVerifyModel.verify: time 0.001096/1.553, allocations: 428.8 kB / 1.116 GB, free: 13.54 MB / 0.573 GB
Notification: Performance of NFConvertDAE.convert: time 0.006728/1.56, allocations: 2.911 MB / 1.119 GB, free: 10.62 MB / 0.573 GB
Notification: Performance of FrontEnd - DAE generated: time 6.973e-06/1.56, allocations: 0 / 1.119 GB, free: 10.62 MB / 0.573 GB
Notification: Performance of FrontEnd: time 2.965e-06/1.56, allocations: 0 / 1.119 GB, free: 10.62 MB / 0.573 GB
Notification: Performance of Transformations before backend: time 7.478e-05/1.56, allocations: 0 / 1.119 GB, free: 10.62 MB / 0.573 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 273
 * Number of variables: 273
Notification: Performance of Generate backend data structure: time 0.005838/1.566, allocations: 1.914 MB / 1.121 GB, free: 8.648 MB / 0.573 GB
Notification: Performance of prepare preOptimizeDAE: time 5.708e-05/1.566, allocations: 8.031 kB / 1.121 GB, free: 8.641 MB / 0.573 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006216/1.572, allocations: 0.6338 MB / 1.122 GB, free: 8.004 MB / 0.573 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006128/1.579, allocations: 1.84 MB / 1.124 GB, free: 6.109 MB / 0.573 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001311/1.58, allocations: 222 kB / 1.124 GB, free: 5.891 MB / 0.573 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003793/1.58, allocations: 111.9 kB / 1.124 GB, free: 5.781 MB / 0.573 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004866/1.585, allocations: 1.402 MB / 1.125 GB, free: 4.266 MB / 0.573 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.798e-05/1.585, allocations: 3.938 kB / 1.125 GB, free: 4.262 MB / 0.573 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001982/1.586, allocations: 44 kB / 1.125 GB, free: 4.219 MB / 0.573 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.651e-05/1.586, allocations: 42 kB / 1.125 GB, free: 4.18 MB / 0.573 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005052/1.591, allocations: 1.062 MB / 1.126 GB, free: 3.117 MB / 0.573 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.01536/1.606, allocations: 6.892 MB / 1.133 GB, free: 11.86 MB / 0.5886 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002837/1.609, allocations: 366.5 kB / 1.134 GB, free: 11.49 MB / 0.5886 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007254/1.61, allocations: 137.6 kB / 1.134 GB, free: 11.36 MB / 0.5886 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004258/1.61, allocations: 186.2 kB / 1.134 GB, free: 11.14 MB / 0.5886 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.638e-05/1.61, allocations: 34.56 kB / 1.134 GB, free: 11.09 MB / 0.5886 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0009/1.611, allocations: 79.05 kB / 1.134 GB, free: 11.02 MB / 0.5886 GB
Notification: Performance of pre-optimization done (n=20): time 4.168e-06/1.611, allocations: 4 kB / 1.134 GB, free: 11.01 MB / 0.5886 GB
Notification: Performance of matching and sorting (n=20): time 0.002917/1.614, allocations: 0.5237 MB / 1.134 GB, free: 10.48 MB / 0.5886 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.483e-05/1.614, allocations: 63.33 kB / 1.135 GB, free: 10.41 MB / 0.5886 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004586/1.619, allocations: 1.714 MB / 1.136 GB, free: 8.695 MB / 0.5886 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001532/1.619, allocations: 33.7 kB / 1.136 GB, free: 8.656 MB / 0.5886 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001177/1.62, allocations: 0.8289 MB / 1.137 GB, free: 7.82 MB / 0.5886 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001542/1.62, allocations: 86.86 kB / 1.137 GB, free: 7.734 MB / 0.5886 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 7.913e-05/1.62, allocations: 15.98 kB / 1.137 GB, free: 7.719 MB / 0.5886 GB
Notification: Performance of setup shared object (initialization): time 0.0001906/1.621, allocations: 337.9 kB / 1.137 GB, free: 7.383 MB / 0.5886 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0016/1.622, allocations: 281.8 kB / 1.138 GB, free: 7.109 MB / 0.5886 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00197/1.624, allocations: 0.6731 MB / 1.138 GB, free: 6.25 MB / 0.5886 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001809/1.626, allocations: 363.7 kB / 1.139 GB, free: 5.895 MB / 0.5886 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.457e-05/1.626, allocations: 4 kB / 1.139 GB, free: 5.891 MB / 0.5886 GB
Notification: Performance of matching and sorting (n=85) (initialization): time 0.004516/1.631, allocations: 1.031 MB / 1.14 GB, free: 4.852 MB / 0.5886 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004465/1.631, allocations: 462.2 kB / 1.14 GB, free: 4.23 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.509e-05/1.631, allocations: 12 kB / 1.14 GB, free: 4.219 MB / 0.5886 GB
Notification: Performance of postOpt tearingSystem (initialization): time 4.325e-05/1.631, allocations: 20 kB / 1.14 GB, free: 4.199 MB / 0.5886 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001578/1.633, allocations: 203.7 kB / 1.14 GB, free: 4 MB / 0.5886 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.288e-05/1.633, allocations: 19.94 kB / 1.14 GB, free: 3.98 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002712/1.636, allocations: 184.4 kB / 1.141 GB, free: 3.801 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001563/1.636, allocations: 39.98 kB / 1.141 GB, free: 3.762 MB / 0.5886 GB
Notification: Performance of prepare postOptimizeDAE: time 1.232e-05/1.636, allocations: 4 kB / 1.141 GB, free: 3.758 MB / 0.5886 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003109/1.639, allocations: 0.8085 MB / 1.141 GB, free: 2.949 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.761e-05/1.639, allocations: 12 kB / 1.141 GB, free: 2.938 MB / 0.5886 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.746e-05/1.639, allocations: 16 kB / 1.141 GB, free: 2.922 MB / 0.5886 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001287/1.64, allocations: 183.8 kB / 1.142 GB, free: 2.742 MB / 0.5886 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.906e-05/1.64, allocations: 27.92 kB / 1.142 GB, free: 2.715 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002031/1.643, allocations: 124.5 kB / 1.142 GB, free: 2.59 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00012/1.643, allocations: 39.98 kB / 1.142 GB, free: 2.551 MB / 0.5886 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 54
 * 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 (84):
 * Single equations (assignments): 83
 * Array equations: 1
 * 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.001246/1.644, allocations: 0.5207 MB / 1.142 GB, free: 2.02 MB / 0.5886 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004408/1.644, allocations: 162 kB / 1.142 GB, free: 1.859 MB / 0.5886 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00197/1.646, allocations: 431.9 kB / 1.143 GB, free: 1.426 MB / 0.5886 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 9.478e-06/1.646, allocations: 7.984 kB / 1.143 GB, free: 1.418 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.02e-06/1.646, allocations: 3.984 kB / 1.143 GB, free: 1.414 MB / 0.5886 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008412/1.647, allocations: 286.9 kB / 1.143 GB, free: 1.113 MB / 0.5886 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002967/1.648, allocations: 8 kB / 1.143 GB, free: 1.105 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001679/1.649, allocations: 79.92 kB / 1.143 GB, free: 1.027 MB / 0.5886 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 5.752e-05/1.649, allocations: 7.984 kB / 1.143 GB, free: 1.02 MB / 0.5886 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.001833/1.651, allocations: 380.6 kB / 1.144 GB, free: 0.6406 MB / 0.5886 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.001889/1.653, allocations: 0.5843 MB / 1.144 GB, free: 36 kB / 0.5886 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0006554/1.654, allocations: 115.9 kB / 1.144 GB, free: 15.92 MB / 0.6042 GB
Notification: Performance of sorting global known variables: time 0.00351/1.657, allocations: 1.194 MB / 1.145 GB, free: 14.74 MB / 0.6042 GB
Notification: Performance of Backend: time 1.302e-06/1.657, allocations: 0 / 1.145 GB, free: 14.74 MB / 0.6042 GB
Notification: Performance of simCode: created initialization part: time 0.006558/1.664, allocations: 1.349 MB / 1.147 GB, free: 13.39 MB / 0.6042 GB
Notification: Performance of SimCode: time 0.01589/1.68, allocations: 3.936 MB / 1.151 GB, free: 9.418 MB / 0.6042 GB
Notification: Performance of Templates: time 0.6729/2.353, allocations: 33.86 MB / 1.184 GB, free: 242.6 MB / 0.6042 GB