Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001701/0.001701, allocations: 120.3 kB / 17.13 MB, free: 5.918 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001768/0.001768, allocations: 191.7 kB / 20.08 MB, free: 2.988 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.449/1.449, allocations: 225.6 MB / 248.5 MB, free: 3.625 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.634/2.634, allocations: 443.5 MB / 0.7293 GB, free: 14.52 MB / 0.5762 GB
"
[Timeout remaining time 177]
Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/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(Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|tanEnh.vol.1..T|tanEnh.vol.2..T|tanEnh.vol.3..T|tanEnh.vol.4..T|tanEnh.vol.5..T|tanEnh.vol.6..T|tanEnh.vol.7..T|dHTan.y|dHTanEnh.y|tan.vol.1..T|tan.vol.2..T|tan.vol.3..T|tan.vol.4..T|tan.vol.5..T|tan.vol.6..T|tan.vol.7..T",fileNamePrefix="Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading")
translateModel(Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|tanEnh.vol.1..T|tanEnh.vol.2..T|tanEnh.vol.3..T|tanEnh.vol.4..T|tanEnh.vol.5..T|tanEnh.vol.6..T|tanEnh.vol.7..T|dHTan.y|dHTanEnh.y|tan.vol.1..T|tan.vol.2..T|tan.vol.3..T|tan.vol.4..T|tan.vol.5..T|tan.vol.6..T|tan.vol.7..T",fileNamePrefix="Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.001981/0.001981, allocations: 67.89 kB / 1.057 GB, free: 2 MB / 0.795 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3548/0.3568, allocations: 160.7 MB / 1.214 GB, free: 0.9688 MB / 0.9512 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading): time 0.8906/1.247, allocations: 56.4 MB / 1.269 GB, free: 7.898 MB / 0.9981 GB
Notification: Performance of NFInst.instExpressions: time 0.02636/1.274, allocations: 18.03 MB / 1.287 GB, free: 7.535 MB / 0.9981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00429/1.278, allocations: 77.06 kB / 1.287 GB, free: 7.535 MB / 0.9981 GB
Notification: Performance of NFTyping.typeComponents: time 0.003542/1.282, allocations: 0.9282 MB / 1.288 GB, free: 7.52 MB / 0.9981 GB
Notification: Performance of NFTyping.typeBindings: time 0.01071/1.292, allocations: 2.624 MB / 1.29 GB, free: 7.012 MB / 0.9981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005705/1.298, allocations: 1.529 MB / 1.292 GB, free: 6.934 MB / 0.9981 GB
Notification: Performance of NFFlatten.flatten: time 0.02992/1.328, allocations: 21.27 MB / 1.313 GB, free: 14.54 MB / 1.014 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0175/1.345, allocations: 8.878 MB / 1.321 GB, free: 10.97 MB / 1.014 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02153/1.367, allocations: 7.245 MB / 1.328 GB, free: 8.129 MB / 1.014 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01713/1.384, allocations: 6.661 MB / 1.335 GB, free: 5.711 MB / 1.014 GB
Notification: Performance of NFPackage.collectConstants: time 0.004801/1.389, allocations: 0.9416 MB / 1.336 GB, free: 5.711 MB / 1.014 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01127/1.4, allocations: 1.723 MB / 1.337 GB, free: 5.441 MB / 1.014 GB
Notification: Performance of NFScalarize.scalarize: time 0.006705/1.407, allocations: 2.434 MB / 1.34 GB, free: 3.984 MB / 1.014 GB
Notification: Performance of NFVerifyModel.verify: time 0.0178/1.425, allocations: 4.711 MB / 1.344 GB, free: 1.156 MB / 1.014 GB
Notification: Performance of NFConvertDAE.convert: time 0.02783/1.452, allocations: 13.28 MB / 1.357 GB, free: 9.938 MB / 1.029 GB
Notification: Performance of FrontEnd - DAE generated: time 6.392e-06/1.452, allocations: 0 / 1.357 GB, free: 9.938 MB / 1.029 GB
Notification: Performance of FrontEnd: time 1.643e-06/1.452, allocations: 0 / 1.357 GB, free: 9.938 MB / 1.029 GB
Notification: Performance of Transformations before backend: time 0.0006462/1.453, allocations: 0 / 1.357 GB, free: 9.938 MB / 1.029 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1564
 * Number of variables: 1564
Notification: Performance of Generate backend data structure: time 0.03033/1.483, allocations: 8.696 MB / 1.366 GB, free: 4.184 MB / 1.029 GB
Notification: Performance of prepare preOptimizeDAE: time 4.579e-05/1.483, allocations: 11.66 kB / 1.366 GB, free: 4.184 MB / 1.029 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005427/1.489, allocations: 0.8945 MB / 1.367 GB, free: 3.707 MB / 1.029 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01689/1.506, allocations: 6.745 MB / 1.373 GB, free: 13.04 MB / 1.045 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005361/1.506, allocations: 461.3 kB / 1.374 GB, free: 12.59 MB / 1.045 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002794/1.509, allocations: 0.6041 MB / 1.374 GB, free: 11.99 MB / 1.045 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02636/1.535, allocations: 9.027 MB / 1.383 GB, free: 2.648 MB / 1.045 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003109/1.536, allocations: 27.86 kB / 1.383 GB, free: 2.621 MB / 1.045 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002278/1.538, allocations: 258.4 kB / 1.383 GB, free: 2.371 MB / 1.045 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002637/1.538, allocations: 187.9 kB / 1.384 GB, free: 2.188 MB / 1.045 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01857/1.557, allocations: 7.461 MB / 1.391 GB, free: 10.72 MB / 1.061 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.06921/1.626, allocations: 36.08 MB / 1.426 GB, free: 4.871 MB / 1.092 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.007652/1.634, allocations: 3.991 MB / 1.43 GB, free: 0.8789 MB / 1.092 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005504/1.639, allocations: 3.033 MB / 1.433 GB, free: 13.85 MB / 1.107 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001523/1.641, allocations: 0.9611 MB / 1.434 GB, free: 12.71 MB / 1.107 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004816/1.646, allocations: 2.394 MB / 1.436 GB, free: 10.29 MB / 1.107 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003521/1.649, allocations: 399.4 kB / 1.437 GB, free: 9.895 MB / 1.107 GB
Notification: Performance of pre-optimization done (n=317): time 1.416e-05/1.649, allocations: 0 / 1.437 GB, free: 9.895 MB / 1.107 GB
Notification: Performance of matching and sorting (n=317): time 0.01555/1.665, allocations: 7.142 MB / 1.444 GB, free: 2.668 MB / 1.107 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.038e-05/1.665, allocations: 89.84 kB / 1.444 GB, free: 2.566 MB / 1.107 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01265/1.677, allocations: 5.668 MB / 1.449 GB, free: 12.92 MB / 1.123 GB
Notification: Performance of collectPreVariables (initialization): time 0.0007658/1.678, allocations: 85.7 kB / 1.449 GB, free: 12.83 MB / 1.123 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004362/1.683, allocations: 3.276 MB / 1.453 GB, free: 9.547 MB / 1.123 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002005/1.685, allocations: 1.171 MB / 1.454 GB, free: 8.391 MB / 1.123 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002376/1.687, allocations: 0.8624 MB / 1.454 GB, free: 7.516 MB / 1.123 GB
Notification: Performance of setup shared object (initialization): time 0.0001517/1.687, allocations: 309.1 kB / 1.455 GB, free: 7.211 MB / 1.123 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004171/1.691, allocations: 2.163 MB / 1.457 GB, free: 5.039 MB / 1.123 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00559/1.697, allocations: 3.693 MB / 1.461 GB, free: 0.6445 MB / 1.123 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01002/1.707, allocations: 6.319 MB / 1.467 GB, free: 9.609 MB / 1.139 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.776e-05/1.707, allocations: 20 kB / 1.467 GB, free: 9.59 MB / 1.139 GB
Notification: Performance of matching and sorting (n=569) (initialization): time 0.01382/1.721, allocations: 6.786 MB / 1.473 GB, free: 2.754 MB / 1.139 GB
Notification: Performance of prepare postOptimizeDAE: time 9.104e-05/1.721, allocations: 57.69 kB / 1.473 GB, free: 2.691 MB / 1.139 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.499e-05/1.721, allocations: 52 kB / 1.473 GB, free: 2.641 MB / 1.139 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001149/1.722, allocations: 441 kB / 1.474 GB, free: 2.207 MB / 1.139 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003791/1.726, allocations: 1.002 MB / 1.475 GB, free: 1.203 MB / 1.139 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007003/1.733, allocations: 5.707 MB / 1.48 GB, free: 11.24 MB / 1.154 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005677/1.739, allocations: 291.7 kB / 1.481 GB, free: 10.95 MB / 1.154 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006066/1.739, allocations: 216 kB / 1.481 GB, free: 10.74 MB / 1.154 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 222
 * Number of states: 0 ()
 * Number of discrete variables: 7 (pulse.count,$PRE.pulse.count,pulse.T_start,$PRE.pulse.T_start,$whenCondition1,dHTan.local_reset,dHTanEnh.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (540):
 * Single equations (assignments): 537
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,14,100.0%), (1,14,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.005749/1.745, allocations: 2.479 MB / 1.483 GB, free: 8.25 MB / 1.154 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002398/1.747, allocations: 0.5513 MB / 1.484 GB, free: 7.695 MB / 1.154 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01107/1.758, allocations: 5.251 MB / 1.489 GB, free: 2.387 MB / 1.154 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.019e-05/1.758, allocations: 35.92 kB / 1.489 GB, free: 2.352 MB / 1.154 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.616e-05/1.758, allocations: 8 kB / 1.489 GB, free: 2.344 MB / 1.154 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.007569/1.766, allocations: 4.514 MB / 1.493 GB, free: 13.73 MB / 1.17 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02864/1.795, allocations: 11.97 MB / 1.505 GB, free: 1.684 MB / 1.17 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.682e-05/1.795, allocations: 0 / 1.505 GB, free: 1.684 MB / 1.17 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003192/1.798, allocations: 0.6438 MB / 1.506 GB, free: 1.039 MB / 1.17 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001098/1.799, allocations: 372.3 kB / 1.506 GB, free: 0.6719 MB / 1.17 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004213/1.799, allocations: 96.08 kB / 1.506 GB, free: 0.5781 MB / 1.17 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007707/1.807, allocations: 5.614 MB / 1.512 GB, free: 10.69 MB / 1.186 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.799e-06/1.807, allocations: 4 kB / 1.512 GB, free: 10.68 MB / 1.186 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01413/1.821, allocations: 7.779 MB / 1.519 GB, free: 2.816 MB / 1.186 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004166/1.825, allocations: 1.181 MB / 1.52 GB, free: 1.613 MB / 1.186 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002011/1.827, allocations: 67.98 kB / 1.52 GB, free: 1.547 MB / 1.186 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004372/1.832, allocations: 199.8 kB / 1.521 GB, free: 1.352 MB / 1.186 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005245/1.832, allocations: 198.2 kB / 1.521 GB, free: 1.156 MB / 1.186 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005113/1.833, allocations: 139.9 kB / 1.521 GB, free: 1.02 MB / 1.186 GB
Notification: Performance of sorting global known variables: time 0.009372/1.842, allocations: 3.959 MB / 1.525 GB, free: 13.08 MB / 1.201 GB
Notification: Performance of sort global known variables: time 1e-07/1.842, allocations: 3.938 kB / 1.525 GB, free: 13.08 MB / 1.201 GB
Notification: Performance of remove unused functions: time 0.009518/1.852, allocations: 2.023 MB / 1.527 GB, free: 11.05 MB / 1.201 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 16 (tanEnh.vol[1].dynBal.U,tanEnh.vol[2].dynBal.U,tanEnh.vol[3].dynBal.U,tanEnh.vol[4].dynBal.U,tanEnh.vol[5].dynBal.U,tanEnh.vol[6].dynBal.U,tanEnh.vol[7].dynBal.U,tan.vol[1].dynBal.U,tan.vol[2].dynBal.U,tan.vol[3].dynBal.U,tan.vol[4].dynBal.U,tan.vol[5].dynBal.U,tan.vol[6].dynBal.U,tan.vol[7].dynBal.U,dHTanEnh.y,dHTan.y)
 * Number of discrete variables: 3 ($whenCondition1,pulse.T_start,pulse.count)
 * Number of discrete states: 1 (pulse.count)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (296):
 * Single equations (assignments): 289
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 3
 * When equations: 2
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,14,100.0%), (1,14,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001934/1.854, allocations: 0.4931 MB / 1.527 GB, free: 10.57 MB / 1.201 GB
Notification: Performance of simCode: created initialization part: time 0.01085/1.864, allocations: 5.237 MB / 1.532 GB, free: 5.281 MB / 1.201 GB
Notification: Performance of simCode: created event and clocks part: time 1.051e-05/1.864, allocations: 4 kB / 1.532 GB, free: 5.277 MB / 1.201 GB
Notification: Performance of simCode: created simulation system equations: time 0.004746/1.869, allocations: 2.377 MB / 1.535 GB, free: 2.871 MB / 1.201 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.02066/1.89, allocations: 3.389 MB / 1.538 GB, free: 15.57 MB / 1.217 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03368/1.924, allocations: 14.14 MB / 1.552 GB, free: 1.375 MB / 1.217 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007892/1.931, allocations: 4.546 MB / 1.556 GB, free: 12.8 MB / 1.232 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002807/1.934, allocations: 495.2 kB / 1.557 GB, free: 12.32 MB / 1.232 GB
Notification: Performance of SimCode: time 1.543e-06/1.934, allocations: 0 / 1.557 GB, free: 12.32 MB / 1.232 GB
Notification: Performance of Templates: time 0.2904/2.225, allocations: 188.6 MB / 1.741 GB, free: 180 kB / 1.404 GB
"
[Timeout remaining time 298]
make -j1 -f Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.makefile [Timeout 300]
(rm -f Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe ; mkfifo Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe ; head -c 1048576 < Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe >> ../files/Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.sim & ./Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_10.0.x/Buildings_Fluid_Storage_Validation_StratifiedLoadingUnloading.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/Buildings_10_Buildings.Fluid.Storage.Validation.StratifiedLoadingUnloading.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 30.64676273218356]