Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IDEAS.Fluid.FMI.Validation.HeaterFan,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan")
translateModel(IDEAS.Fluid.FMI.Validation.HeaterFan,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009002/0.0009003, allocations: 91.53 kB / 15.06 MB, free: 1.098 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.00111/0.00111, allocations: 172.7 kB / 15.95 MB, free: 208 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.314/1.314, allocations: 223.3 MB / 240 MB, free: 5.203 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0/package.mo): time 0.7344/0.7344, allocations: 132.3 MB / 423.4 MB, free: 1.992 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0002895/0.0002895, allocations: 8.625 kB / 0.5034 GB, free: 4.234 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.354/0.3543, allocations: 81.58 MB / 0.5831 GB, free: 3.965 MB / 430.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.FMI.Validation.HeaterFan): time 0.5818/0.9362, allocations: 285.5 MB / 0.8619 GB, free: 25.19 MB / 0.6387 GB
Notification: Performance of NFInst.instExpressions: time 0.02372/0.96, allocations: 25.57 MB / 0.8869 GB, free: 24.8 MB / 0.6387 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002134/0.9621, allocations: 66.12 kB / 0.8869 GB, free: 24.8 MB / 0.6387 GB
Notification: Performance of NFTyping.typeComponents: time 0.002568/0.9647, allocations: 0.9746 MB / 0.8879 GB, free: 24.79 MB / 0.6387 GB
Notification: Performance of NFTyping.typeBindings: time 0.004981/0.9697, allocations: 2.186 MB / 0.89 GB, free: 24.7 MB / 0.6387 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004227/0.9739, allocations: 1.742 MB / 0.8917 GB, free: 24.64 MB / 0.6387 GB
Notification: Performance of NFFlatten.flatten: time 0.004949/0.9789, allocations: 5.116 MB / 0.8967 GB, free: 24.63 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001727/0.9806, allocations: 1.391 MB / 0.8981 GB, free: 24.44 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003213/0.9839, allocations: 1.809 MB / 0.8998 GB, free: 24.42 MB / 0.6387 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001674/0.9856, allocations: 1.248 MB / 0.901 GB, free: 24.41 MB / 0.6387 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001593/0.9857, allocations: 69.59 kB / 0.9011 GB, free: 24.41 MB / 0.6387 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004193/0.9899, allocations: 2.009 MB / 0.9031 GB, free: 24.38 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004541/0.9904, allocations: 488.4 kB / 0.9035 GB, free: 24.23 MB / 0.6387 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005209/0.9909, allocations: 473.9 kB / 0.904 GB, free: 24.19 MB / 0.6387 GB
Notification: Performance of NFConvertDAE.convert: time 0.003087/0.994, allocations: 3.403 MB / 0.9073 GB, free: 22.35 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 4.499e-06/0.994, allocations: 3.469 kB / 0.9073 GB, free: 22.35 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.523e-06/0.994, allocations: 0 / 0.9073 GB, free: 22.35 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 1.974e-05/0.9941, allocations: 0 / 0.9073 GB, free: 22.35 MB / 0.6387 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.002769/0.9968, allocations: 2.12 MB / 0.9094 GB, free: 20.94 MB / 0.6387 GB
Notification: Performance of prepare preOptimizeDAE: time 3.211e-05/0.9969, allocations: 11.17 kB / 0.9094 GB, free: 20.94 MB / 0.6387 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004569/1.001, allocations: 0.8601 MB / 0.9102 GB, free: 20.59 MB / 0.6387 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002671/1.004, allocations: 1.944 MB / 0.9121 GB, free: 19.79 MB / 0.6387 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000543/1.005, allocations: 237 kB / 0.9124 GB, free: 19.74 MB / 0.6387 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001595/1.005, allocations: 131.4 kB / 0.9125 GB, free: 19.74 MB / 0.6387 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002901/1.008, allocations: 1.66 MB / 0.9141 GB, free: 19.26 MB / 0.6387 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.507e-05/1.008, allocations: 2.625 kB / 0.9141 GB, free: 19.26 MB / 0.6387 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001202/1.008, allocations: 52.03 kB / 0.9142 GB, free: 19.26 MB / 0.6387 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.382e-05/1.008, allocations: 46.42 kB / 0.9142 GB, free: 19.26 MB / 0.6387 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003119/1.011, allocations: 1.501 MB / 0.9157 GB, free: 19.01 MB / 0.6387 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.008072/1.019, allocations: 7.389 MB / 0.9229 GB, free: 14.41 MB / 0.6387 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002032/1.021, allocations: 0.6964 MB / 0.9236 GB, free: 14.26 MB / 0.6387 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0006347/1.022, allocations: 303.5 kB / 0.9239 GB, free: 14.21 MB / 0.6387 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002231/1.022, allocations: 190.8 kB / 0.924 GB, free: 14.01 MB / 0.6387 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 1.707e-05/1.022, allocations: 40.09 kB / 0.9241 GB, free: 13.97 MB / 0.6387 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0005277/1.023, allocations: 85.12 kB / 0.9242 GB, free: 13.91 MB / 0.6387 GB
Notification: Performance of pre-optimization done (n=37): time 2.485e-06/1.023, allocations: 0 / 0.9242 GB, free: 13.91 MB / 0.6387 GB
Notification: Performance of matching and sorting (n=37): time 0.002589/1.025, allocations: 1.331 MB / 0.9255 GB, free: 13.23 MB / 0.6387 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.044e-05/1.025, allocations: 59.41 kB / 0.9255 GB, free: 13.16 MB / 0.6387 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00239/1.028, allocations: 1.854 MB / 0.9273 GB, free: 12.65 MB / 0.6387 GB
Notification: Performance of collectPreVariables (initialization): time 5.23e-05/1.028, allocations: 38.17 kB / 0.9274 GB, free: 12.61 MB / 0.6387 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004998/1.028, allocations: 0.8196 MB / 0.9282 GB, free: 12.29 MB / 0.6387 GB
Notification: Performance of collectInitialBindings (initialization): time 7.85e-05/1.028, allocations: 132.7 kB / 0.9283 GB, free: 12.2 MB / 0.6387 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.625e-05/1.028, allocations: 13.94 kB / 0.9283 GB, free: 12.2 MB / 0.6387 GB
Notification: Performance of setup shared object (initialization): time 4.575e-05/1.028, allocations: 329.8 kB / 0.9286 GB, free: 11.89 MB / 0.6387 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001027/1.03, allocations: 413.8 kB / 0.929 GB, free: 11.8 MB / 0.6387 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001077/1.031, allocations: 0.6521 MB / 0.9296 GB, free: 11.42 MB / 0.6387 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001046/1.032, allocations: 452.9 kB / 0.9301 GB, free: 11.34 MB / 0.6387 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.769e-06/1.032, allocations: 3.188 kB / 0.9301 GB, free: 11.34 MB / 0.6387 GB
Notification: Performance of matching and sorting (n=74) (initialization): time 0.002626/1.034, allocations: 1.393 MB / 0.9314 GB, free: 10.83 MB / 0.6387 GB
Notification: Performance of prepare postOptimizeDAE: time 9.285e-05/1.034, allocations: 292.2 kB / 0.9317 GB, free: 10.5 MB / 0.6387 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.088e-05/1.034, allocations: 6.406 kB / 0.9317 GB, free: 10.5 MB / 0.6387 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001806/1.035, allocations: 57.75 kB / 0.9318 GB, free: 10.47 MB / 0.6387 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001026/1.036, allocations: 220 kB / 0.932 GB, free: 10.42 MB / 0.6387 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001347/1.037, allocations: 2.822 MB / 0.9347 GB, free: 7.621 MB / 0.6387 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001818/1.039, allocations: 275 kB / 0.935 GB, free: 7.363 MB / 0.6387 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.586e-05/1.039, allocations: 31.23 kB / 0.935 GB, free: 7.336 MB / 0.6387 GB
Notification: Performance of prepare postOptimizeDAE: time 5.651e-06/1.039, allocations: 4 kB / 0.935 GB, free: 7.332 MB / 0.6387 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002122/1.041, allocations: 1.151 MB / 0.9362 GB, free: 6.438 MB / 0.6387 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.394e-06/1.041, allocations: 8.062 kB / 0.9362 GB, free: 6.43 MB / 0.6387 GB
Notification: Performance of postOpt tearingSystem (initialization): time 9.67e-05/1.041, allocations: 63.39 kB / 0.9362 GB, free: 6.371 MB / 0.6387 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008931/1.042, allocations: 208.7 kB / 0.9364 GB, free: 6.184 MB / 0.6387 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001183/1.043, allocations: 2.822 MB / 0.9392 GB, free: 3.262 MB / 0.6387 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001433/1.045, allocations: 214.1 kB / 0.9394 GB, free: 3.062 MB / 0.6387 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.808e-05/1.045, allocations: 31.92 kB / 0.9394 GB, free: 3.035 MB / 0.6387 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.0007456/1.046, allocations: 0.5194 MB / 0.9399 GB, free: 2.691 MB / 0.6387 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001624/1.046, allocations: 165.9 kB / 0.9401 GB, free: 2.527 MB / 0.6387 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001616/1.047, allocations: 0.9309 MB / 0.941 GB, free: 1.77 MB / 0.6387 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.068e-06/1.047, allocations: 4 kB / 0.941 GB, free: 1.766 MB / 0.6387 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.296e-06/1.047, allocations: 4 kB / 0.941 GB, free: 1.762 MB / 0.6387 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.43e-06/1.047, allocations: 8.078 kB / 0.941 GB, free: 1.754 MB / 0.6387 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 postOpt removeSimpleEquations (simulation): time 0.002841/1.05, allocations: 2.172 MB / 0.9431 GB, free: 92 kB / 0.6387 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.637e-06/1.05, allocations: 0 / 0.9431 GB, free: 92 kB / 0.6387 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005843/1.051, allocations: 104.2 kB / 0.9432 GB, free: 15.99 MB / 0.6544 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0001132/1.051, allocations: 47.2 kB / 0.9433 GB, free: 15.95 MB / 0.6544 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.289e-05/1.051, allocations: 12.38 kB / 0.9433 GB, free: 15.95 MB / 0.6544 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001729/1.053, allocations: 2.816 MB / 0.946 GB, free: 13.03 MB / 0.6544 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.954e-06/1.053, allocations: 0 / 0.946 GB, free: 13.03 MB / 0.6544 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001655/1.054, allocations: 0.9969 MB / 0.947 GB, free: 12.12 MB / 0.6544 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005085/1.055, allocations: 327.8 kB / 0.9473 GB, free: 11.85 MB / 0.6544 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001804/1.055, allocations: 10.5 kB / 0.9473 GB, free: 11.85 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001123/1.056, allocations: 139 kB / 0.9475 GB, free: 11.71 MB / 0.6544 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 4.933e-05/1.056, allocations: 35.03 kB / 0.9475 GB, free: 11.69 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.647e-05/1.056, allocations: 19.22 kB / 0.9475 GB, free: 11.67 MB / 0.6544 GB
Notification: Performance of sorting global known variables: time 0.0021/1.058, allocations: 1.315 MB / 0.9488 GB, free: 10.71 MB / 0.6544 GB
Notification: Performance of sort global known variables: time 2.41e-07/1.058, allocations: 0 / 0.9488 GB, free: 10.71 MB / 0.6544 GB
Notification: Performance of remove unused functions: time 0.002468/1.061, allocations: 0.7641 MB / 0.9496 GB, free: 9.984 MB / 0.6544 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * 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 simulation (33):
 * Single equations (assignments): 31
 * 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: 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 Backend phase and start with SimCode phase: time 0.0005845/1.062, allocations: 306.8 kB / 0.9498 GB, free: 9.707 MB / 0.6544 GB
Notification: Performance of simCode: created initialization part: time 0.003539/1.065, allocations: 1.728 MB / 0.9515 GB, free: 8.301 MB / 0.6544 GB
Notification: Performance of simCode: created event and clocks part: time 3.016e-06/1.065, allocations: 5.062 kB / 0.9515 GB, free: 8.297 MB / 0.6544 GB
Notification: Performance of simCode: created simulation system equations: time 0.000806/1.066, allocations: 0.4961 MB / 0.952 GB, free: 7.867 MB / 0.6544 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003709/1.07, allocations: 0.9559 MB / 0.953 GB, free: 7.082 MB / 0.6544 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004238/1.074, allocations: 3.126 MB / 0.956 GB, free: 4.227 MB / 0.6544 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008577/1.075, allocations: 1.349 MB / 0.9573 GB, free: 2.91 MB / 0.6544 GB
Notification: Performance of simCode: alias equations: time 0.001777/1.077, allocations: 371 kB / 0.9577 GB, free: 2.543 MB / 0.6544 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003302/1.077, allocations: 127.3 kB / 0.9578 GB, free: 2.422 MB / 0.6544 GB
Notification: Performance of SimCode: time 5.91e-07/1.077, allocations: 0 / 0.9578 GB, free: 2.422 MB / 0.6544 GB
Notification: Performance of Templates: time 0.0503/1.127, allocations: 33.05 MB / 0.9901 GB, free: 1.934 MB / 0.6856 GB
make -j1 -f IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.makefile
(rm -f IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe ; mkfifo IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe >> ../files/IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.sim & ./IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe 2>&1)