Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_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-master/package.mo", uses=false)
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/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_dev_IDEAS.Fluid.FMI.Validation.HeaterFan")
translateModel(IDEAS.Fluid.FMI.Validation.HeaterFan,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_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.0008085/0.0008082, allocations: 98.27 kB / 15.36 MB, free: 0.7344 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.006639/0.006639, allocations: 172.2 kB / 16.27 MB, free: 6.66 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.44/1.44, allocations: 223.3 MB / 240.3 MB, free: 5.355 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 1.079/1.079, allocations: 135.3 MB / 426.7 MB, free: 14.32 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0005969/0.0005969, allocations: 12.84 kB / 0.5076 GB, free: 4.781 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2139/0.2146, allocations: 82.31 MB / 0.588 GB, free: 2.645 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.FMI.Validation.HeaterFan): time 1.204/1.418, allocations: 306.2 MB / 0.887 GB, free: 20.85 MB / 0.67 GB
Notification: Performance of NFInst.instExpressions: time 0.0317/1.45, allocations: 27.31 MB / 0.9136 GB, free: 20.64 MB / 0.67 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004499/1.455, allocations: 67.91 kB / 0.9137 GB, free: 20.64 MB / 0.67 GB
Notification: Performance of NFTyping.typeComponents: time 0.005976/1.461, allocations: 1.334 MB / 0.915 GB, free: 20.62 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.01044/1.471, allocations: 2.8 MB / 0.9177 GB, free: 20.49 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009299/1.48, allocations: 2.365 MB / 0.9201 GB, free: 20.41 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.01251/1.493, allocations: 5.988 MB / 0.9259 GB, free: 20.4 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003886/1.497, allocations: 1.616 MB / 0.9275 GB, free: 20.13 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.008397/1.505, allocations: 1.964 MB / 0.9294 GB, free: 20.1 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00604/1.511, allocations: 1.329 MB / 0.9307 GB, free: 20.1 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0007212/1.512, allocations: 67.19 kB / 0.9308 GB, free: 20.1 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01089/1.523, allocations: 3.624 MB / 0.9343 GB, free: 20.06 MB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.001635/1.525, allocations: 0.4973 MB / 0.9348 GB, free: 20.05 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.002454/1.527, allocations: 0.9934 MB / 0.9358 GB, free: 20.02 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.01126/1.538, allocations: 4.668 MB / 0.9403 GB, free: 19.79 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 6.202e-06/1.538, allocations: 0 / 0.9403 GB, free: 19.79 MB / 0.67 GB
Notification: Performance of FrontEnd: time 3.076e-06/1.538, allocations: 2.844 kB / 0.9403 GB, free: 19.79 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 8.106e-05/1.539, allocations: 0 / 0.9403 GB, free: 19.79 MB / 0.67 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.004229/1.543, allocations: 2.099 MB / 0.9424 GB, free: 18.39 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.416e-05/1.543, allocations: 9.969 kB / 0.9424 GB, free: 18.39 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005234/1.548, allocations: 0.8711 MB / 0.9432 GB, free: 18.04 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003794/1.552, allocations: 2.002 MB / 0.9452 GB, free: 17.18 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006503/1.553, allocations: 231 kB / 0.9454 GB, free: 17.14 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003191/1.553, allocations: 130.9 kB / 0.9455 GB, free: 17.14 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003854/1.557, allocations: 1.665 MB / 0.9472 GB, free: 16.66 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.727e-05/1.557, allocations: 4.969 kB / 0.9472 GB, free: 16.66 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001628/1.557, allocations: 55.09 kB / 0.9472 GB, free: 16.66 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.496e-05/1.557, allocations: 49.28 kB / 0.9473 GB, free: 16.66 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003978/1.561, allocations: 1.51 MB / 0.9487 GB, free: 16.41 MB / 0.67 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.01223/1.573, allocations: 7.39 MB / 0.956 GB, free: 11.82 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002361/1.576, allocations: 0.6974 MB / 0.9566 GB, free: 11.68 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007182/1.576, allocations: 301.2 kB / 0.9569 GB, free: 11.63 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002732/1.577, allocations: 193.2 kB / 0.9571 GB, free: 11.43 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.875e-05/1.577, allocations: 45.62 kB / 0.9571 GB, free: 11.39 MB / 0.67 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0009358/1.578, allocations: 92.58 kB / 0.9572 GB, free: 11.32 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=37): time 3.307e-06/1.578, allocations: 0 / 0.9572 GB, free: 11.32 MB / 0.67 GB
Notification: Performance of matching and sorting (n=37): time 0.003158/1.581, allocations: 1.326 MB / 0.9585 GB, free: 10.66 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.32e-05/1.581, allocations: 58.56 kB / 0.9586 GB, free: 10.59 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003297/1.584, allocations: 1.865 MB / 0.9604 GB, free: 10.08 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001425/1.584, allocations: 36.92 kB / 0.9604 GB, free: 10.05 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006715/1.585, allocations: 0.8231 MB / 0.9612 GB, free: 9.719 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001247/1.585, allocations: 130.2 kB / 0.9614 GB, free: 9.629 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001065/1.585, allocations: 57.98 kB / 0.9614 GB, free: 9.605 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 7.063e-05/1.585, allocations: 330.5 kB / 0.9617 GB, free: 9.293 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001192/1.587, allocations: 422.9 kB / 0.9621 GB, free: 9.215 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001311/1.588, allocations: 0.6706 MB / 0.9628 GB, free: 8.824 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001922/1.59, allocations: 0.8758 MB / 0.9637 GB, free: 8.492 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.612e-06/1.59, allocations: 3.375 kB / 0.9637 GB, free: 8.492 MB / 0.67 GB
Notification: Performance of matching and sorting (n=74) (initialization): time 0.003278/1.593, allocations: 1.422 MB / 0.965 GB, free: 7.969 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001658/1.593, allocations: 277.6 kB / 0.9653 GB, free: 7.648 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.385e-05/1.593, allocations: 5.156 kB / 0.9653 GB, free: 7.648 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000207/1.594, allocations: 61.25 kB / 0.9654 GB, free: 7.625 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00107/1.595, allocations: 220 kB / 0.9656 GB, free: 7.574 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001825/1.597, allocations: 2.825 MB / 0.9683 GB, free: 4.828 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00274/1.599, allocations: 280.1 kB / 0.9686 GB, free: 4.73 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.093e-05/1.599, allocations: 34.8 kB / 0.9686 GB, free: 4.727 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 1.051e-05/1.599, allocations: 3.312 kB / 0.9686 GB, free: 4.727 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002522/1.602, allocations: 1.167 MB / 0.9698 GB, free: 4.344 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.157e-05/1.602, allocations: 6.688 kB / 0.9698 GB, free: 4.344 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001239/1.602, allocations: 55.75 kB / 0.9698 GB, free: 4.32 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009454/1.603, allocations: 207.5 kB / 0.97 GB, free: 4.277 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001488/1.605, allocations: 2.818 MB / 0.9728 GB, free: 1.543 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002079/1.607, allocations: 211.3 kB / 0.973 GB, free: 1.473 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.053e-05/1.607, allocations: 33.45 kB / 0.973 GB, free: 1.469 MB / 0.67 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 (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001163/1.608, allocations: 0.5251 MB / 0.9735 GB, free: 1.25 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003317/1.608, allocations: 168.8 kB / 0.9737 GB, free: 1.16 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001938/1.61, allocations: 0.9326 MB / 0.9746 GB, free: 0.7305 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.3e-06/1.61, allocations: 6 kB / 0.9746 GB, free: 0.7305 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.641e-06/1.61, allocations: 11.02 kB / 0.9746 GB, free: 0.7227 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.672e-06/1.61, allocations: 3.984 kB / 0.9746 GB, free: 0.7188 MB / 0.67 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.004065/1.614, allocations: 2.171 MB / 0.9768 GB, free: 15.71 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.351e-06/1.614, allocations: 3.312 kB / 0.9768 GB, free: 15.71 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006027/1.615, allocations: 95.45 kB / 0.9769 GB, free: 15.69 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0001489/1.615, allocations: 55.56 kB / 0.9769 GB, free: 15.66 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.953e-05/1.615, allocations: 15.69 kB / 0.9769 GB, free: 15.66 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002007/1.617, allocations: 2.829 MB / 0.9797 GB, free: 12.91 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.635e-06/1.617, allocations: 3.438 kB / 0.9797 GB, free: 12.91 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002096/1.619, allocations: 0.9897 MB / 0.9807 GB, free: 12.23 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007984/1.62, allocations: 335.2 kB / 0.981 GB, free: 11.94 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001662/1.62, allocations: 7.438 kB / 0.981 GB, free: 11.93 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001238/1.621, allocations: 134.5 kB / 0.9811 GB, free: 11.88 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.588e-05/1.622, allocations: 31.94 kB / 0.9811 GB, free: 11.87 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.826e-05/1.622, allocations: 17.98 kB / 0.9812 GB, free: 11.86 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.002514/1.624, allocations: 1.33 MB / 0.9825 GB, free: 11.12 MB / 0.6856 GB
Notification: Performance of sort global known variables: time 5.11e-07/1.624, allocations: 0 / 0.9825 GB, free: 11.12 MB / 0.6856 GB
Notification: Performance of remove unused functions: time 0.002725/1.627, allocations: 0.7696 MB / 0.9832 GB, free: 10.41 MB / 0.6856 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 (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0008246/1.628, allocations: 309.3 kB / 0.9835 GB, free: 10.12 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.00434/1.632, allocations: 1.737 MB / 0.9852 GB, free: 8.375 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 5.49e-06/1.632, allocations: 0 / 0.9852 GB, free: 8.375 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.0009369/1.633, allocations: 0.4933 MB / 0.9857 GB, free: 7.863 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005349/1.638, allocations: 0.9569 MB / 0.9866 GB, free: 6.93 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006714/1.645, allocations: 3.25 MB / 0.9898 GB, free: 3.656 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001095/1.646, allocations: 1.344 MB / 0.9911 GB, free: 2.285 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.002092/1.648, allocations: 375 kB / 0.9915 GB, free: 1.914 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003359/1.649, allocations: 127.4 kB / 0.9916 GB, free: 1.789 MB / 0.6856 GB
Notification: Performance of SimCode: time 1.272e-06/1.649, allocations: 0 / 0.9916 GB, free: 1.789 MB / 0.6856 GB
Notification: Performance of Templates: time 0.06461/1.713, allocations: 34.2 MB / 1.025 GB, free: 48 kB / 0.7169 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan.sim & ./IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.FMI.Validation.HeaterFan.pipe 2>&1)