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.0009731/0.0009731, allocations: 80.88 kB / 19.94 MB, free: 4.547 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.001042/0.001042, allocations: 181 kB / 23.36 MB, free: 1.133 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.9076/0.9076, allocations: 177.1 MB / 203.8 MB, free: 5.465 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.7942/0.7942, allocations: 154.6 MB / 414.8 MB, free: 0.5078 MB / 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.473e-06/1.473e-06, allocations: 0 / 480 MB, free: 6.91 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.38e-05/1.527e-05, allocations: 2.312 kB / 480 MB, free: 6.906 MB / 442.7 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Taps.Examples.DHW_example): time 0.01001/0.01002, allocations: 12.75 MB / 492.7 MB, free: 10.17 MB / 458.7 MB
Notification: Performance of NFInst.instExpressions: time 0.008014/0.01804, allocations: 6.132 MB / 498.9 MB, free: 4.078 MB / 458.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009564/0.019, allocations: 72.72 kB / 498.9 MB, free: 4.008 MB / 458.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.0009265/0.01992, allocations: 349.1 kB / 499.3 MB, free: 3.664 MB / 458.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.001739/0.02166, allocations: 1.099 MB / 0.4886 GB, free: 2.57 MB / 458.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001718/0.02338, allocations: 1.202 MB / 0.4898 GB, free: 1.379 MB / 458.7 MB
Notification: Performance of NFFlatten.flatten: time 0.002167/0.02555, allocations: 2.681 MB / 0.4924 GB, free: 14.69 MB / 474.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008202/0.02637, allocations: 0.8808 MB / 0.4933 GB, free: 13.78 MB / 474.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001641/0.02801, allocations: 1.346 MB / 0.4946 GB, free: 12.45 MB / 474.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0005948/0.0286, allocations: 0.6541 MB / 0.4953 GB, free: 11.79 MB / 474.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001024/0.0287, allocations: 109.4 kB / 0.4954 GB, free: 11.69 MB / 474.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0005402/0.02924, allocations: 415.4 kB / 0.4958 GB, free: 11.28 MB / 474.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001852/0.02943, allocations: 286.8 kB / 0.496 GB, free: 11 MB / 474.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.0002962/0.02973, allocations: 423 kB / 0.4964 GB, free: 10.59 MB / 474.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.0009772/0.0307, allocations: 1.138 MB / 0.4975 GB, free: 9.445 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.596e-06/0.03071, allocations: 3.984 kB / 0.4975 GB, free: 9.441 MB / 474.7 MB
Notification: Performance of FrontEnd: time 8.51e-07/0.03071, allocations: 0.9062 kB / 0.4975 GB, free: 9.441 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 7.404e-06/0.03071, allocations: 0 / 0.4975 GB, free: 9.441 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.001272/0.03199, allocations: 1.4 MB / 0.4989 GB, free: 8.008 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 2.977e-05/0.03202, allocations: 8.031 kB / 0.4989 GB, free: 8 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0006169/0.03263, allocations: 296 kB / 0.4992 GB, free: 7.715 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0007526/0.03339, allocations: 0.6447 MB / 0.4998 GB, free: 7.047 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.633e-05/0.03342, allocations: 60.16 kB / 0.4999 GB, free: 6.988 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 8.15e-05/0.0335, allocations: 91.92 kB / 0.5 GB, free: 6.898 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0007315/0.03424, allocations: 0.8389 MB / 0.5008 GB, free: 5.977 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.713e-05/0.03425, allocations: 4 kB / 0.5008 GB, free: 5.973 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.601e-05/0.0343, allocations: 32 kB / 0.5008 GB, free: 5.941 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.317e-05/0.03431, allocations: 22.48 kB / 0.5009 GB, free: 5.922 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0003386/0.03465, allocations: 273.9 kB / 0.5011 GB, free: 5.652 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.002259/0.03691, allocations: 2.582 MB / 0.5036 GB, free: 3.004 MB / 474.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0003661/0.03728, allocations: 312.6 kB / 0.5039 GB, free: 2.695 MB / 474.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001108/0.03739, allocations: 93.89 kB / 0.504 GB, free: 2.602 MB / 474.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001977/0.03758, allocations: 81.75 kB / 0.5041 GB, free: 2.523 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0002787/0.03786, allocations: 333.8 kB / 0.5044 GB, free: 2.156 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=47): time 1.553e-06/0.03786, allocations: 0 / 0.5044 GB, free: 2.156 MB / 474.7 MB
Notification: Performance of matching and sorting (n=47): time 0.0004482/0.03831, allocations: 329.5 kB / 0.5047 GB, free: 1.832 MB / 474.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001714/0.03848, allocations: 114.8 kB / 0.5048 GB, free: 1.695 MB / 474.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0004272/0.03891, allocations: 0.6224 MB / 0.5055 GB, free: 1.062 MB / 474.7 MB
Notification: Performance of collectPreVariables (initialization): time 3.973e-05/0.03895, allocations: 38.7 kB / 0.5055 GB, free: 1.02 MB / 474.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0001715/0.03912, allocations: 409.8 kB / 0.5059 GB, free: 0.6094 MB / 474.7 MB
Notification: Performance of collectInitialBindings (initialization): time 9.264e-05/0.03921, allocations: 171.3 kB / 0.506 GB, free: 452 kB / 474.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001368/0.03935, allocations: 138.2 kB / 0.5062 GB, free: 304 kB / 474.7 MB
Notification: Performance of setup shared object (initialization): time 7.972e-05/0.03943, allocations: 333.8 kB / 0.5065 GB, free: 15.96 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001711/0.0396, allocations: 123.1 kB / 0.5066 GB, free: 15.84 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002738/0.03988, allocations: 446.5 kB / 0.507 GB, free: 15.29 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0003937/0.04027, allocations: 434.8 kB / 0.5075 GB, free: 14.79 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.097e-06/0.04027, allocations: 4 kB / 0.5075 GB, free: 14.79 MB / 490.7 MB
Notification: Performance of matching and sorting (n=76) (initialization): time 0.0004992/0.04077, allocations: 480.7 kB / 0.5079 GB, free: 14.31 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.111e-05/0.0408, allocations: 37.7 kB / 0.5079 GB, free: 14.27 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.911e-06/0.04081, allocations: 6.562 kB / 0.508 GB, free: 14.26 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 1.45e-05/0.04082, allocations: 12 kB / 0.508 GB, free: 14.25 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0004124/0.04124, allocations: 171.7 kB / 0.5081 GB, free: 14.08 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 9.578e-06/0.04125, allocations: 19.92 kB / 0.5081 GB, free: 14.06 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0005204/0.04177, allocations: 81.64 kB / 0.5082 GB, free: 13.98 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.435e-05/0.04181, allocations: 31.98 kB / 0.5083 GB, free: 13.95 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.0001623/0.04197, allocations: 154 kB / 0.5084 GB, free: 13.79 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 7.621e-05/0.04205, allocations: 67.92 kB / 0.5085 GB, free: 13.73 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006201/0.04267, allocations: 0.5025 MB / 0.509 GB, free: 13.22 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.564e-06/0.04268, allocations: 9.484 kB / 0.509 GB, free: 13.21 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.605e-06/0.04268, allocations: 0 / 0.509 GB, free: 13.21 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.19e-06/0.04269, allocations: 0 / 0.509 GB, free: 13.21 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001044/0.04373, allocations: 1.141 MB / 0.5101 GB, free: 12.02 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.091e-06/0.04374, allocations: 0 / 0.5101 GB, free: 12.02 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000326/0.04406, allocations: 130.2 kB / 0.5102 GB, free: 11.9 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 4.729e-06/0.04407, allocations: 4.844 kB / 0.5102 GB, free: 11.89 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.416e-05/0.04409, allocations: 15.98 kB / 0.5102 GB, free: 11.88 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 3.336e-06/0.04409, allocations: 4 kB / 0.5102 GB, free: 11.88 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.208e-06/0.0441, allocations: 7.938 kB / 0.5102 GB, free: 11.87 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005556/0.04465, allocations: 0.6807 MB / 0.5109 GB, free: 11.15 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001711/0.04482, allocations: 119.3 kB / 0.511 GB, free: 11.04 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.064e-05/0.04489, allocations: 8 kB / 0.511 GB, free: 11.03 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002507/0.04514, allocations: 44.98 kB / 0.5111 GB, free: 10.98 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 6.106e-05/0.0452, allocations: 45.3 kB / 0.5111 GB, free: 10.94 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.889e-05/0.04523, allocations: 19.98 kB / 0.5111 GB, free: 10.92 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.0002265/0.04545, allocations: 307.3 kB / 0.5114 GB, free: 10.62 MB / 490.7 MB
Notification: Performance of sort global known variables: time 7e-08/0.04545, allocations: 0.875 kB / 0.5114 GB, free: 10.62 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.0005187/0.04597, allocations: 339.1 kB / 0.5117 GB, free: 10.29 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.001305/0.04728, allocations: 0.6484 MB / 0.5124 GB, free: 9.969 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.0004401/0.04772, allocations: 412.9 kB / 0.5128 GB, free: 9.551 MB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 9.658e-06/0.04773, allocations: 8 kB / 0.5128 GB, free: 9.543 MB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.0001686/0.04789, allocations: 150.4 kB / 0.5129 GB, free: 9.395 MB / 490.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0008163/0.04871, allocations: 388 kB / 0.5133 GB, free: 9.066 MB / 490.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.002773/0.05148, allocations: 1.816 MB / 0.5151 GB, free: 7.215 MB / 490.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0001136/0.0516, allocations: 180.1 kB / 0.5152 GB, free: 7.02 MB / 490.7 MB
Notification: Performance of simCode: alias equations: time 0.0003666/0.05196, allocations: 167.6 kB / 0.5154 GB, free: 6.883 MB / 490.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007453/0.05271, allocations: 315.6 kB / 0.5157 GB, free: 6.574 MB / 490.7 MB
Notification: Performance of SimCode: time 6.91e-07/0.05271, allocations: 0 / 0.5157 GB, free: 6.574 MB / 490.7 MB
Notification: Performance of Templates: time 0.01984/0.07256, allocations: 17.99 MB / 0.5333 GB, free: 5.07 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  -lv LOG_STATS > IDEAS_IDEAS.Fluid.Taps.Examples.DHW_example.pipe 2>&1) [Timeout 240]
[Calling sys.exit(0), Time elapsed: 4.136660109739751]