Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_master_IBPSA.Fluid.Humidifiers.Validation.SprayAirWasher_X.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.Humidifiers.Validation.SprayAirWasher_X,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_master_IBPSA.Fluid.Humidifiers.Validation.SprayAirWasher_X")
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.002693/0.002698, allocations: 357.5 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.561/1.561, allocations: 165.8 MB / 183.1 MB, free: 5.418 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.173/4.173, allocations: 410.8 MB / 0.6001 GB, free: 15.07 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.002339/0.002339, allocations: 188.3 kB / 0.6668 GB, free: 6.898 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004855/0.0004859, allocations: 16 kB / 0.7429 GB, free: 8.93 MB / 378.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4579/0.4584, allocations: 67.44 MB / 0.8087 GB, free: 28.76 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.Humidifiers.Validation.SprayAirWasher_X): time 0.8182/1.277, allocations: 162.3 MB / 0.9672 GB, free: 1.27 MB / 474.7 MB
Notification: Performance of NFInst.instExpressions: time 0.007931/1.285, allocations: 3.627 MB / 0.9707 GB, free: 1.203 MB / 474.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009197/1.286, allocations: 22.91 kB / 0.9707 GB, free: 1.203 MB / 474.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001475/1.287, allocations: 380.2 kB / 0.9711 GB, free: 1.203 MB / 474.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.004014/1.291, allocations: 1.056 MB / 0.9721 GB, free: 1.039 MB / 474.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002877/1.294, allocations: 0.6502 MB / 0.9728 GB, free: 1 MB / 474.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002692/1.297, allocations: 1.564 MB / 0.9743 GB, free: 0.7539 MB / 474.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009106/1.298, allocations: 375.9 kB / 0.9747 GB, free: 0.6875 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002487/1.3, allocations: 0.7474 MB / 0.9754 GB, free: 0.5234 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009428/1.301, allocations: 436.1 kB / 0.9758 GB, free: 452 kB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 8.507e-05/1.301, allocations: 20.88 kB / 0.9758 GB, free: 452 kB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00193/1.303, allocations: 0.5714 MB / 0.9764 GB, free: 404 kB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.000266/1.304, allocations: 173.2 kB / 0.9766 GB, free: 380 kB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.000266/1.304, allocations: 143.5 kB / 0.9767 GB, free: 328 kB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001535/1.305, allocations: 0.7266 MB / 0.9774 GB, free: 252 kB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.801e-06/1.305, allocations: 0 / 0.9774 GB, free: 252 kB / 474.7 MB
Notification: Performance of FrontEnd: time 2.394e-06/1.305, allocations: 0 / 0.9774 GB, free: 252 kB / 474.7 MB
Notification: Performance of Transformations before backend: time 9.868e-06/1.305, allocations: 0 / 0.9774 GB, free: 252 kB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 97
 * Number of variables: 97
Notification: Performance of Generate backend data structure: time 0.003062/1.309, allocations: 0.9083 MB / 0.9783 GB, free: 15.62 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.757e-05/1.309, allocations: 12.59 kB / 0.9783 GB, free: 15.62 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002074/1.311, allocations: 233.9 kB / 0.9785 GB, free: 15.58 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001234/1.312, allocations: 0.5158 MB / 0.979 GB, free: 15.22 MB / 490.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.646e-05/1.312, allocations: 40.05 kB / 0.9791 GB, free: 15.21 MB / 490.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 9.727e-05/1.312, allocations: 36.88 kB / 0.9791 GB, free: 15.21 MB / 490.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001651/1.314, allocations: 0.5412 MB / 0.9796 GB, free: 15.02 MB / 490.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 2.1e-05/1.314, allocations: 0 / 0.9796 GB, free: 15.02 MB / 490.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.696e-05/1.314, allocations: 12.78 kB / 0.9796 GB, free: 15.02 MB / 490.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.64e-05/1.314, allocations: 14.84 kB / 0.9797 GB, free: 15.02 MB / 490.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001956/1.316, allocations: 0.5228 MB / 0.9802 GB, free: 14.92 MB / 490.7 MB
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.005116/1.321, allocations: 2.222 MB / 0.9823 GB, free: 13.35 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.001098/1.322, allocations: 346.3 kB / 0.9827 GB, free: 13.19 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003432/1.323, allocations: 140.1 kB / 0.9828 GB, free: 13.16 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.002234/1.325, allocations: 0.8656 MB / 0.9836 GB, free: 12.25 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0004637/1.325, allocations: 196.7 kB / 0.9838 GB, free: 12.15 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=26): time 4.518e-06/1.325, allocations: 0 / 0.9838 GB, free: 12.15 MB / 490.7 MB
Notification: Performance of matching and sorting (n=26): time 0.001514/1.327, allocations: 442 kB / 0.9842 GB, free: 12.02 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.059e-05/1.327, allocations: 80.02 kB / 0.9843 GB, free: 11.93 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001287/1.328, allocations: 0.6534 MB / 0.985 GB, free: 11.7 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 5.711e-05/1.328, allocations: 35.11 kB / 0.985 GB, free: 11.67 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.000341/1.329, allocations: 311.3 kB / 0.9853 GB, free: 11.5 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001124/1.329, allocations: 91.53 kB / 0.9854 GB, free: 11.44 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 3.322e-05/1.329, allocations: 8.188 kB / 0.9854 GB, free: 11.44 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0002033/1.329, allocations: 370.2 kB / 0.9857 GB, free: 11.11 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005722/1.33, allocations: 134.6 kB / 0.9859 GB, free: 11.08 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007131/1.33, allocations: 272.5 kB / 0.9861 GB, free: 10.89 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0006311/1.331, allocations: 170.4 kB / 0.9863 GB, free: 10.85 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.821e-06/1.331, allocations: 1.781 kB / 0.9863 GB, free: 10.85 MB / 490.7 MB
Notification: Performance of matching and sorting (n=43) (initialization): time 0.001656/1.333, allocations: 480.8 kB / 0.9868 GB, free: 10.7 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001706/1.333, allocations: 210.9 kB / 0.987 GB, free: 10.46 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.107e-05/1.333, allocations: 3.156 kB / 0.987 GB, free: 10.46 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 2.379e-05/1.333, allocations: 7.688 kB / 0.987 GB, free: 10.46 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007327/1.334, allocations: 85.3 kB / 0.987 GB, free: 10.45 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.862e-05/1.334, allocations: 9.375 kB / 0.9871 GB, free: 10.44 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001073/1.335, allocations: 89.88 kB / 0.9871 GB, free: 10.4 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.278e-05/1.335, allocations: 24.33 kB / 0.9872 GB, free: 10.39 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 8.196e-06/1.335, allocations: 2.781 kB / 0.9872 GB, free: 10.39 MB / 490.7 MB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.001399/1.336, allocations: 386.4 kB / 0.9875 GB, free: 10.31 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.823e-06/1.336, allocations: 3.562 kB / 0.9875 GB, free: 10.31 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 9.858e-06/1.336, allocations: 14.41 kB / 0.9876 GB, free: 10.3 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007131/1.337, allocations: 89.69 kB / 0.9876 GB, free: 10.28 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.037e-05/1.337, allocations: 5.906 kB / 0.9876 GB, free: 10.28 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008697/1.338, allocations: 72.55 kB / 0.9877 GB, free: 10.25 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.449e-05/1.338, allocations: 18.88 kB / 0.9877 GB, free: 10.25 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 18
 * Number of states: 0 ()
 * Number of discrete variables: 5 ($whenCondition1,$PRE.XSet.nextTimeEvent,XSet.nextTimeEvent,$PRE.XSet.nextTimeEventScaled,XSet.nextTimeEventScaled)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (43):
 * Single equations (assignments): 43
 * 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 prepare postOptimizeDAE: time 0.0004606/1.338, allocations: 284.3 kB / 0.988 GB, free: 10.04 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001302/1.339, allocations: 58.5 kB / 0.9881 GB, free: 10.01 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008739/1.339, allocations: 326.6 kB / 0.9884 GB, free: 9.895 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.06e-05/1.34, allocations: 3.953 kB / 0.9884 GB, free: 9.891 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.717e-06/1.34, allocations: 2.844 kB / 0.9884 GB, free: 9.891 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002999/1.34, allocations: 162.3 kB / 0.9885 GB, free: 9.75 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001183/1.34, allocations: 6.375 kB / 0.9885 GB, free: 9.75 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0005739/1.341, allocations: 30.62 kB / 0.9886 GB, free: 9.738 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.653e-05/1.341, allocations: 18.16 kB / 0.9886 GB, free: 9.73 MB / 490.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0009196/1.342, allocations: 431.6 kB / 0.989 GB, free: 9.504 MB / 490.7 MB
Notification: Performance of postOpt detectDAEmodeSparsePattern (simulation): time 0.0009087/1.343, allocations: 0.5518 MB / 0.9895 GB, free: 9.145 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0002731/1.343, allocations: 104.8 kB / 0.9896 GB, free: 9.129 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.001048/1.344, allocations: 472.5 kB / 0.9901 GB, free: 8.965 MB / 490.7 MB
Notification: Performance of Backend: time 8.82e-07/1.344, allocations: 6.625 kB / 0.9901 GB, free: 8.961 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.004058/1.348, allocations: 0.9841 MB / 0.9911 GB, free: 8.641 MB / 490.7 MB
Notification: Performance of SimCode: time 0.003912/1.352, allocations: 1.704 MB / 0.9927 GB, free: 7.453 MB / 490.7 MB
Notification: Performance of Templates: time 0.03455/1.386, allocations: 12.62 MB / 1.005 GB, free: 1.137 MB / 490.7 MB