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) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001251/0.001251, allocations: 105.3 kB / 18.05 MB, free: 4.289 MB / 14.72 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001244/0.001244, allocations: 193.8 kB / 21.17 MB, free: 1.18 MB / 14.72 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.378/1.378, allocations: 225.6 MB / 249.7 MB, free: 3.27 MB / 190.1 MB
[Timeout remaining time 88]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.8493/0.8493, allocations: 137.4 MB / 442.1 MB, free: 13.85 MB / 350.1 MB
[Timeout remaining time 89]
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=500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T")
translateModel(IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T,tolerance=1e-08,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T") [Timeout 660]
Notification: Performance of FrontEnd - loaded program: time 0.001938/0.001938, allocations: 63.89 kB / 0.6134 GB, free: 1.598 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3882/0.3902, allocations: 85.54 MB / 0.6969 GB, free: 14.94 MB / 0.5137 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/Blocks/Math.mo:543:5-544:48:writable] Warning: Parameter mov.gaiSpe.k has no value, and is fixed during initialization (fixed=true), using available start value (start=1) as default value.
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T): time 0.7123/1.102, allocations: 236.8 MB / 0.9282 GB, free: 1.262 MB / 0.67 GB
Notification: Performance of NFInst.instExpressions: time 0.02108/1.124, allocations: 15.76 MB / 0.9436 GB, free: 0.9062 MB / 0.67 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003974/1.128, allocations: 88.94 kB / 0.9437 GB, free: 0.9062 MB / 0.67 GB
Notification: Performance of NFTyping.typeComponents: time 0.00374/1.131, allocations: 1.366 MB / 0.945 GB, free: 0.8711 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.008625/1.14, allocations: 3.54 MB / 0.9485 GB, free: 0.7305 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005938/1.146, allocations: 2.539 MB / 0.951 GB, free: 0.6289 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.007208/1.153, allocations: 4.99 MB / 0.9558 GB, free: 15.23 MB / 0.6856 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00264/1.156, allocations: 1.572 MB / 0.9574 GB, free: 14.52 MB / 0.6856 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005877/1.162, allocations: 2.792 MB / 0.9601 GB, free: 13.71 MB / 0.6856 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003438/1.165, allocations: 1.648 MB / 0.9617 GB, free: 13.17 MB / 0.6856 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003826/1.165, allocations: 202.8 kB / 0.9619 GB, free: 13.16 MB / 0.6856 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007275/1.173, allocations: 3.585 MB / 0.9654 GB, free: 11.76 MB / 0.6856 GB
Notification: Performance of NFScalarize.scalarize: time 0.001075/1.174, allocations: 0.7743 MB / 0.9661 GB, free: 11.22 MB / 0.6856 GB
Notification: Performance of NFVerifyModel.verify: time 0.001834/1.176, allocations: 1.192 MB / 0.9673 GB, free: 10.43 MB / 0.6856 GB
Notification: Performance of NFConvertDAE.convert: time 0.008469/1.184, allocations: 4.337 MB / 0.9715 GB, free: 7.855 MB / 0.6856 GB
Notification: Performance of FrontEnd - DAE generated: time 4.599e-06/1.184, allocations: 0 / 0.9715 GB, free: 7.855 MB / 0.6856 GB
Notification: Performance of FrontEnd: time 1.633e-06/1.184, allocations: 0 / 0.9715 GB, free: 7.855 MB / 0.6856 GB
Notification: Performance of Transformations before backend: time 6.868e-05/1.184, allocations: 2.531 kB / 0.9716 GB, free: 7.855 MB / 0.6856 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.005716/1.19, allocations: 2.913 MB / 0.9744 GB, free: 5.715 MB / 0.6856 GB
Notification: Performance of prepare preOptimizeDAE: time 4.67e-05/1.19, allocations: 10.02 kB / 0.9744 GB, free: 5.715 MB / 0.6856 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00497/1.195, allocations: 0.7869 MB / 0.9752 GB, free: 5.352 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004119/1.199, allocations: 1.753 MB / 0.9769 GB, free: 3.949 MB / 0.6856 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001205/1.199, allocations: 106.6 kB / 0.977 GB, free: 3.844 MB / 0.6856 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002522/1.199, allocations: 146 kB / 0.9771 GB, free: 3.703 MB / 0.6856 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003435/1.203, allocations: 1.643 MB / 0.9787 GB, free: 1.992 MB / 0.6856 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.624e-05/1.203, allocations: 7.984 kB / 0.9787 GB, free: 1.984 MB / 0.6856 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001413/1.203, allocations: 59.28 kB / 0.9788 GB, free: 1.93 MB / 0.6856 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.293e-05/1.203, allocations: 54.48 kB / 0.9788 GB, free: 1.875 MB / 0.6856 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003224/1.206, allocations: 1.42 MB / 0.9802 GB, free: 472 kB / 0.6856 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.01191/1.218, allocations: 7.745 MB / 0.9878 GB, free: 8.285 MB / 0.7012 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002656/1.221, allocations: 1.502 MB / 0.9893 GB, free: 6.656 MB / 0.7012 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001081/1.222, allocations: 490.5 kB / 0.9897 GB, free: 6.176 MB / 0.7012 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001356/1.223, allocations: 0.7723 MB / 0.9905 GB, free: 5.262 MB / 0.7012 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001244/1.224, allocations: 0.5591 MB / 0.991 GB, free: 4.691 MB / 0.7012 GB
Notification: Performance of pre-optimization done (n=83): time 2.836e-06/1.224, allocations: 0 / 0.991 GB, free: 4.691 MB / 0.7012 GB
Notification: Performance of matching and sorting (n=83): time 0.004365/1.229, allocations: 1.574 MB / 0.9926 GB, free: 3.094 MB / 0.7012 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.675e-05/1.229, allocations: 62.94 kB / 0.9926 GB, free: 3.023 MB / 0.7012 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002432/1.231, allocations: 1.563 MB / 0.9942 GB, free: 1.461 MB / 0.7012 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001378/1.231, allocations: 53.7 kB / 0.9942 GB, free: 1.402 MB / 0.7012 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009307/1.232, allocations: 0.94 MB / 0.9951 GB, free: 468 kB / 0.7012 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003357/1.233, allocations: 308 kB / 0.9954 GB, free: 164 kB / 0.7012 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003736/1.233, allocations: 215.6 kB / 0.9956 GB, free: 15.95 MB / 0.7169 GB
Notification: Performance of setup shared object (initialization): time 0.0001923/1.233, allocations: 349.9 kB / 0.996 GB, free: 15.6 MB / 0.7169 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001047/1.234, allocations: 453.8 kB / 0.9964 GB, free: 15.15 MB / 0.7169 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001348/1.236, allocations: 0.7831 MB / 0.9972 GB, free: 14.22 MB / 0.7169 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002279/1.238, allocations: 1.217 MB / 0.9983 GB, free: 12.91 MB / 0.7169 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.07e-06/1.238, allocations: 0 / 0.9983 GB, free: 12.91 MB / 0.7169 GB
Notification: Performance of matching and sorting (n=133) (initialization): time 0.00319/1.241, allocations: 1.433 MB / 0.9997 GB, free: 11.52 MB / 0.7169 GB
Notification: Performance of prepare postOptimizeDAE: time 4.987e-05/1.241, allocations: 37.7 kB / 0.9998 GB, free: 11.48 MB / 0.7169 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.519e-05/1.241, allocations: 8 kB / 0.9998 GB, free: 11.47 MB / 0.7169 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.1e-05/1.241, allocations: 12 kB / 0.9998 GB, free: 11.46 MB / 0.7169 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001142/1.242, allocations: 269.7 kB / 1 GB, free: 11.21 MB / 0.7169 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.26e-05/1.242, allocations: 22.64 kB / 1 GB, free: 11.19 MB / 0.7169 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001491/1.244, allocations: 138.7 kB / 1 GB, free: 11.06 MB / 0.7169 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.937e-05/1.244, allocations: 55.98 kB / 1 GB, free: 11 MB / 0.7169 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001741/1.246, allocations: 0.8069 MB / 1.001 GB, free: 10.2 MB / 0.7169 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00128/1.247, allocations: 0.7635 MB / 1.002 GB, free: 9.285 MB / 0.7169 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.002236/1.249, allocations: 1.185 MB / 1.003 GB, free: 8.012 MB / 0.7169 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.248e-06/1.249, allocations: 0 / 1.003 GB, free: 8.012 MB / 0.7169 GB
Notification: Performance of matching and sorting (n=133) (initialization_lambda0): time 0.00308/1.252, allocations: 1.401 MB / 1.004 GB, free: 6.645 MB / 0.7169 GB
Notification: Performance of prepare postOptimizeDAE: time 3.939e-05/1.252, allocations: 37.7 kB / 1.004 GB, free: 6.602 MB / 0.7169 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.35e-05/1.252, allocations: 8 kB / 1.004 GB, free: 6.594 MB / 0.7169 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.492e-05/1.252, allocations: 12 kB / 1.004 GB, free: 6.582 MB / 0.7169 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001105/1.254, allocations: 269.9 kB / 1.005 GB, free: 6.336 MB / 0.7169 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.865e-05/1.254, allocations: 18.53 kB / 1.005 GB, free: 6.32 MB / 0.7169 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001382/1.255, allocations: 124 kB / 1.005 GB, free: 6.199 MB / 0.7169 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 9.302e-05/1.255, allocations: 51.98 kB / 1.005 GB, free: 6.148 MB / 0.7169 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.000373/1.255, allocations: 251.4 kB / 1.005 GB, free: 5.91 MB / 0.7169 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002897/1.256, allocations: 190.8 kB / 1.005 GB, free: 5.723 MB / 0.7169 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002812/1.258, allocations: 1.154 MB / 1.006 GB, free: 4.559 MB / 0.7169 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002346/1.261, allocations: 1.237 MB / 1.008 GB, free: 3.234 MB / 0.7169 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.282e-06/1.261, allocations: 3.984 kB / 1.008 GB, free: 3.23 MB / 0.7169 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007564/1.262, allocations: 351.6 kB / 1.008 GB, free: 2.867 MB / 0.7169 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002075/1.262, allocations: 19.98 kB / 1.008 GB, free: 2.848 MB / 0.7169 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009162/1.263, allocations: 55.95 kB / 1.008 GB, free: 2.793 MB / 0.7169 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001601/1.263, allocations: 64.84 kB / 1.008 GB, free: 2.73 MB / 0.7169 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.003132/1.266, allocations: 1.185 MB / 1.009 GB, free: 1.547 MB / 0.7169 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.003497/1.269, allocations: 1.815 MB / 1.011 GB, free: 15.71 MB / 0.7325 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001009/1.271, allocations: 450.2 kB / 1.011 GB, free: 15.28 MB / 0.7325 GB
Notification: Performance of sorting global known variables: time 0.001666/1.272, allocations: 1.053 MB / 1.012 GB, free: 14.23 MB / 0.7325 GB
Notification: Performance of Backend: time 2.71e-07/1.272, allocations: 0 / 1.012 GB, free: 14.23 MB / 0.7325 GB
Notification: Performance of simCode: created initialization part: time 0.00712/1.279, allocations: 2.391 MB / 1.015 GB, free: 12.11 MB / 0.7325 GB
Notification: Performance of SimCode: time 0.007823/1.287, allocations: 4.353 MB / 1.019 GB, free: 7.723 MB / 0.7325 GB
Notification: Performance of Templates: time 0.07472/1.362, allocations: 42.99 MB / 1.061 GB, free: 13.23 MB / 0.7794 GB
[Timeout remaining time 659]
make -j1 -f IDEAS_dev_IDEAS.Fluid.HeatExchangers.Examples.AirHeater_T.makefile [Timeout 660]
(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) [Timeout 480]