Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.FMI.Validation.HeaterFan.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,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.FMI.Validation.HeaterFan")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002517/0.002516, allocations: 358.4 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.496/1.496, allocations: 165.8 MB / 183.1 MB, free: 5.395 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.179/4.179, 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.001771/0.00177, allocations: 201.5 kB / 0.6668 GB, free: 6.914 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.000484/0.0004843, allocations: 8 kB / 0.7429 GB, free: 8.961 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4332/0.4338, allocations: 67.45 MB / 0.8087 GB, free: 28.77 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.FMI.Validation.HeaterFan): time 1.037/1.471, allocations: 277 MB / 1.079 GB, free: 3.758 MB / 0.5261 GB
Notification: Performance of NFInst.instExpressions: time 0.06055/1.532, allocations: 28.47 MB / 1.107 GB, free: 7.219 MB / 0.5573 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003501/1.535, allocations: 67.56 kB / 1.107 GB, free: 7.152 MB / 0.5573 GB
Notification: Performance of NFTyping.typeComponents: time 0.005097/1.541, allocations: 0.9318 MB / 1.108 GB, free: 6.215 MB / 0.5573 GB
Notification: Performance of NFTyping.typeBindings: time 0.01663/1.557, allocations: 3.118 MB / 1.111 GB, free: 3.082 MB / 0.5573 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01859/1.576, allocations: 2.897 MB / 1.114 GB, free: 208 kB / 0.5573 GB
Notification: Performance of NFFlatten.flatten: time 0.01453/1.59, allocations: 5.286 MB / 1.119 GB, free: 10.91 MB / 0.573 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003839/1.594, allocations: 1.389 MB / 1.12 GB, free: 9.488 MB / 0.573 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006103/1.601, allocations: 1.731 MB / 1.122 GB, free: 7.75 MB / 0.573 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005395/1.606, allocations: 1.249 MB / 1.123 GB, free: 6.496 MB / 0.573 GB
Notification: Performance of NFPackage.collectConstants: time 0.0009618/1.607, allocations: 64 kB / 1.123 GB, free: 6.434 MB / 0.573 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006683/1.614, allocations: 1.566 MB / 1.125 GB, free: 4.863 MB / 0.573 GB
Notification: Performance of NFScalarize.scalarize: time 0.001464/1.615, allocations: 490.2 kB / 1.125 GB, free: 4.383 MB / 0.573 GB
Notification: Performance of NFVerifyModel.verify: time 0.001306/1.617, allocations: 464.6 kB / 1.126 GB, free: 3.922 MB / 0.573 GB
Notification: Performance of NFConvertDAE.convert: time 0.00825/1.625, allocations: 3.238 MB / 1.129 GB, free: 0.6758 MB / 0.573 GB
Notification: Performance of FrontEnd - DAE generated: time 1.042e-05/1.625, allocations: 0 / 1.129 GB, free: 0.6758 MB / 0.573 GB
Notification: Performance of FrontEnd: time 3.277e-06/1.625, allocations: 0 / 1.129 GB, free: 0.6758 MB / 0.573 GB
Notification: Performance of Transformations before backend: time 0.0001166/1.625, allocations: 0 / 1.129 GB, free: 0.6758 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: 328
 * Number of variables: 328
Notification: Performance of Generate backend data structure: time 0.009821/1.635, allocations: 2.068 MB / 1.131 GB, free: 14.54 MB / 0.5886 GB
Notification: Performance of prepare preOptimizeDAE: time 6.514e-05/1.635, allocations: 8.031 kB / 1.131 GB, free: 14.54 MB / 0.5886 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01413/1.649, allocations: 0.8677 MB / 1.132 GB, free: 13.66 MB / 0.5886 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009339/1.659, allocations: 1.934 MB / 1.134 GB, free: 11.68 MB / 0.5886 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002243/1.661, allocations: 238.4 kB / 1.134 GB, free: 11.44 MB / 0.5886 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007357/1.662, allocations: 132 kB / 1.134 GB, free: 11.31 MB / 0.5886 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009235/1.671, allocations: 1.661 MB / 1.136 GB, free: 9.594 MB / 0.5886 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.643e-05/1.671, allocations: 0 / 1.136 GB, free: 9.594 MB / 0.5886 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002228/1.671, allocations: 52 kB / 1.136 GB, free: 9.543 MB / 0.5886 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.427e-05/1.671, allocations: 50.5 kB / 1.136 GB, free: 9.492 MB / 0.5886 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00748/1.679, allocations: 1.501 MB / 1.137 GB, free: 7.984 MB / 0.5886 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.01873/1.698, allocations: 7.399 MB / 1.145 GB, free: 84 kB / 0.5886 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004377/1.702, allocations: 0.6858 MB / 1.145 GB, free: 15.38 MB / 0.6042 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001614/1.704, allocations: 300.7 kB / 1.145 GB, free: 15.09 MB / 0.6042 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004254/1.704, allocations: 186.2 kB / 1.146 GB, free: 14.87 MB / 0.6042 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.465e-05/1.704, allocations: 34.56 kB / 1.146 GB, free: 14.82 MB / 0.6042 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001052/1.705, allocations: 94.92 kB / 1.146 GB, free: 14.73 MB / 0.6042 GB
Notification: Performance of pre-optimization done (n=37): time 3.827e-06/1.705, allocations: 0 / 1.146 GB, free: 14.73 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=37): time 0.005873/1.711, allocations: 1.329 MB / 1.147 GB, free: 13.32 MB / 0.6042 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.501e-05/1.711, allocations: 59.34 kB / 1.147 GB, free: 13.25 MB / 0.6042 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005197/1.716, allocations: 1.851 MB / 1.149 GB, free: 11.35 MB / 0.6042 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001831/1.717, allocations: 33.7 kB / 1.149 GB, free: 11.31 MB / 0.6042 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001122/1.718, allocations: 0.8195 MB / 1.15 GB, free: 10.48 MB / 0.6042 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002212/1.718, allocations: 130.1 kB / 1.15 GB, free: 10.36 MB / 0.6042 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 8.636e-05/1.718, allocations: 12 kB / 1.15 GB, free: 10.34 MB / 0.6042 GB
Notification: Performance of setup shared object (initialization): time 0.0001732/1.718, allocations: 329.9 kB / 1.15 GB, free: 10.02 MB / 0.6042 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002388/1.721, allocations: 421 kB / 1.151 GB, free: 9.605 MB / 0.6042 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002693/1.724, allocations: 0.6508 MB / 1.151 GB, free: 8.84 MB / 0.6042 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002527/1.726, allocations: 461.3 kB / 1.152 GB, free: 8.391 MB / 0.6042 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.112e-05/1.726, allocations: 4 kB / 1.152 GB, free: 8.387 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=74) (initialization): time 0.006248/1.732, allocations: 1.393 MB / 1.153 GB, free: 6.941 MB / 0.6042 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002606/1.733, allocations: 268.6 kB / 1.153 GB, free: 6.59 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.03e-05/1.733, allocations: 11.98 kB / 1.153 GB, free: 6.578 MB / 0.6042 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003061/1.733, allocations: 61.64 kB / 1.153 GB, free: 6.512 MB / 0.6042 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002185/1.735, allocations: 219.6 kB / 1.154 GB, free: 6.297 MB / 0.6042 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003081/1.738, allocations: 2.817 MB / 1.156 GB, free: 3.211 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003821/1.742, allocations: 284.3 kB / 1.157 GB, free: 2.934 MB / 0.6042 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001831/1.742, allocations: 35.98 kB / 1.157 GB, free: 2.898 MB / 0.6042 GB
Notification: Performance of prepare postOptimizeDAE: time 1.224e-05/1.742, allocations: 4 kB / 1.157 GB, free: 2.895 MB / 0.6042 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.00508/1.748, allocations: 1.155 MB / 1.158 GB, free: 1.695 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.056e-05/1.748, allocations: 4 kB / 1.158 GB, free: 1.691 MB / 0.6042 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002254/1.748, allocations: 61.62 kB / 1.158 GB, free: 1.625 MB / 0.6042 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00194/1.75, allocations: 204 kB / 1.158 GB, free: 1.426 MB / 0.6042 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3965/2.146, allocations: 2.808 MB / 1.161 GB, free: 252.9 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004085/2.151, allocations: 211.3 kB / 1.161 GB, free: 252.9 MB / 0.6042 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002746/2.151, allocations: 32.59 kB / 1.161 GB, free: 252.9 MB / 0.6042 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 28
 * 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 (69):
 * Single equations (assignments): 66
 * 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: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(1,100.0%) 2,(1,100.0%) 2}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.002076/2.153, allocations: 0.519 MB / 1.162 GB, free: 252.8 MB / 0.6042 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006189/2.154, allocations: 174.3 kB / 1.162 GB, free: 252.8 MB / 0.6042 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004776/2.158, allocations: 0.9215 MB / 1.163 GB, free: 252.5 MB / 0.6042 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.802e-05/2.158, allocations: 5.203 kB / 1.163 GB, free: 252.5 MB / 0.6042 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.041e-06/2.158, allocations: 5.219 kB / 1.163 GB, free: 252.5 MB / 0.6042 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00141/2.16, allocations: 334.5 kB / 1.163 GB, free: 252.4 MB / 0.6042 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005054/2.16, allocations: 9.531 kB / 1.163 GB, free: 252.4 MB / 0.6042 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02708/2.187, allocations: 139.8 kB / 1.163 GB, free: 252.3 MB / 0.6042 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001928/2.188, allocations: 22.16 kB / 1.163 GB, free: 252.3 MB / 0.6042 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01386/2.202, allocations: 0.71 MB / 1.164 GB, free: 252.1 MB / 0.6042 GB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.01564/2.217, allocations: 1.154 MB / 1.165 GB, free: 251.5 MB / 0.6042 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001622/2.219, allocations: 247.5 kB / 1.165 GB, free: 251.4 MB / 0.6042 GB
Notification: Performance of sorting global known variables: time 0.006198/2.225, allocations: 1.312 MB / 1.166 GB, free: 250.9 MB / 0.6042 GB
Notification: Performance of Backend: time 1.754e-06/2.225, allocations: 0 / 1.166 GB, free: 250.9 MB / 0.6042 GB
Notification: Performance of simCode: created initialization part: time 0.009425/2.235, allocations: 2.055 MB / 1.168 GB, free: 250.1 MB / 0.6042 GB
Notification: Performance of SimCode: time 0.01407/2.249, allocations: 4.366 MB / 1.173 GB, free: 247.8 MB / 0.6042 GB
Notification: Performance of Templates: time 0.09372/2.343, allocations: 41.13 MB / 1.213 GB, free: 212.1 MB / 0.6042 GB