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.0007951/0.0007951, allocations: 84.38 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.0008147/0.0008148, 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 4.0.0+maint.om/package.mo): time 1.414/1.414, allocations: 223.3 MB / 240 MB, free: 5.414 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.9957/0.9957, allocations: 135.3 MB / 426.4 MB, free: 14.11 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003681/0.0003681, allocations: 8 kB / 0.507 GB, free: 5.16 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1657/0.1661, allocations: 82.31 MB / 0.5874 GB, free: 3.027 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T): time 0.5693/0.7354, allocations: 215.1 MB / 0.7974 GB, free: 5.883 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.02488/0.7604, allocations: 14.71 MB / 0.8118 GB, free: 7.137 MB / 0.6231 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00356/0.764, allocations: 79.5 kB / 0.8118 GB, free: 7.059 MB / 0.6231 GB
Notification: Performance of NFTyping.typeComponents: time 0.003821/0.7678, allocations: 0.8672 MB / 0.8127 GB, free: 6.191 MB / 0.6231 GB
Notification: Performance of NFTyping.typeBindings: time 0.008702/0.7765, allocations: 2.518 MB / 0.8152 GB, free: 3.664 MB / 0.6231 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006231/0.7828, allocations: 1.856 MB / 0.817 GB, free: 1.828 MB / 0.6231 GB
Notification: Performance of NFFlatten.flatten: time 0.009071/0.7919, allocations: 5.565 MB / 0.8224 GB, free: 12.25 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003485/0.7954, allocations: 1.386 MB / 0.8238 GB, free: 10.84 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.006087/0.8015, allocations: 2.128 MB / 0.8258 GB, free: 8.703 MB / 0.6387 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003037/0.8046, allocations: 1.494 MB / 0.8273 GB, free: 7.203 MB / 0.6387 GB
Notification: Performance of NFPackage.collectConstants: time 0.0005925/0.8052, allocations: 84 kB / 0.8274 GB, free: 7.121 MB / 0.6387 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005525/0.8107, allocations: 2.097 MB / 0.8294 GB, free: 5.02 MB / 0.6387 GB
Notification: Performance of NFScalarize.scalarize: time 0.001139/0.8119, allocations: 0.6228 MB / 0.83 GB, free: 4.395 MB / 0.6387 GB
Notification: Performance of NFVerifyModel.verify: time 0.001089/0.813, allocations: 460.5 kB / 0.8305 GB, free: 3.938 MB / 0.6387 GB
Notification: Performance of NFConvertDAE.convert: time 0.004887/0.8179, allocations: 3.004 MB / 0.8334 GB, free: 0.9297 MB / 0.6387 GB
Notification: Performance of FrontEnd - DAE generated: time 4.969e-06/0.8179, allocations: 3.938 kB / 0.8334 GB, free: 0.9258 MB / 0.6387 GB
Notification: Performance of FrontEnd: time 1.914e-06/0.8179, allocations: 0 / 0.8334 GB, free: 0.9258 MB / 0.6387 GB
Notification: Performance of Transformations before backend: time 0.0001193/0.818, allocations: 0 / 0.8334 GB, free: 0.9258 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: 333
 * Number of variables: 333
Notification: Performance of Generate backend data structure: time 0.005319/0.8234, allocations: 2.46 MB / 0.8358 GB, free: 14.4 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 3.947e-05/0.8234, allocations: 8.031 kB / 0.8358 GB, free: 14.39 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004891/0.8283, allocations: 0.8127 MB / 0.8366 GB, free: 13.58 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003683/0.832, allocations: 1.584 MB / 0.8382 GB, free: 11.95 MB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003856/0.8324, allocations: 174.5 kB / 0.8383 GB, free: 11.77 MB / 0.6544 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003552/0.8328, allocations: 140 kB / 0.8385 GB, free: 11.64 MB / 0.6544 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003648/0.8365, allocations: 1.676 MB / 0.8401 GB, free: 9.891 MB / 0.6544 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.431e-05/0.8365, allocations: 0 / 0.8401 GB, free: 9.891 MB / 0.6544 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001578/0.8367, allocations: 56 kB / 0.8401 GB, free: 9.836 MB / 0.6544 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.219e-05/0.8367, allocations: 58.45 kB / 0.8402 GB, free: 9.777 MB / 0.6544 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003479/0.8402, allocations: 1.493 MB / 0.8417 GB, free: 8.277 MB / 0.6544 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.01138/0.8516, allocations: 7.62 MB / 0.8491 GB, free: 104 kB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002778/0.8544, allocations: 1.666 MB / 0.8507 GB, free: 14.3 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00113/0.8556, allocations: 0.5354 MB / 0.8513 GB, free: 13.77 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001324/0.8569, allocations: 0.8107 MB / 0.852 GB, free: 12.81 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001293/0.8583, allocations: 0.5868 MB / 0.8526 GB, free: 12.21 MB / 0.67 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0008765/0.8592, allocations: 123.2 kB / 0.8527 GB, free: 12.09 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=85): time 4.378e-06/0.8592, allocations: 0 / 0.8527 GB, free: 12.09 MB / 0.67 GB
Notification: Performance of matching and sorting (n=85): time 0.004703/0.8639, allocations: 1.675 MB / 0.8544 GB, free: 10.39 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.078e-05/0.864, allocations: 64.41 kB / 0.8544 GB, free: 10.32 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002648/0.8666, allocations: 1.591 MB / 0.856 GB, free: 8.684 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001108/0.8667, allocations: 49.7 kB / 0.856 GB, free: 8.629 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008677/0.8676, allocations: 0.9164 MB / 0.8569 GB, free: 7.703 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003119/0.8679, allocations: 295.7 kB / 0.8572 GB, free: 7.41 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 6.727e-05/0.868, allocations: 20 kB / 0.8572 GB, free: 7.391 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001582/0.8682, allocations: 353.9 kB / 0.8576 GB, free: 7.039 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001072/0.8693, allocations: 450.3 kB / 0.858 GB, free: 6.602 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001292/0.8706, allocations: 0.7976 MB / 0.8588 GB, free: 5.656 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001164/0.8717, allocations: 0.559 MB / 0.8593 GB, free: 5.09 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.686e-06/0.8717, allocations: 0 / 0.8593 GB, free: 5.09 MB / 0.67 GB
Notification: Performance of matching and sorting (n=134) (initialization): time 0.003057/0.8748, allocations: 1.442 MB / 0.8607 GB, free: 3.633 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002593/0.8751, allocations: 373.3 kB / 0.8611 GB, free: 3.148 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.839e-05/0.8751, allocations: 9.25 kB / 0.8611 GB, free: 3.141 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.298e-05/0.8752, allocations: 16 kB / 0.8611 GB, free: 3.125 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001175/0.8763, allocations: 283.6 kB / 0.8614 GB, free: 2.848 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.9e-05/0.8764, allocations: 19.92 kB / 0.8614 GB, free: 2.828 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001661/0.878, allocations: 166.4 kB / 0.8616 GB, free: 2.668 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001062/0.8782, allocations: 55.98 kB / 0.8616 GB, free: 2.613 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 9.268e-06/0.8782, allocations: 4 kB / 0.8616 GB, free: 2.609 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002589/0.8808, allocations: 1.258 MB / 0.8628 GB, free: 1.34 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.517e-05/0.8808, allocations: 10.19 kB / 0.8629 GB, free: 1.332 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.724e-05/0.8808, allocations: 16 kB / 0.8629 GB, free: 1.316 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001126/0.882, allocations: 280.4 kB / 0.8631 GB, free: 1.043 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.307e-05/0.882, allocations: 23.88 kB / 0.8632 GB, free: 1.02 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001557/0.8836, allocations: 164.5 kB / 0.8633 GB, free: 0.8633 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.836e-05/0.8837, allocations: 47.98 kB / 0.8634 GB, free: 0.8164 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 37
 * Number of states: 0 ()
 * Number of discrete variables: 6 ($whenCondition1,$PRE.TSet.T_start,TSet.T_start,$PRE.TSet.count,TSet.count,conPI.I.trigger_internal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (132):
 * Single equations (assignments): 130
 * Array equations: 1
 * Algorithm blocks: 1
 * 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.000936/0.8846, allocations: 0.5397 MB / 0.8639 GB, free: 272 kB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003406/0.885, allocations: 177.8 kB / 0.8641 GB, free: 92 kB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.3436/1.229, allocations: 1.215 MB / 0.8652 GB, free: 216.5 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.098e-05/1.229, allocations: 9.938 kB / 0.8653 GB, free: 216.5 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.157e-05/1.229, allocations: 9.812 kB / 0.8653 GB, free: 216.5 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002044/1.231, allocations: 1.276 MB / 0.8665 GB, free: 216.1 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.005919/1.237, allocations: 2.985 MB / 0.8694 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.282e-06/1.237, allocations: 0 / 0.8694 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009387/1.238, allocations: 205.3 kB / 0.8696 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0002406/1.238, allocations: 52.64 kB / 0.8697 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.215e-05/1.238, allocations: 23.61 kB / 0.8697 GB, free: 215.5 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001053/1.239, allocations: 1.423 MB / 0.8711 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.005e-06/1.239, allocations: 2.344 kB / 0.8711 GB, free: 214.3 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003479/1.242, allocations: 1.851 MB / 0.8729 GB, free: 214 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007892/1.243, allocations: 349.8 kB / 0.8732 GB, free: 213.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001917/1.243, allocations: 17.66 kB / 0.8732 GB, free: 213.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001179/1.245, allocations: 159.2 kB / 0.8734 GB, free: 213.9 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001331/1.245, allocations: 73.44 kB / 0.8735 GB, free: 213.9 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.622e-05/1.245, allocations: 30.88 kB / 0.8735 GB, free: 213.9 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.001555/1.246, allocations: 1.073 MB / 0.8745 GB, free: 213.4 MB / 0.67 GB
Notification: Performance of sort global known variables: time 1.8e-07/1.246, allocations: 3.219 kB / 0.8745 GB, free: 213.4 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.002662/1.249, allocations: 0.9803 MB / 0.8755 GB, free: 213.3 MB / 0.67 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 (85):
 * Single equations (assignments): 82
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 2
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(1,100.0%) 2}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.001501/1.251, allocations: 0.6039 MB / 0.8761 GB, free: 213.2 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.003439/1.254, allocations: 1.838 MB / 0.8779 GB, free: 212.5 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 4.358e-06/1.254, allocations: 0 / 0.8779 GB, free: 212.5 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.001236/1.255, allocations: 0.6457 MB / 0.8785 GB, free: 212.3 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003459/1.259, allocations: 0.8038 MB / 0.8793 GB, free: 212.1 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006808/1.266, allocations: 4.417 MB / 0.8836 GB, free: 209.8 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007982/1.266, allocations: 1.486 MB / 0.8851 GB, free: 209 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.001947/1.268, allocations: 445.9 kB / 0.8855 GB, free: 208.9 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003689/1.269, allocations: 131.9 kB / 0.8856 GB, free: 208.9 MB / 0.67 GB
Notification: Performance of SimCode: time 6.72e-07/1.269, allocations: 0 / 0.8856 GB, free: 208.9 MB / 0.67 GB
Notification: Performance of Templates: time 0.06424/1.333, allocations: 30.43 MB / 0.9153 GB, free: 203.2 MB / 0.67 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)