Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.0009701/0.0009701, allocations: 80.94 kB / 19.94 MB, free: 4.543 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.0009759/0.0009759, allocations: 177.4 kB / 23.36 MB, free: 1.129 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 0.9804/0.9804, allocations: 177.2 MB / 203.8 MB, free: 5.477 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:108:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Examples/PPD12/Structure.mo:531:21-545:116:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.order:0:0-0:0:readonly] Warning: Found duplicate names in package.order file: A, B, C.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Media/Refrigerants/R410A/package.mo:2:1-70:10:writable] Warning: C was referenced in the package.order file, but was not found in package.mo, C/package.mo or C.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo): time 0.8522/0.8522, allocations: 154.6 MB / 414.8 MB, free: 480 kB / 378.7 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/Cryptographics/BaseClasses/sha.mo:2:1-19:8:writable] Warning: External function annotation should occur on the external-clause, not on the function.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/Utilities/File/sha2.mo:2:1-9:9:writable] Warning: External function annotation should occur on the external-clause, not on the function.
"
[Timeout remaining time 179]
Using package IDEAS with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 4.0.0/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(IDEAS.Fluid.Taps.Examples.DHW_example,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example")
translateModel(IDEAS.Fluid.Taps.Examples.DHW_example,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.273e-06/1.273e-06, allocations: 0 / 480 MB, free: 6.859 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.041e-05/2.168e-05, allocations: 5.156 kB / 480 MB, free: 6.855 MB / 442.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Taps.Examples.DHW_example): time 0.01074/0.01076, allocations: 12.8 MB / 492.8 MB, free: 10.08 MB / 458.7 MB
Notification: Performance of NFInst.instExpressions: time 0.009111/0.01987, allocations: 6.132 MB / 499 MB, free: 3.988 MB / 458.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001477/0.02135, allocations: 67.44 kB / 499 MB, free: 3.922 MB / 458.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008248/0.02218, allocations: 345.2 kB / 499.4 MB, free: 3.582 MB / 458.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.002303/0.02448, allocations: 1.09 MB / 0.4887 GB, free: 2.496 MB / 458.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001991/0.02647, allocations: 1.199 MB / 0.4899 GB, free: 1.309 MB / 458.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002676/0.02915, allocations: 2.685 MB / 0.4925 GB, free: 14.62 MB / 474.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001464/0.03061, allocations: 0.901 MB / 0.4934 GB, free: 13.71 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001736/0.03235, allocations: 1.317 MB / 0.4947 GB, free: 12.4 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.000817/0.03316, allocations: 0.6677 MB / 0.4953 GB, free: 11.73 MB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001416/0.0333, allocations: 108 kB / 0.4954 GB, free: 11.62 MB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007165/0.03402, allocations: 419.5 kB / 0.4958 GB, free: 11.21 MB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001886/0.03421, allocations: 290.8 kB / 0.4961 GB, free: 10.93 MB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003323/0.03454, allocations: 431.8 kB / 0.4965 GB, free: 10.51 MB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.001233/0.03577, allocations: 1.133 MB / 0.4976 GB, free: 9.371 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.527e-06/0.03578, allocations: 1.594 kB / 0.4976 GB, free: 9.371 MB / 474.7 MB
Notification: Performance of FrontEnd: time 9.62e-07/0.03578, allocations: 0 / 0.4976 GB, free: 9.371 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 1.679e-05/0.0358, allocations: 0 / 0.4976 GB, free: 9.371 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 196
 * Number of variables: 196
Notification: Performance of Generate backend data structure: time 0.001414/0.03721, allocations: 1.403 MB / 0.499 GB, free: 7.934 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.146e-05/0.03724, allocations: 13.06 kB / 0.499 GB, free: 7.922 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0006683/0.03791, allocations: 284.3 kB / 0.4993 GB, free: 7.648 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0008242/0.03873, allocations: 0.6523 MB / 0.4999 GB, free: 6.973 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.803e-05/0.03877, allocations: 62.38 kB / 0.5 GB, free: 6.91 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 7.551e-05/0.03885, allocations: 79.94 kB / 0.5001 GB, free: 6.832 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0008024/0.03965, allocations: 0.8453 MB / 0.5009 GB, free: 5.902 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.666e-05/0.03967, allocations: 1.844 kB / 0.5009 GB, free: 5.902 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.692e-05/0.03971, allocations: 37.17 kB / 0.5009 GB, free: 5.867 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.405e-05/0.03973, allocations: 20 kB / 0.5009 GB, free: 5.848 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0003758/0.0401, allocations: 270.2 kB / 0.5012 GB, free: 5.582 MB / 474.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.002332/0.04244, allocations: 2.594 MB / 0.5037 GB, free: 2.926 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0003933/0.04283, allocations: 312.8 kB / 0.504 GB, free: 2.617 MB / 474.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001186/0.04295, allocations: 93.33 kB / 0.5041 GB, free: 2.523 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002089/0.04316, allocations: 74.25 kB / 0.5042 GB, free: 2.453 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.000301/0.04346, allocations: 347.2 kB / 0.5045 GB, free: 2.074 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=47): time 2.154e-06/0.04346, allocations: 0.5 kB / 0.5045 GB, free: 2.074 MB / 474.7 MB
Notification: Performance of matching and sorting (n=47): time 0.0004752/0.04393, allocations: 323.7 kB / 0.5048 GB, free: 1.754 MB / 474.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.471e-05/0.04398, allocations: 116 kB / 0.5049 GB, free: 1.617 MB / 474.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0004499/0.04443, allocations: 0.6225 MB / 0.5056 GB, free: 0.9844 MB / 474.7 MB
Notification: Performance of collectPreVariables (initialization): time 3.937e-05/0.04447, allocations: 33.7 kB / 0.5056 GB, free: 0.9453 MB / 474.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0001803/0.04465, allocations: 411.6 kB / 0.506 GB, free: 0.5352 MB / 474.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001001/0.04475, allocations: 178.5 kB / 0.5061 GB, free: 368 kB / 474.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000145/0.04489, allocations: 134.1 kB / 0.5063 GB, free: 224 kB / 474.7 MB
Notification: Performance of setup shared object (initialization): time 8.885e-05/0.04498, allocations: 329.9 kB / 0.5066 GB, free: 15.89 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001824/0.04517, allocations: 127.6 kB / 0.5067 GB, free: 15.76 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.000284/0.04545, allocations: 438.5 kB / 0.5071 GB, free: 15.22 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0004301/0.04588, allocations: 435.4 kB / 0.5075 GB, free: 14.72 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.689e-06/0.04588, allocations: 4 kB / 0.5075 GB, free: 14.72 MB / 490.7 MB
Notification: Performance of matching and sorting (n=76) (initialization): time 0.0005364/0.04642, allocations: 478.7 kB / 0.508 GB, free: 14.24 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 2.969e-05/0.04645, allocations: 34.64 kB / 0.508 GB, free: 14.2 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.387e-06/0.04646, allocations: 8 kB / 0.508 GB, free: 14.19 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 1.432e-05/0.04647, allocations: 8 kB / 0.5081 GB, free: 14.18 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005114/0.04699, allocations: 202.2 kB / 0.5082 GB, free: 13.99 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 8.677e-06/0.04699, allocations: 12 kB / 0.5083 GB, free: 13.98 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0002848/0.04728, allocations: 84.27 kB / 0.5083 GB, free: 13.89 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.754e-05/0.04733, allocations: 35.19 kB / 0.5084 GB, free: 13.86 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 24
 * Number of states: 0 ()
 * Number of discrete variables: 5 (mDHW60C.count,$PRE.mDHW60C.count,mDHW60C.T_start,$PRE.mDHW60C.T_start,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (75):
 * Single equations (assignments): 74
 * Array equations: 0
 * 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.0001714/0.0475, allocations: 160.4 kB / 0.5085 GB, free: 13.7 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 7.449e-05/0.04757, allocations: 60.73 kB / 0.5086 GB, free: 13.64 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006617/0.04823, allocations: 0.5011 MB / 0.5091 GB, free: 13.14 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.935e-06/0.04824, allocations: 4 kB / 0.5091 GB, free: 13.13 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.774e-06/0.04824, allocations: 2.438 kB / 0.5091 GB, free: 13.13 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.462e-06/0.04825, allocations: 3.984 kB / 0.5091 GB, free: 13.13 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001142/0.04939, allocations: 1.143 MB / 0.5102 GB, free: 11.93 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.679e-06/0.0494, allocations: 2.188 kB / 0.5102 GB, free: 11.93 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000404/0.0498, allocations: 143.8 kB / 0.5103 GB, free: 11.79 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 5.149e-06/0.04981, allocations: 5.031 kB / 0.5103 GB, free: 11.79 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 3.095e-05/0.04984, allocations: 19.98 kB / 0.5104 GB, free: 11.77 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 5.239e-06/0.04984, allocations: 7.938 kB / 0.5104 GB, free: 11.76 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.096e-06/0.04985, allocations: 8.875 kB / 0.5104 GB, free: 11.75 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0006021/0.05045, allocations: 0.6779 MB / 0.511 GB, free: 11.04 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001705/0.05062, allocations: 115.2 kB / 0.5112 GB, free: 10.93 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.245e-05/0.05068, allocations: 16 kB / 0.5112 GB, free: 10.91 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002663/0.05095, allocations: 47.89 kB / 0.5112 GB, free: 10.87 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.756e-05/0.05101, allocations: 43.27 kB / 0.5113 GB, free: 10.83 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.15e-05/0.05105, allocations: 15.98 kB / 0.5113 GB, free: 10.81 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.0002463/0.05129, allocations: 311.3 kB / 0.5116 GB, free: 10.51 MB / 490.7 MB
Notification: Performance of sort global known variables: time 7e-08/0.05129, allocations: 0 / 0.5116 GB, free: 10.51 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.0006036/0.0519, allocations: 335.1 kB / 0.5119 GB, free: 10.18 MB / 490.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 2 (dHW_RealInput.THot.T,dHW_ProfileReader.THot.T)
 * Number of discrete variables: 3 ($whenCondition1,mDHW60C.T_start,mDHW60C.count)
 * Number of discrete states: 1 (mDHW60C.count)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (53):
 * Single equations (assignments): 48
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 3
 * When equations: 2
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.001279/0.05317, allocations: 0.6571 MB / 0.5125 GB, free: 9.848 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.0004889/0.05366, allocations: 401.5 kB / 0.5129 GB, free: 9.441 MB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 1.274e-05/0.05368, allocations: 12 kB / 0.5129 GB, free: 9.43 MB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.0001779/0.05385, allocations: 149.9 kB / 0.5131 GB, free: 9.281 MB / 490.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0008365/0.05469, allocations: 377.8 kB / 0.5134 GB, free: 8.957 MB / 490.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003042/0.05773, allocations: 1.825 MB / 0.5152 GB, free: 7.102 MB / 490.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0002458/0.05798, allocations: 179.5 kB / 0.5154 GB, free: 6.906 MB / 490.7 MB
Notification: Performance of simCode: alias equations: time 0.0004197/0.0584, allocations: 162.8 kB / 0.5155 GB, free: 6.77 MB / 490.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005499/0.05895, allocations: 316.9 kB / 0.5158 GB, free: 6.461 MB / 490.7 MB
Notification: Performance of SimCode: time 9.01e-07/0.05895, allocations: 4 kB / 0.5158 GB, free: 6.457 MB / 490.7 MB
Notification: Performance of Templates: time 0.02127/0.08022, allocations: 17.97 MB / 0.5334 GB, free: 4.969 MB / 0.4948 GB
"
[Timeout remaining time 660]
make -j1 -f IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.makefile [Timeout 660]
(rm -f IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.pipe ; mkfifo IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.pipe ; head -c 1048576 < IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.pipe >> ../files/IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.sim & ./IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example -abortSlowSimulation -alarm=240 -s gbode -gbm=radauIIA3 -lv LOG_STATS > IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 4.0535743101499975]