Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.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.001404/0.001404, allocations: 107.5 kB / 18.7 MB, free: 3.32 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.001406/0.001406, allocations: 192.4 kB / 21.95 MB, free: 72 kB / 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.399/1.399, allocations: 225.6 MB / 250.7 MB, free: 13.42 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0-master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0-master/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0-master/package.mo): time 0.9219/0.9219, allocations: 145.2 MB / 451 MB, free: 6.109 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package BuildSysPro with version 3.6.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.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(BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial")
translateModel(BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001965/0.001965, allocations: 73.14 kB / 0.6248 GB, free: 15.96 MB / 462.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1718/0.1738, allocations: 86.64 MB / 0.7094 GB, free: 9.273 MB / 0.5294 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial): time 0.4226/0.5964, allocations: 50.1 MB / 0.7584 GB, free: 5.488 MB / 0.5606 GB
Notification: Performance of NFInst.instExpressions: time 0.01869/0.6151, allocations: 13.95 MB / 0.772 GB, free: 5.156 MB / 0.5606 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003027/0.6181, allocations: 66.34 kB / 0.772 GB, free: 5.156 MB / 0.5606 GB
Notification: Performance of NFTyping.typeComponents: time 0.002715/0.6208, allocations: 0.8324 MB / 0.7729 GB, free: 5.016 MB / 0.5606 GB
Notification: Performance of NFTyping.typeBindings: time 0.008277/0.6291, allocations: 2.362 MB / 0.7752 GB, free: 3.957 MB / 0.5606 GB
Notification: Performance of NFTyping.typeClassSections: time 0.004001/0.6331, allocations: 1.337 MB / 0.7765 GB, free: 3.328 MB / 0.5606 GB
Notification: Performance of NFFlatten.flatten: time 0.0223/0.6554, allocations: 19.64 MB / 0.7956 GB, free: 10.66 MB / 0.5763 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01097/0.6664, allocations: 6.446 MB / 0.8019 GB, free: 6.809 MB / 0.5763 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01624/0.6826, allocations: 6.615 MB / 0.8084 GB, free: 3.535 MB / 0.5763 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01209/0.6947, allocations: 6.032 MB / 0.8143 GB, free: 14.7 MB / 0.5919 GB
Notification: Performance of NFPackage.collectConstants: time 0.003016/0.6977, allocations: 0.8438 MB / 0.8151 GB, free: 13.85 MB / 0.5919 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.008218/0.706, allocations: 1.627 MB / 0.8167 GB, free: 12.28 MB / 0.5919 GB
Notification: Performance of NFScalarize.scalarize: time 0.004851/0.7108, allocations: 2.2 MB / 0.8189 GB, free: 10.11 MB / 0.5919 GB
Notification: Performance of NFVerifyModel.verify: time 0.0116/0.7224, allocations: 4.288 MB / 0.823 GB, free: 5.84 MB / 0.5919 GB
Notification: Performance of NFConvertDAE.convert: time 0.02035/0.7428, allocations: 12.72 MB / 0.8355 GB, free: 9.391 MB / 0.6075 GB
Notification: Performance of FrontEnd - DAE generated: time 7.955e-06/0.7428, allocations: 3.938 kB / 0.8355 GB, free: 9.387 MB / 0.6075 GB
Notification: Performance of FrontEnd: time 2.124e-06/0.7428, allocations: 0 / 0.8355 GB, free: 9.387 MB / 0.6075 GB
Notification: Performance of Transformations before backend: time 0.0004851/0.7433, allocations: 3.938 kB / 0.8355 GB, free: 9.383 MB / 0.6075 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1414
 * Number of variables: 1414
Notification: Performance of Generate backend data structure: time 0.02085/0.7641, allocations: 7.939 MB / 0.8432 GB, free: 1.414 MB / 0.6075 GB
Notification: Performance of prepare preOptimizeDAE: time 4.533e-05/0.7641, allocations: 8.031 kB / 0.8432 GB, free: 1.406 MB / 0.6075 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002962/0.7671, allocations: 0.8165 MB / 0.844 GB, free: 0.5859 MB / 0.6075 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01318/0.7803, allocations: 6.371 MB / 0.8502 GB, free: 10.29 MB / 0.6231 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004243/0.7807, allocations: 415.6 kB / 0.8506 GB, free: 9.887 MB / 0.6231 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001921/0.7826, allocations: 0.5458 MB / 0.8512 GB, free: 9.344 MB / 0.6231 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01853/0.8012, allocations: 8.076 MB / 0.8591 GB, free: 1.035 MB / 0.6231 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001319/0.8013, allocations: 19.86 kB / 0.8591 GB, free: 1.016 MB / 0.6231 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007246/0.802, allocations: 232 kB / 0.8593 GB, free: 0.7891 MB / 0.6231 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001832/0.8022, allocations: 164 kB / 0.8595 GB, free: 0.6289 MB / 0.6231 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01381/0.816, allocations: 6.528 MB / 0.8658 GB, free: 10.09 MB / 0.6388 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.05064/0.8667, allocations: 31.28 MB / 0.8964 GB, free: 9.234 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.006314/0.873, allocations: 3.446 MB / 0.8997 GB, free: 5.793 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004309/0.8773, allocations: 2.572 MB / 0.9023 GB, free: 3.211 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001146/0.8784, allocations: 0.8335 MB / 0.9031 GB, free: 2.223 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.769e-05/0.8785, allocations: 80.55 kB / 0.9031 GB, free: 2.133 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=267): time 4.268e-06/0.8785, allocations: 0 / 0.9031 GB, free: 2.133 MB / 0.67 GB
Notification: Performance of matching and sorting (n=267): time 0.01273/0.8912, allocations: 5.769 MB / 0.9088 GB, free: 12.27 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.78e-05/0.8913, allocations: 81.45 kB / 0.9089 GB, free: 12.18 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01028/0.9016, allocations: 5.239 MB / 0.914 GB, free: 6.938 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 0.0007349/0.9023, allocations: 77.7 kB / 0.914 GB, free: 6.855 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003309/0.9056, allocations: 2.935 MB / 0.9169 GB, free: 3.91 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00176/0.9074, allocations: 0.985 MB / 0.9179 GB, free: 2.938 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001376/0.9088, allocations: 0.7195 MB / 0.9186 GB, free: 2.211 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 0.0001494/0.9089, allocations: 305.1 kB / 0.9189 GB, free: 1.91 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003093/0.912, allocations: 1.674 MB / 0.9205 GB, free: 240 kB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004312/0.9163, allocations: 2.928 MB / 0.9234 GB, free: 12.76 MB / 0.7013 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007829/0.9242, allocations: 4.978 MB / 0.9282 GB, free: 7.188 MB / 0.7013 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.531e-05/0.9242, allocations: 8 kB / 0.9282 GB, free: 7.18 MB / 0.7013 GB
Notification: Performance of matching and sorting (n=475) (initialization): time 0.01212/0.9363, allocations: 5.447 MB / 0.9336 GB, free: 1.68 MB / 0.7013 GB
Notification: Performance of prepare postOptimizeDAE: time 5.291e-05/0.9364, allocations: 24 kB / 0.9336 GB, free: 1.656 MB / 0.7013 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.153e-05/0.9364, allocations: 40 kB / 0.9336 GB, free: 1.617 MB / 0.7013 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001117/0.9376, allocations: 418 kB / 0.934 GB, free: 1.203 MB / 0.7013 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00356/0.9411, allocations: 0.858 MB / 0.9348 GB, free: 352 kB / 0.7013 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007094/0.9482, allocations: 5.708 MB / 0.9404 GB, free: 10.37 MB / 0.7169 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004736/0.9529, allocations: 0.5276 MB / 0.9409 GB, free: 9.836 MB / 0.7169 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000754/0.9537, allocations: 183.9 kB / 0.9411 GB, free: 9.656 MB / 0.7169 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 181
 * Number of states: 0 ()
 * Number of discrete variables: 2 (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 (447):
 * Single equations (assignments): 445
 * Array equations: 0
 * Algorithm blocks: 0
 * 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.004893/0.9586, allocations: 2.276 MB / 0.9433 GB, free: 7.367 MB / 0.7169 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001806/0.9604, allocations: 0.5085 MB / 0.9438 GB, free: 6.855 MB / 0.7169 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008607/0.969, allocations: 4.167 MB / 0.9479 GB, free: 2.637 MB / 0.7169 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.521e-05/0.969, allocations: 32 kB / 0.9479 GB, free: 2.605 MB / 0.7169 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.236e-05/0.9691, allocations: 4 kB / 0.9479 GB, free: 2.602 MB / 0.7169 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.006038/0.9751, allocations: 3.614 MB / 0.9515 GB, free: 14.88 MB / 0.7325 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02221/0.9973, allocations: 10.28 MB / 0.9615 GB, free: 4.48 MB / 0.7325 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.477e-05/0.9973, allocations: 4 kB / 0.9615 GB, free: 4.477 MB / 0.7325 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002372/0.9997, allocations: 0.4954 MB / 0.962 GB, free: 3.98 MB / 0.7325 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0009491/1.001, allocations: 373.3 kB / 0.9623 GB, free: 3.609 MB / 0.7325 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002915/1.001, allocations: 79.95 kB / 0.9624 GB, free: 3.531 MB / 0.7325 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006634/1.008, allocations: 5.647 MB / 0.9679 GB, free: 13.62 MB / 0.7481 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.468e-06/1.008, allocations: 11.94 kB / 0.9679 GB, free: 13.61 MB / 0.7481 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01179/1.019, allocations: 6.267 MB / 0.9741 GB, free: 7.172 MB / 0.7481 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003581/1.023, allocations: 1.122 MB / 0.9752 GB, free: 6.027 MB / 0.7481 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001375/1.024, allocations: 63.92 kB / 0.9752 GB, free: 5.965 MB / 0.7481 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003307/1.028, allocations: 175.6 kB / 0.9754 GB, free: 5.793 MB / 0.7481 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004114/1.028, allocations: 173.6 kB / 0.9756 GB, free: 5.621 MB / 0.7481 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003434/1.028, allocations: 131.9 kB / 0.9757 GB, free: 5.492 MB / 0.7481 GB
Notification: Performance of sorting global known variables: time 0.008445/1.037, allocations: 3.835 MB / 0.9794 GB, free: 1.672 MB / 0.7481 GB
Notification: Performance of sort global known variables: time 1.21e-07/1.037, allocations: 0 / 0.9794 GB, free: 1.672 MB / 0.7481 GB
Notification: Performance of remove unused functions: time 0.007591/1.044, allocations: 1.821 MB / 0.9812 GB, free: 15.84 MB / 0.7638 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 19
 * Number of states: 16 (heaTan.vol[1].dynBal.U,heaTan.vol[2].dynBal.U,heaTan.vol[3].dynBal.U,heaTan.vol[4].dynBal.U,heaTan.vol[5].dynBal.U,heaTan.vol[6].dynBal.U,heaTan.vol[7].dynBal.U,cooTan.vol[1].dynBal.U,cooTan.vol[2].dynBal.U,cooTan.vol[3].dynBal.U,cooTan.vol[4].dynBal.U,cooTan.vol[5].dynBal.U,cooTan.vol[6].dynBal.U,cooTan.vol[7].dynBal.U,dHTanEnh.y,dHTan.y)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (242):
 * Single equations (assignments): 237
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 3
 * 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 Backend phase and start with SimCode phase: time 0.001542/1.046, allocations: 0.5007 MB / 0.9817 GB, free: 15.37 MB / 0.7638 GB
Notification: Performance of simCode: created initialization part: time 0.00794/1.054, allocations: 4.129 MB / 0.9857 GB, free: 11.2 MB / 0.7638 GB
Notification: Performance of simCode: created event and clocks part: time 1.207e-05/1.054, allocations: 0 / 0.9857 GB, free: 11.2 MB / 0.7638 GB
Notification: Performance of simCode: created simulation system equations: time 0.003493/1.057, allocations: 1.925 MB / 0.9876 GB, free: 9.246 MB / 0.7638 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01606/1.073, allocations: 3.451 MB / 0.991 GB, free: 5.891 MB / 0.7638 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02254/1.096, allocations: 12.8 MB / 1.003 GB, free: 9.008 MB / 0.7794 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004532/1.101, allocations: 4.235 MB / 1.008 GB, free: 4.746 MB / 0.7794 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001833/1.102, allocations: 443.3 kB / 1.008 GB, free: 4.312 MB / 0.7794 GB
Notification: Performance of SimCode: time 1.442e-06/1.102, allocations: 4 kB / 1.008 GB, free: 4.309 MB / 0.7794 GB
Notification: Performance of Templates: time 0.5701/1.672, allocations: 175.3 MB / 1.179 GB, free: 180.8 MB / 0.8263 GB
"
[Timeout remaining time 658]
make -j1 -f BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.makefile [Timeout 660]
(rm -f BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.sim & ./BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildSysPro_BuildSysPro.IBPSA.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 26.664215048076585]
Failed to read output from testmodel.py, exit status != 0:
1.6808428070507944 1.69027045 0.944919118
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd151836050>