Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.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.002175/0.002175, allocations: 110 kB / 18.93 MB, free: 2.945 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.002168/0.002168, allocations: 186.8 kB / 22.23 MB, free: 5.164 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.536/1.536, allocations: 225.6 MB / 251 MB, free: 13 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/Examples/PPD12/Structure.mo:527:21-527:482:writable] Warning: Lexer treating \\ as \\\\, since \\e is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/Examples/PPD12/Structure.mo:527:21-527:490:writable] Warning: Lexer treating \\ as \\\\, since \\< is not a valid Modelica escape sequence.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/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 3.0.0-master/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 3.0.0-master/package.mo): time 1.102/1.102, allocations: 177.9 MB / 484.2 MB, free: 5.277 MB / 382.1 MB
"
[Timeout remaining time 178]
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.Storage.Validation.StratifiedNonUniformInitial,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial")
translateModel(IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002259/0.002259, allocations: 67.94 kB / 0.674 GB, free: 10.72 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7106/0.7129, allocations: 95.17 MB / 0.7669 GB, free: 7.906 MB / 0.5762 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial): time 0.06416/0.7771, allocations: 50.19 MB / 0.8159 GB, free: 4.551 MB / 0.6075 GB
Notification: Performance of NFInst.instExpressions: time 0.02473/0.8018, allocations: 13.98 MB / 0.8296 GB, free: 9.812 MB / 0.6231 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003784/0.8056, allocations: 96.59 kB / 0.8297 GB, free: 9.762 MB / 0.6231 GB
Notification: Performance of NFTyping.typeComponents: time 0.003378/0.809, allocations: 0.8326 MB / 0.8305 GB, free: 9.047 MB / 0.6231 GB
Notification: Performance of NFTyping.typeBindings: time 0.009165/0.8181, allocations: 2.391 MB / 0.8328 GB, free: 7.133 MB / 0.6231 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00454/0.8227, allocations: 1.4 MB / 0.8342 GB, free: 6.023 MB / 0.6231 GB
Notification: Performance of NFFlatten.flatten: time 0.02826/0.8509, allocations: 20.18 MB / 0.8539 GB, free: 9.84 MB / 0.6387 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01339/0.8643, allocations: 6.458 MB / 0.8602 GB, free: 3.262 MB / 0.6387 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02161/0.8859, allocations: 6.867 MB / 0.8669 GB, free: 12.41 MB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01349/0.8994, allocations: 5.969 MB / 0.8727 GB, free: 6.422 MB / 0.6544 GB
Notification: Performance of NFPackage.collectConstants: time 0.003447/0.9029, allocations: 0.8477 MB / 0.8736 GB, free: 5.574 MB / 0.6544 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.009068/0.9119, allocations: 1.643 MB / 0.8752 GB, free: 3.93 MB / 0.6544 GB
Notification: Performance of NFScalarize.scalarize: time 0.005803/0.9177, allocations: 2.196 MB / 0.8773 GB, free: 1.727 MB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.01384/0.9316, allocations: 4.314 MB / 0.8815 GB, free: 13.39 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.02522/0.9568, allocations: 12.14 MB / 0.8934 GB, free: 1.223 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 1.076e-05/0.9568, allocations: 0 / 0.8934 GB, free: 1.223 MB / 0.67 GB
Notification: Performance of FrontEnd: time 3.136e-06/0.9568, allocations: 4 kB / 0.8934 GB, free: 1.219 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 0.0005508/0.9573, allocations: 4 kB / 0.8934 GB, free: 1.215 MB / 0.67 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.02615/0.9835, allocations: 7.861 MB / 0.9011 GB, free: 9.281 MB / 0.6856 GB
Notification: Performance of prepare preOptimizeDAE: time 5.34e-05/0.9836, allocations: 8.031 kB / 0.9011 GB, free: 9.273 MB / 0.6856 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005187/0.9887, allocations: 0.8243 MB / 0.9019 GB, free: 8.445 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01593/1.005, allocations: 6.394 MB / 0.9081 GB, free: 1.969 MB / 0.6856 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004749/1.005, allocations: 415.1 kB / 0.9085 GB, free: 1.562 MB / 0.6856 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00229/1.007, allocations: 0.539 MB / 0.909 GB, free: 1.023 MB / 0.6856 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02309/1.031, allocations: 8.065 MB / 0.9169 GB, free: 8.723 MB / 0.7012 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001703/1.031, allocations: 19.83 kB / 0.9169 GB, free: 8.703 MB / 0.7012 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001186/1.032, allocations: 240 kB / 0.9172 GB, free: 8.469 MB / 0.7012 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002218/1.032, allocations: 168 kB / 0.9173 GB, free: 8.305 MB / 0.7012 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01699/1.049, allocations: 6.524 MB / 0.9237 GB, free: 1.77 MB / 0.7012 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.06259/1.112, allocations: 31.21 MB / 0.9542 GB, free: 0.707 MB / 0.7325 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00698/1.119, allocations: 3.441 MB / 0.9575 GB, free: 13.26 MB / 0.7481 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004703/1.123, allocations: 2.592 MB / 0.9601 GB, free: 10.66 MB / 0.7481 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0007666/1.124, allocations: 161.9 kB / 0.9602 GB, free: 10.5 MB / 0.7481 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.099e-05/1.124, allocations: 68.67 kB / 0.9603 GB, free: 10.43 MB / 0.7481 GB
Notification: Performance of pre-optimization done (n=267): time 4.198e-06/1.124, allocations: 0 / 0.9603 GB, free: 10.43 MB / 0.7481 GB
Notification: Performance of matching and sorting (n=267): time 0.0132/1.137, allocations: 5.619 MB / 0.9658 GB, free: 4.746 MB / 0.7481 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.519e-05/1.137, allocations: 77.52 kB / 0.9659 GB, free: 4.656 MB / 0.7481 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01206/1.15, allocations: 5.251 MB / 0.971 GB, free: 15.41 MB / 0.7637 GB
Notification: Performance of collectPreVariables (initialization): time 0.0009151/1.15, allocations: 73.7 kB / 0.9711 GB, free: 15.33 MB / 0.7637 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003589/1.154, allocations: 2.938 MB / 0.9739 GB, free: 12.37 MB / 0.7637 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001995/1.156, allocations: 0.98 MB / 0.9749 GB, free: 11.38 MB / 0.7637 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001422/1.157, allocations: 0.7156 MB / 0.9756 GB, free: 10.66 MB / 0.7637 GB
Notification: Performance of setup shared object (initialization): time 0.0001475/1.158, allocations: 309.1 kB / 0.9759 GB, free: 10.35 MB / 0.7637 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00301/1.161, allocations: 1.674 MB / 0.9775 GB, free: 8.676 MB / 0.7637 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004423/1.165, allocations: 2.936 MB / 0.9804 GB, free: 5.191 MB / 0.7637 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008818/1.174, allocations: 4.97 MB / 0.9852 GB, free: 15.62 MB / 0.7794 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.161e-05/1.174, allocations: 12 kB / 0.9852 GB, free: 15.61 MB / 0.7794 GB
Notification: Performance of matching and sorting (n=475) (initialization): time 0.01248/1.186, allocations: 5.444 MB / 0.9906 GB, free: 10.12 MB / 0.7794 GB
Notification: Performance of prepare postOptimizeDAE: time 5.462e-05/1.186, allocations: 27.94 kB / 0.9906 GB, free: 10.09 MB / 0.7794 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.97e-05/1.187, allocations: 40 kB / 0.9906 GB, free: 10.05 MB / 0.7794 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001133/1.188, allocations: 424.9 kB / 0.991 GB, free: 9.633 MB / 0.7794 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003418/1.191, allocations: 0.8541 MB / 0.9919 GB, free: 8.777 MB / 0.7794 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007073/1.198, allocations: 5.494 MB / 0.9972 GB, free: 3.066 MB / 0.7794 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006088/1.204, allocations: 0.5354 MB / 0.9977 GB, free: 2.527 MB / 0.7794 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009425/1.205, allocations: 184 kB / 0.9979 GB, free: 2.348 MB / 0.7794 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.005605/1.211, allocations: 2.284 MB / 1 GB, free: 52 kB / 0.7794 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002213/1.213, allocations: 0.5046 MB / 1.001 GB, free: 15.54 MB / 0.795 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.008255/1.221, allocations: 4.158 MB / 1.005 GB, free: 11.32 MB / 0.795 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.725e-05/1.221, allocations: 31.98 kB / 1.005 GB, free: 11.29 MB / 0.795 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.126e-05/1.221, allocations: 4 kB / 1.005 GB, free: 11.29 MB / 0.795 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.005774/1.227, allocations: 3.624 MB / 1.008 GB, free: 7.547 MB / 0.795 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02165/1.249, allocations: 10.29 MB / 1.018 GB, free: 13.09 MB / 0.8106 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.601e-05/1.249, allocations: 7.984 kB / 1.018 GB, free: 13.08 MB / 0.8106 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002221/1.251, allocations: 0.5076 MB / 1.019 GB, free: 12.57 MB / 0.8106 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0009182/1.252, allocations: 369.3 kB / 1.019 GB, free: 12.2 MB / 0.8106 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002166/1.252, allocations: 75.95 kB / 1.019 GB, free: 12.13 MB / 0.8106 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006405/1.259, allocations: 5.438 MB / 1.025 GB, free: 6.473 MB / 0.8106 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.36e-06/1.259, allocations: 4 kB / 1.025 GB, free: 6.469 MB / 0.8106 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01133/1.27, allocations: 6.272 MB / 1.031 GB, free: 28 kB / 0.8106 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004003/1.274, allocations: 1.076 MB / 1.032 GB, free: 14.95 MB / 0.8262 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001529/1.275, allocations: 63.98 kB / 1.032 GB, free: 14.88 MB / 0.8262 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0036/1.279, allocations: 167.7 kB / 1.032 GB, free: 14.72 MB / 0.8262 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003819/1.279, allocations: 181.5 kB / 1.032 GB, free: 14.54 MB / 0.8262 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003451/1.28, allocations: 135.9 kB / 1.032 GB, free: 14.41 MB / 0.8262 GB
Notification: Performance of sorting global known variables: time 0.007706/1.287, allocations: 3.847 MB / 1.036 GB, free: 10.57 MB / 0.8262 GB
Notification: Performance of sort global known variables: time 1.21e-07/1.287, allocations: 0 / 1.036 GB, free: 10.57 MB / 0.8262 GB
Notification: Performance of remove unused functions: time 0.01127/1.299, allocations: 1.817 MB / 1.038 GB, free: 8.754 MB / 0.8262 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.002163/1.301, allocations: 494.9 kB / 1.038 GB, free: 8.266 MB / 0.8262 GB
Notification: Performance of simCode: created initialization part: time 0.008654/1.309, allocations: 4.137 MB / 1.042 GB, free: 4.09 MB / 0.8262 GB
Notification: Performance of simCode: created event and clocks part: time 1.582e-05/1.31, allocations: 4.375 kB / 1.042 GB, free: 4.086 MB / 0.8262 GB
Notification: Performance of simCode: created simulation system equations: time 0.003579/1.313, allocations: 1.921 MB / 1.044 GB, free: 2.137 MB / 0.8262 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01914/1.332, allocations: 3.461 MB / 1.048 GB, free: 14.78 MB / 0.8419 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02589/1.358, allocations: 12.75 MB / 1.06 GB, free: 1.926 MB / 0.8419 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005717/1.364, allocations: 4.307 MB / 1.064 GB, free: 13.59 MB / 0.8575 GB
Notification: Performance of simCode: alias equations: time 0.00709/1.371, allocations: 1.847 MB / 1.066 GB, free: 11.74 MB / 0.8575 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001904/1.373, allocations: 419.3 kB / 1.066 GB, free: 11.33 MB / 0.8575 GB
Notification: Performance of SimCode: time 2.174e-06/1.373, allocations: 0 / 1.066 GB, free: 11.33 MB / 0.8575 GB
Notification: Performance of Templates: time 0.6211/1.994, allocations: 116.7 MB / 1.18 GB, free: 224.7 MB / 0.8731 GB
"
[Timeout remaining time 658]
make -j1 -f IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial.makefile [Timeout 660]
(rm -f IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial.sim & ./IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Storage.Validation.StratifiedNonUniformInitial.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 11.191139226779342]
Failed to read output from testmodel.py, exit status != 0:
2.0034615471959114 2.011305599 1.051964926
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fdef84917b0>