Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.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 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo", uses=false)
Using package Annex60 with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow")
translateModel(Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001033/0.001033, allocations: 87.11 kB / 15.06 MB, free: 1.09 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.001392/0.001392, allocations: 168.7 kB / 15.95 MB, free: 204 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.934/1.934, allocations: 205.4 MB / 222.1 MB, free: 5.645 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.466/0.466, allocations: 55.01 MB / 325.4 MB, free: 0.7227 MB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Annex60 requested package Modelica of version 3.2.2. Modelica 3.2.3 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.000609/0.0006091, allocations: 19.98 kB / 396.7 MB, free: 11.71 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4538/0.4544, allocations: 60.72 MB / 457.4 MB, free: 38.43 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow): time 0.8022/1.257, allocations: 278.6 MB / 0.7187 GB, free: 5.191 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.03161/1.288, allocations: 26.2 MB / 0.7443 GB, free: 3.617 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004058/1.292, allocations: 64.47 kB / 0.7443 GB, free: 3.617 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.004007/1.297, allocations: 0.8568 MB / 0.7452 GB, free: 3.379 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.008013/1.305, allocations: 2.155 MB / 0.7473 GB, free: 2.773 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005889/1.31, allocations: 1.597 MB / 0.7488 GB, free: 2.387 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.007631/1.318, allocations: 4.673 MB / 0.7534 GB, free: 1.539 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002462/1.321, allocations: 1.147 MB / 0.7545 GB, free: 17.14 MB / 0.5137 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003828/1.324, allocations: 1.502 MB / 0.756 GB, free: 16.9 MB / 0.5137 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002276/1.327, allocations: 1.164 MB / 0.7571 GB, free: 16.71 MB / 0.5137 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001962/1.327, allocations: 58.09 kB / 0.7572 GB, free: 16.71 MB / 0.5137 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004819/1.332, allocations: 1.657 MB / 0.7588 GB, free: 16.28 MB / 0.5137 GB
Notification: Performance of NFScalarize.scalarize: time 0.0006148/1.332, allocations: 467.5 kB / 0.7592 GB, free: 15.98 MB / 0.5137 GB
Notification: Performance of NFVerifyModel.verify: time 0.0008306/1.333, allocations: 413.1 kB / 0.7596 GB, free: 15.79 MB / 0.5137 GB
Notification: Performance of NFConvertDAE.convert: time 0.004826/1.338, allocations: 2.946 MB / 0.7625 GB, free: 13.3 MB / 0.5137 GB
Notification: Performance of FrontEnd - DAE generated: time 7.524e-06/1.338, allocations: 0 / 0.7625 GB, free: 13.3 MB / 0.5137 GB
Notification: Performance of FrontEnd: time 1.442e-06/1.338, allocations: 0 / 0.7625 GB, free: 13.3 MB / 0.5137 GB
Notification: Performance of Transformations before backend: time 5.129e-05/1.338, allocations: 0 / 0.7625 GB, free: 13.3 MB / 0.5137 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 265
 * Number of variables: 265
Notification: Performance of Generate backend data structure: time 0.004142/1.342, allocations: 1.935 MB / 0.7644 GB, free: 11.47 MB / 0.5137 GB
Notification: Performance of prepare preOptimizeDAE: time 4.773e-05/1.342, allocations: 8.031 kB / 0.7644 GB, free: 11.46 MB / 0.5137 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003309/1.346, allocations: 0.589 MB / 0.765 GB, free: 10.89 MB / 0.5137 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003626/1.349, allocations: 1.818 MB / 0.7668 GB, free: 9.688 MB / 0.5137 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006059/1.35, allocations: 215.7 kB / 0.767 GB, free: 9.512 MB / 0.5137 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001706/1.35, allocations: 107.1 kB / 0.7671 GB, free: 9.441 MB / 0.5137 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0028/1.353, allocations: 1.355 MB / 0.7684 GB, free: 8.395 MB / 0.5137 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.56e-05/1.353, allocations: 2.906 kB / 0.7684 GB, free: 8.395 MB / 0.5137 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001056/1.353, allocations: 46.44 kB / 0.7684 GB, free: 8.383 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.015e-05/1.353, allocations: 45.97 kB / 0.7685 GB, free: 8.336 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002335/1.356, allocations: 1.038 MB / 0.7695 GB, free: 7.664 MB / 0.5137 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.009155/1.365, allocations: 6.812 MB / 0.7761 GB, free: 1.809 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001366/1.366, allocations: 369.5 kB / 0.7765 GB, free: 1.543 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003325/1.366, allocations: 129.9 kB / 0.7766 GB, free: 1.453 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003077/1.367, allocations: 194.1 kB / 0.7768 GB, free: 1.23 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.135e-05/1.367, allocations: 37.75 kB / 0.7768 GB, free: 1.188 MB / 0.5137 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0006231/1.367, allocations: 71.53 kB / 0.7769 GB, free: 1.121 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=20): time 4.228e-06/1.367, allocations: 3.188 kB / 0.7769 GB, free: 1.121 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=20): time 0.001426/1.369, allocations: 0.5345 MB / 0.7774 GB, free: 0.6875 MB / 0.5137 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.207e-05/1.369, allocations: 51.41 kB / 0.7775 GB, free: 0.625 MB / 0.5137 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002609/1.372, allocations: 1.705 MB / 0.7792 GB, free: 15.4 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 4.524e-05/1.372, allocations: 33.14 kB / 0.7792 GB, free: 15.36 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00079/1.372, allocations: 0.8223 MB / 0.78 GB, free: 14.91 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 6.669e-05/1.372, allocations: 77.06 kB / 0.7801 GB, free: 14.84 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.473e-05/1.373, allocations: 15.12 kB / 0.7801 GB, free: 14.84 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001829/1.373, allocations: 334.3 kB / 0.7804 GB, free: 14.52 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006973/1.373, allocations: 286.6 kB / 0.7807 GB, free: 14.32 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001146/1.375, allocations: 0.6595 MB / 0.7813 GB, free: 13.59 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.000998/1.376, allocations: 363.7 kB / 0.7817 GB, free: 13.35 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.136e-05/1.376, allocations: 4 kB / 0.7817 GB, free: 13.34 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=84) (initialization): time 0.002648/1.378, allocations: 1.018 MB / 0.7827 GB, free: 12.59 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0005076/1.379, allocations: 447.5 kB / 0.7831 GB, free: 12.03 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.366e-05/1.379, allocations: 12 kB / 0.7831 GB, free: 12.02 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.309e-05/1.379, allocations: 20 kB / 0.7831 GB, free: 12 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008091/1.38, allocations: 198.7 kB / 0.7833 GB, free: 11.82 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 6.006e-05/1.38, allocations: 22.44 kB / 0.7833 GB, free: 11.81 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001688/1.381, allocations: 187.1 kB / 0.7835 GB, free: 11.64 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.692e-05/1.382, allocations: 43.55 kB / 0.7835 GB, free: 11.6 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 1.047e-05/1.382, allocations: 4 kB / 0.7835 GB, free: 11.59 MB / 0.5294 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001876/1.383, allocations: 0.7931 MB / 0.7843 GB, free: 11.07 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.633e-05/1.384, allocations: 12 kB / 0.7843 GB, free: 11.06 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.566e-05/1.384, allocations: 21.88 kB / 0.7844 GB, free: 11.04 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006508/1.384, allocations: 188.6 kB / 0.7845 GB, free: 10.88 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.874e-05/1.384, allocations: 26 kB / 0.7846 GB, free: 10.86 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001029/1.385, allocations: 129.9 kB / 0.7847 GB, free: 10.75 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.24e-05/1.385, allocations: 39.8 kB / 0.7847 GB, free: 10.71 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 53
 * 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 (83):
 * Single equations (assignments): 82
 * 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.001177/1.387, allocations: 0.5138 MB / 0.7852 GB, free: 10.37 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002256/1.387, allocations: 158 kB / 0.7854 GB, free: 10.21 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001154/1.388, allocations: 427.3 kB / 0.7858 GB, free: 9.91 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.812e-06/1.388, allocations: 4 kB / 0.7858 GB, free: 9.906 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.703e-06/1.388, allocations: 4 kB / 0.7858 GB, free: 9.902 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.203e-06/1.388, allocations: 3.984 kB / 0.7858 GB, free: 9.898 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.002583/1.391, allocations: 1.489 MB / 0.7872 GB, free: 8.77 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.226e-06/1.391, allocations: 0 / 0.7872 GB, free: 8.77 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002838/1.391, allocations: 50.48 kB / 0.7873 GB, free: 8.723 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 8.075e-06/1.391, allocations: 4 kB / 0.7873 GB, free: 8.719 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.531e-05/1.391, allocations: 8.875 kB / 0.7873 GB, free: 8.715 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.969e-06/1.391, allocations: 11.02 kB / 0.7873 GB, free: 8.711 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.979e-06/1.391, allocations: 4 kB / 0.7873 GB, free: 8.707 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0009723/1.392, allocations: 0.571 MB / 0.7879 GB, free: 8.184 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003679/1.392, allocations: 275.3 kB / 0.7881 GB, free: 7.941 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001522/1.392, allocations: 10.83 kB / 0.7881 GB, free: 7.934 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007408/1.393, allocations: 79.02 kB / 0.7882 GB, free: 7.859 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 4.407e-05/1.393, allocations: 22.94 kB / 0.7882 GB, free: 7.84 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.217e-05/1.393, allocations: 10.81 kB / 0.7883 GB, free: 7.832 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.00198/1.395, allocations: 1.184 MB / 0.7894 GB, free: 7.004 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 2e-07/1.395, allocations: 4 kB / 0.7894 GB, free: 7 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.002157/1.397, allocations: 0.6449 MB / 0.79 GB, free: 6.391 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 9
 * 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 (20):
 * Single equations (assignments): 20
 * 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: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.000645/1.398, allocations: 284 kB / 0.7903 GB, free: 6.125 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.002166/1.4, allocations: 0.9426 MB / 0.7912 GB, free: 5.477 MB / 0.5294 GB
Notification: Performance of simCode: created event and clocks part: time 3.226e-06/1.4, allocations: 0 / 0.7912 GB, free: 5.477 MB / 0.5294 GB
Notification: Performance of simCode: created simulation system equations: time 0.000321/1.401, allocations: 132.5 kB / 0.7914 GB, free: 5.391 MB / 0.5294 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003231/1.404, allocations: 0.7984 MB / 0.7921 GB, free: 4.742 MB / 0.5294 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004116/1.408, allocations: 2.737 MB / 0.7948 GB, free: 2.227 MB / 0.5294 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001067/1.409, allocations: 1.273 MB / 0.7961 GB, free: 0.9844 MB / 0.5294 GB
Notification: Performance of simCode: alias equations: time 0.001741/1.411, allocations: 343.9 kB / 0.7964 GB, free: 0.6484 MB / 0.5294 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002936/1.411, allocations: 110.7 kB / 0.7965 GB, free: 0.543 MB / 0.5294 GB
Notification: Performance of SimCode: time 3.306e-06/1.411, allocations: 0 / 0.7965 GB, free: 0.543 MB / 0.5294 GB
Notification: Performance of Templates: time 0.05098/1.462, allocations: 26.84 MB / 0.8227 GB, free: 6.004 MB / 0.5606 GB
make -j1 -f Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.makefile
Environment - environmentSimulation:
(rm -f Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.pipe ; mkfifo Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.pipe ; head -c 1048576 < Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.pipe >> ../files/Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.sim & ./Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Annex60_1.0.0_Annex60.Fluid.FMI.Validation.HeaterFan_noReverseFlow.pipe 2>&1)
Environment - environmentSimulation: