Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.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.HeatExchangers.Examples.AirHeater_T,tolerance=1e-08,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T")
translateModel(IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T,tolerance=1e-08,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001332/0.001332, allocations: 104.8 kB / 16.27 MB, free: 6.055 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.001251/0.001251, allocations: 191.9 kB / 17.2 MB, free: 5.688 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.435/1.435, allocations: 222.9 MB / 240.9 MB, free: 15.27 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.8394/0.8394, allocations: 135.2 MB / 426.2 MB, free: 4.168 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001868/0.001869, allocations: 55.91 kB / 0.506 GB, free: 8.383 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.4234/0.4253, allocations: 80.93 MB / 0.5851 GB, free: 25.3 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T): time 0.6384/1.064, allocations: 235.9 MB / 0.8155 GB, free: 1.098 MB / 0.5762 GB
Notification: Performance of NFInst.instExpressions: time 0.02178/1.086, allocations: 15.72 MB / 0.8308 GB, free: 0.668 MB / 0.5762 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004405/1.09, allocations: 90.44 kB / 0.8309 GB, free: 0.668 MB / 0.5762 GB
Notification: Performance of NFTyping.typeComponents: time 0.004028/1.094, allocations: 1.317 MB / 0.8322 GB, free: 0.6328 MB / 0.5762 GB
Notification: Performance of NFTyping.typeBindings: time 0.00999/1.104, allocations: 3.405 MB / 0.8355 GB, free: 80 kB / 0.5762 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007405/1.112, allocations: 2.511 MB / 0.838 GB, free: 15.3 MB / 0.5919 GB
Notification: Performance of NFFlatten.flatten: time 0.008014/1.12, allocations: 4.961 MB / 0.8428 GB, free: 13.6 MB / 0.5919 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003399/1.123, allocations: 1.906 MB / 0.8447 GB, free: 12.44 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006392/1.129, allocations: 2.669 MB / 0.8473 GB, free: 11.29 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003744/1.133, allocations: 1.568 MB / 0.8488 GB, free: 10.5 MB / 0.5919 GB
Notification: Performance of NFPackage.collectConstants: time 0.0006009/1.134, allocations: 198.9 kB / 0.849 GB, free: 10.5 MB / 0.5919 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007684/1.141, allocations: 3.426 MB / 0.8523 GB, free: 9.188 MB / 0.5919 GB
Notification: Performance of NFScalarize.scalarize: time 0.001285/1.143, allocations: 0.7399 MB / 0.8531 GB, free: 8.484 MB / 0.5919 GB
Notification: Performance of NFVerifyModel.verify: time 0.00224/1.145, allocations: 1.199 MB / 0.8542 GB, free: 7.301 MB / 0.5919 GB
Notification: Performance of NFConvertDAE.convert: time 0.00914/1.154, allocations: 4.267 MB / 0.8584 GB, free: 3.258 MB / 0.5919 GB
Notification: Performance of FrontEnd - DAE generated: time 6.632e-06/1.154, allocations: 4 kB / 0.8584 GB, free: 3.254 MB / 0.5919 GB
Notification: Performance of FrontEnd: time 2.064e-06/1.154, allocations: 0 / 0.8584 GB, free: 3.254 MB / 0.5919 GB
Notification: Performance of Transformations before backend: time 5.673e-05/1.154, allocations: 0 / 0.8584 GB, free: 3.254 MB / 0.5919 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 333
 * Number of variables: 333
Notification: Performance of Generate backend data structure: time 0.005683/1.16, allocations: 2.819 MB / 0.8611 GB, free: 412 kB / 0.5919 GB
Notification: Performance of prepare preOptimizeDAE: time 4.676e-05/1.16, allocations: 9.109 kB / 0.8612 GB, free: 404 kB / 0.5919 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005009/1.165, allocations: 0.7759 MB / 0.8619 GB, free: 15.62 MB / 0.6075 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0036/1.169, allocations: 1.655 MB / 0.8635 GB, free: 13.99 MB / 0.6075 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004224/1.169, allocations: 164.7 kB / 0.8637 GB, free: 13.83 MB / 0.6075 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002648/1.169, allocations: 143.9 kB / 0.8638 GB, free: 13.69 MB / 0.6075 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003498/1.173, allocations: 1.655 MB / 0.8654 GB, free: 11.96 MB / 0.6075 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.006e-05/1.173, allocations: 0 / 0.8654 GB, free: 11.96 MB / 0.6075 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001431/1.173, allocations: 58.97 kB / 0.8655 GB, free: 11.91 MB / 0.6075 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.181e-05/1.173, allocations: 50.48 kB / 0.8655 GB, free: 11.86 MB / 0.6075 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003514/1.177, allocations: 1.428 MB / 0.8669 GB, free: 10.44 MB / 0.6075 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.01259/1.189, allocations: 7.794 MB / 0.8746 GB, free: 2.211 MB / 0.6075 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00303/1.192, allocations: 1.572 MB / 0.8761 GB, free: 0.5117 MB / 0.6075 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001219/1.194, allocations: 0.5181 MB / 0.8766 GB, free: 15.99 MB / 0.6231 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001461/1.195, allocations: 0.7723 MB / 0.8773 GB, free: 15.08 MB / 0.6231 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001347/1.196, allocations: 0.5514 MB / 0.8779 GB, free: 14.51 MB / 0.6231 GB
Notification: Performance of pre-optimization done (n=83): time 3.867e-06/1.197, allocations: 0 / 0.8779 GB, free: 14.51 MB / 0.6231 GB
Notification: Performance of matching and sorting (n=83): time 0.00465/1.201, allocations: 1.598 MB / 0.8794 GB, free: 12.89 MB / 0.6231 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.824e-05/1.201, allocations: 60.41 kB / 0.8795 GB, free: 12.82 MB / 0.6231 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002539/1.204, allocations: 1.567 MB / 0.881 GB, free: 11.25 MB / 0.6231 GB
Notification: Performance of collectPreVariables (initialization): time 0.00016/1.204, allocations: 49.7 kB / 0.8811 GB, free: 11.2 MB / 0.6231 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009668/1.205, allocations: 0.9239 MB / 0.882 GB, free: 10.27 MB / 0.6231 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003735/1.205, allocations: 294.4 kB / 0.8823 GB, free: 9.984 MB / 0.6231 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003818/1.206, allocations: 210.8 kB / 0.8825 GB, free: 9.77 MB / 0.6231 GB
Notification: Performance of setup shared object (initialization): time 0.0001879/1.206, allocations: 345.9 kB / 0.8828 GB, free: 9.426 MB / 0.6231 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001183/1.207, allocations: 461.8 kB / 0.8832 GB, free: 8.973 MB / 0.6231 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001436/1.209, allocations: 0.7831 MB / 0.884 GB, free: 8.047 MB / 0.6231 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002563/1.211, allocations: 1.228 MB / 0.8852 GB, free: 6.727 MB / 0.6231 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.001e-06/1.211, allocations: 0 / 0.8852 GB, free: 6.727 MB / 0.6231 GB
Notification: Performance of matching and sorting (n=133) (initialization): time 0.003382/1.215, allocations: 1.435 MB / 0.8866 GB, free: 5.324 MB / 0.6231 GB
Notification: Performance of prepare postOptimizeDAE: time 5.206e-05/1.215, allocations: 37.69 kB / 0.8866 GB, free: 5.281 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.954e-05/1.215, allocations: 8 kB / 0.8866 GB, free: 5.273 MB / 0.6231 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.912e-05/1.215, allocations: 23.92 kB / 0.8867 GB, free: 5.25 MB / 0.6231 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001198/1.216, allocations: 270.2 kB / 0.8869 GB, free: 5.004 MB / 0.6231 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.887e-05/1.216, allocations: 22.61 kB / 0.8869 GB, free: 4.984 MB / 0.6231 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001415/1.217, allocations: 131.7 kB / 0.8871 GB, free: 4.852 MB / 0.6231 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001018/1.217, allocations: 52 kB / 0.8871 GB, free: 4.801 MB / 0.6231 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001867/1.219, allocations: 0.807 MB / 0.8879 GB, free: 3.988 MB / 0.6231 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001397/1.221, allocations: 0.7713 MB / 0.8887 GB, free: 3.082 MB / 0.6231 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002449/1.223, allocations: 1.193 MB / 0.8898 GB, free: 1.797 MB / 0.6231 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.582e-06/1.223, allocations: 4 kB / 0.8898 GB, free: 1.793 MB / 0.6231 GB
Notification: Performance of matching and sorting (n=133) (initialization_lambda0): time 0.003263/1.226, allocations: 1.402 MB / 0.8912 GB, free: 436 kB / 0.6231 GB
Notification: Performance of prepare postOptimizeDAE: time 4.622e-05/1.227, allocations: 37.69 kB / 0.8912 GB, free: 392 kB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.403e-05/1.227, allocations: 14.53 kB / 0.8913 GB, free: 380 kB / 0.6231 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.948e-05/1.227, allocations: 12 kB / 0.8913 GB, free: 368 kB / 0.6231 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001158/1.228, allocations: 275.6 kB / 0.8915 GB, free: 108 kB / 0.6231 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.887e-05/1.228, allocations: 14.53 kB / 0.8915 GB, free: 96 kB / 0.6231 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001428/1.229, allocations: 123.9 kB / 0.8917 GB, free: 15.97 MB / 0.6387 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.602e-05/1.229, allocations: 51.98 kB / 0.8917 GB, free: 15.92 MB / 0.6387 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 38
 * Number of states: 0 ()
 * Number of discrete variables: 6 (TSet.count,$PRE.TSet.count,TSet.T_start,$PRE.TSet.T_start,$whenCondition1,conPI.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (131):
 * Single equations (assignments): 129
 * Array equations: 1
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0003677/1.23, allocations: 246.1 kB / 0.8919 GB, free: 15.69 MB / 0.6387 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002946/1.23, allocations: 181.7 kB / 0.8921 GB, free: 15.51 MB / 0.6387 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003059/1.233, allocations: 1.167 MB / 0.8933 GB, free: 14.33 MB / 0.6387 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.049e-05/1.233, allocations: 8 kB / 0.8933 GB, free: 14.32 MB / 0.6387 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.245e-06/1.233, allocations: 0 / 0.8933 GB, free: 14.32 MB / 0.6387 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002492/1.236, allocations: 1.237 MB / 0.8945 GB, free: 13 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.005794/1.241, allocations: 2.91 MB / 0.8973 GB, free: 10.02 MB / 0.6387 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.318e-06/1.241, allocations: 0 / 0.8973 GB, free: 10.02 MB / 0.6387 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009712/1.242, allocations: 195.6 kB / 0.8975 GB, free: 9.84 MB / 0.6387 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0002339/1.243, allocations: 44.83 kB / 0.8975 GB, free: 9.793 MB / 0.6387 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.821e-05/1.243, allocations: 23.98 kB / 0.8976 GB, free: 9.77 MB / 0.6387 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001532/1.244, allocations: 1.535 MB / 0.8991 GB, free: 8.102 MB / 0.6387 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.904e-06/1.244, allocations: 3.938 kB / 0.8991 GB, free: 8.098 MB / 0.6387 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003999/1.248, allocations: 1.781 MB / 0.9008 GB, free: 6.293 MB / 0.6387 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007233/1.249, allocations: 342.6 kB / 0.9011 GB, free: 5.938 MB / 0.6387 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001916/1.249, allocations: 23.98 kB / 0.9012 GB, free: 5.914 MB / 0.6387 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009577/1.25, allocations: 66 kB / 0.9012 GB, free: 5.852 MB / 0.6387 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001517/1.25, allocations: 63.05 kB / 0.9013 GB, free: 5.789 MB / 0.6387 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.482e-05/1.25, allocations: 31.98 kB / 0.9013 GB, free: 5.758 MB / 0.6387 GB
Notification: Performance of sorting global known variables: time 0.00165/1.252, allocations: 1.053 MB / 0.9023 GB, free: 4.715 MB / 0.6387 GB
Notification: Performance of sort global known variables: time 3.71e-07/1.252, allocations: 0 / 0.9023 GB, free: 4.715 MB / 0.6387 GB
Notification: Performance of remove unused functions: time 0.002452/1.255, allocations: 0.9311 MB / 0.9032 GB, free: 3.781 MB / 0.6387 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 8 (vol.dynBal.U,vol.dynBal.m,vol.dynBal.mXi[1],mov.filter.s[1],mov.filter.s[2],conPI.I.y,THeaOut.T,hea.outCon.T)
 * Number of discrete variables: 3 ($whenCondition1,TSet.T_start,TSet.count)
 * Number of discrete states: 1 (TSet.count)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (83):
 * Single equations (assignments): 80
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 2
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(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.00203/1.257, allocations: 0.588 MB / 0.9038 GB, free: 3.383 MB / 0.6387 GB
Notification: Performance of simCode: created initialization part: time 0.004769/1.261, allocations: 1.913 MB / 0.9057 GB, free: 1.52 MB / 0.6387 GB
Notification: Performance of simCode: created event and clocks part: time 5.14e-06/1.261, allocations: 0 / 0.9057 GB, free: 1.52 MB / 0.6387 GB
Notification: Performance of simCode: created simulation system equations: time 0.00137/1.263, allocations: 0.6065 MB / 0.9063 GB, free: 0.918 MB / 0.6387 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003726/1.267, allocations: 0.826 MB / 0.9071 GB, free: 128 kB / 0.6387 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.008095/1.275, allocations: 3.758 MB / 0.9108 GB, free: 12.33 MB / 0.6544 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001318/1.276, allocations: 1.483 MB / 0.9122 GB, free: 10.81 MB / 0.6544 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005832/1.277, allocations: 143.4 kB / 0.9123 GB, free: 10.67 MB / 0.6544 GB
Notification: Performance of SimCode: time 3.176e-06/1.277, allocations: 2.438 kB / 0.9123 GB, free: 10.67 MB / 0.6544 GB
Notification: Performance of Templates: time 0.05388/1.33, allocations: 33.88 MB / 0.9454 GB, free: 8.988 MB / 0.6856 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.sim & ./IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.pipe 2>&1)