Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestBottle.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo): time 0.008174/0.008174, allocations: 1.794 MB / 21.35 MB, free: 3.191 MB / 18.57 MB
"
[Timeout remaining time 180]
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.0008036/0.0008036, allocations: 88.72 kB / 25.15 MB, free: 6.316 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.0008751/0.0008751, allocations: 168 kB / 29.06 MB, free: 3.348 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.9024/0.9024, allocations: 177.2 MB / 210 MB, free: 3.582 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo): time 0.5709/0.5709, allocations: 102.5 MB / 369.5 MB, free: 400 kB / 330.7 MB
"
[Timeout remaining time 179]
Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.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)
Using package SMArtInt with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: translateModel(ClaRa.Components.MechanicalSeparation.Check.TestBottle,tolerance=1e-05,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|bottle_L3_1.insulation.U.[0-9,]+.|bottle_L3_1.volume.h_liq|bottle_L3_1.volume.h_vap|bottle_L3_1.volume.p_vap|bottle_L3_1.volume.volume_vap|bottle_L3_1.wall.U.[0-9,]+.",fileNamePrefix="ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestBottle")
translateModel(ClaRa.Components.MechanicalSeparation.Check.TestBottle,tolerance=1e-05,outputFormat="mat",numberOfIntervals=2500,variableFilter="Time|bottle_L3_1.insulation.U.[0-9,]+.|bottle_L3_1.volume.h_liq|bottle_L3_1.volume.h_vap|bottle_L3_1.volume.p_vap|bottle_L3_1.volume.volume_vap|bottle_L3_1.wall.U.[0-9,]+.",fileNamePrefix="ClaRa_ClaRa.Components.MechanicalSeparation.Check.TestBottle") [Timeout 800]
"Notification: Performance of FrontEnd - loaded program: time 1.794e-06/1.794e-06, allocations: 0 / 469.9 MB, free: 19 MB / 410.8 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.727e-05/1.907e-05, allocations: 2.969 kB / 469.9 MB, free: 19 MB / 410.8 MB
Notification: TILMedia requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Automatically loaded package TILMedia 1.9.0 ClaRa due to usage.
Notification: Performance of NFInst.instantiate(ClaRa.Components.MechanicalSeparation.Check.TestBottle): time 0.1315/0.1315, allocations: 72.39 MB / 0.5296 GB, free: 15.54 MB / 458.8 MB
Notification: Performance of NFInst.instExpressions: time 0.02651/0.158, allocations: 23.33 MB / 0.5524 GB, free: 8.141 MB / 474.8 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002609/0.1606, allocations: 127.1 kB / 0.5525 GB, free: 8.016 MB / 474.8 MB
Notification: Performance of NFTyping.typeComponents: time 0.003782/0.1644, allocations: 2.538 MB / 0.555 GB, free: 5.465 MB / 474.8 MB
Notification: Performance of NFTyping.typeBindings: time 0.005509/0.1699, allocations: 4.307 MB / 0.5592 GB, free: 1.133 MB / 474.8 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005601/0.1755, allocations: 4.097 MB / 0.5632 GB, free: 13.03 MB / 490.8 MB
Notification: Performance of NFFlatten.flatten: time 0.01036/0.1859, allocations: 13.52 MB / 0.5764 GB, free: 15.47 MB / 0.4949 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004023/0.1899, allocations: 4.156 MB / 0.5805 GB, free: 11.24 MB / 0.4949 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.1166/0.3066, allocations: 52.49 MB / 0.6317 GB, free: 7.895 MB / 0.5418 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.006628/0.3132, allocations: 4.792 MB / 0.6364 GB, free: 3.09 MB / 0.5418 GB
Notification: Performance of NFPackage.collectConstants: time 0.00131/0.3145, allocations: 0.8047 MB / 0.6372 GB, free: 2.285 MB / 0.5418 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.2172/0.5317, allocations: 2.632 MB / 0.6398 GB, free: 81.12 MB / 0.5418 GB
Notification: Performance of NFScalarize.scalarize: time 0.003503/0.5352, allocations: 2.825 MB / 0.6425 GB, free: 81.12 MB / 0.5418 GB
Notification: Performance of NFVerifyModel.verify: time 0.00487/0.5401, allocations: 2.811 MB / 0.6453 GB, free: 81.12 MB / 0.5418 GB
Notification: Performance of NFConvertDAE.convert: time 0.01116/0.5512, allocations: 8.979 MB / 0.654 GB, free: 80.46 MB / 0.5418 GB
Notification: Performance of FrontEnd - DAE generated: time 5.059e-06/0.5513, allocations: 0 / 0.654 GB, free: 80.46 MB / 0.5418 GB
Notification: Performance of FrontEnd: time 1.853e-06/0.5513, allocations: 0 / 0.654 GB, free: 80.46 MB / 0.5418 GB
Notification: Performance of Transformations before backend: time 0.0002564/0.5515, allocations: 0 / 0.654 GB, free: 80.46 MB / 0.5418 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1666
 * Number of variables: 1666
Notification: Performance of Generate backend data structure: time 0.01119/0.5627, allocations: 9.143 MB / 0.663 GB, free: 76.64 MB / 0.5418 GB
Notification: Performance of prepare preOptimizeDAE: time 4.206e-05/0.5627, allocations: 10.88 kB / 0.663 GB, free: 76.64 MB / 0.5418 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002034/0.5648, allocations: 1.36 MB / 0.6643 GB, free: 76.14 MB / 0.5418 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005293/0.5701, allocations: 5.046 MB / 0.6692 GB, free: 74.99 MB / 0.5418 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001618/0.5702, allocations: 334.2 kB / 0.6696 GB, free: 74.98 MB / 0.5418 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000475/0.5707, allocations: 453.5 kB / 0.67 GB, free: 74.98 MB / 0.5418 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01219/0.5829, allocations: 11.76 MB / 0.6815 GB, free: 67.54 MB / 0.5418 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001162/0.583, allocations: 60.31 kB / 0.6815 GB, free: 67.54 MB / 0.5418 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000466/0.5835, allocations: 228.3 kB / 0.6817 GB, free: 67.53 MB / 0.5418 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003639/0.5838, allocations: 443.3 kB / 0.6822 GB, free: 67.52 MB / 0.5418 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0109/0.5947, allocations: 5.693 MB / 0.6877 GB, free: 67.41 MB / 0.5418 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.02836/0.6231, allocations: 63.39 MB / 0.7496 GB, free: 9.523 MB / 0.5418 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008784/0.6319, allocations: 5.222 MB / 0.7547 GB, free: 7.172 MB / 0.5418 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002122/0.634, allocations: 1.527 MB / 0.7562 GB, free: 6.477 MB / 0.5418 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005965/0.6346, allocations: 355.4 kB / 0.7566 GB, free: 6.363 MB / 0.5418 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007427/0.642, allocations: 4.562 MB / 0.761 GB, free: 3.973 MB / 0.5418 GB
Notification: Performance of pre-optimization done (n=827): time 1.026e-05/0.642, allocations: 0 / 0.761 GB, free: 3.973 MB / 0.5418 GB
Notification: Performance of matching and sorting (n=828): time 0.045/0.687, allocations: 34.77 MB / 0.795 GB, free: 7.859 MB / 0.573 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004189/0.6875, allocations: 1.533 MB / 0.7965 GB, free: 5.973 MB / 0.573 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00627/0.6937, allocations: 5.455 MB / 0.8018 GB, free: 0.5078 MB / 0.573 GB
Notification: Performance of collectPreVariables (initialization): time 0.0008747/0.6946, allocations: 97.7 kB / 0.8019 GB, free: 416 kB / 0.573 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001904/0.6965, allocations: 3.291 MB / 0.8051 GB, free: 13.12 MB / 0.5887 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002054/0.6986, allocations: 2.901 MB / 0.8079 GB, free: 10.27 MB / 0.5887 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002568/0.7011, allocations: 2.066 MB / 0.81 GB, free: 8.184 MB / 0.5887 GB
Notification: Performance of setup shared object (initialization): time 9.09e-05/0.7012, allocations: 480.2 kB / 0.8104 GB, free: 7.715 MB / 0.5887 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002838/0.7041, allocations: 1.799 MB / 0.8122 GB, free: 5.891 MB / 0.5887 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005241/0.7093, allocations: 6.874 MB / 0.8189 GB, free: 13.05 MB / 0.6043 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0115/0.7208, allocations: 9.97 MB / 0.8286 GB, free: 1.824 MB / 0.6043 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.288e-05/0.7208, allocations: 24 kB / 0.8286 GB, free: 1.801 MB / 0.6043 GB
Notification: Performance of matching and sorting (n=1231) (initialization): time 0.01278/0.7336, allocations: 8.266 MB / 0.8367 GB, free: 10.06 MB / 0.6199 GB
Notification: Performance of prepare postOptimizeDAE: time 8.802e-05/0.7337, allocations: 108.2 kB / 0.8368 GB, free: 9.945 MB / 0.6199 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.00832/0.742, allocations: 5.2 MB / 0.8419 GB, free: 4.711 MB / 0.6199 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001599/0.7436, allocations: 0.5821 MB / 0.8425 GB, free: 4.145 MB / 0.6199 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004813/0.7484, allocations: 2.399 MB / 0.8448 GB, free: 1.895 MB / 0.6199 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008357/0.7568, allocations: 16.6 MB / 0.861 GB, free: 456 kB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003676/0.7605, allocations: 404.6 kB / 0.8614 GB, free: 56 kB / 0.6355 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007002/0.7612, allocations: 0.5859 MB / 0.862 GB, free: 15.47 MB / 0.6512 GB
Warning: Assuming fixed start value for the following 3 variables:
         bottle_L3_1.volume.h_liq:VARIABLE(start = bottle_L3_1.volume.h_liq_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of liquid phase\" type: Real
         bottle_L3_1.volume.h_vap:VARIABLE(start = bottle_L3_1.volume.h_vap_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of vapour phase\" type: Real
         bottle_L3_1.volume.p_vap:VARIABLE(min = 0.0 start = bottle_L3_1.volume.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"Vapour pressure\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004418/0.7656, allocations: 1.997 MB / 0.8639 GB, free: 13.46 MB / 0.6512 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.005155/0.7707, allocations: 6.843 MB / 0.8706 GB, free: 4.656 MB / 0.6512 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.2537/1.024, allocations: 9.889 MB / 0.8803 GB, free: 172.2 MB / 0.6512 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.108e-05/1.025, allocations: 22.53 kB / 0.8803 GB, free: 172.2 MB / 0.6512 GB
Notification: Performance of matching and sorting (n=1231) (initialization_lambda0): time 0.01254/1.037, allocations: 8.225 MB / 0.8883 GB, free: 171.8 MB / 0.6512 GB
Notification: Performance of prepare postOptimizeDAE: time 6.493e-05/1.037, allocations: 108 kB / 0.8884 GB, free: 171.8 MB / 0.6512 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.007973/1.045, allocations: 5.135 MB / 0.8934 GB, free: 171.4 MB / 0.6512 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001343/1.046, allocations: 0.5747 MB / 0.894 GB, free: 171.3 MB / 0.6512 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005074/1.051, allocations: 2.395 MB / 0.8963 GB, free: 171.3 MB / 0.6512 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006202/1.058, allocations: 16.59 MB / 0.9125 GB, free: 155.9 MB / 0.6512 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003488/1.061, allocations: 403.8 kB / 0.9129 GB, free: 155.9 MB / 0.6512 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007109/1.062, allocations: 0.5763 MB / 0.9135 GB, free: 155.9 MB / 0.6512 GB
Warning: Assuming fixed start value for the following 3 variables:
         bottle_L3_1.volume.h_liq:VARIABLE(start = bottle_L3_1.volume.h_liq_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of liquid phase\" type: Real
         bottle_L3_1.volume.h_vap:VARIABLE(start = bottle_L3_1.volume.h_vap_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of vapour phase\" type: Real
         bottle_L3_1.volume.p_vap:VARIABLE(min = 0.0 start = bottle_L3_1.volume.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"Vapour pressure\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 388
 * Number of states: 0 ()
 * Number of discrete variables: 16 (timeTable1.nextEventScaled,timeTable1.nextEvent,$PRE.timeTable1.nextEvent,timeTable1.last,timeTable1.b,timeTable1.a,timeTable.nextEventScaled,timeTable.nextEvent,$PRE.timeTable.nextEvent,timeTable.last,timeTable.b,timeTable.a,$whenCondition2,$whenCondition1,valveVLE_L1_2.pressureLoss.normIsValid,valveVLE_L1_1.pressureLoss.normIsValid)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1204):
 * Single equations (assignments): 1193
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 4
 * Torn equation systems: 5
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 4 systems
   {1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(4,3,87.5%), (1,6,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.001762/1.064, allocations: 0.9118 MB / 0.9144 GB, free: 155.5 MB / 0.6512 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001487/1.065, allocations: 0.8633 MB / 0.9152 GB, free: 155.5 MB / 0.6512 GB
Warning: After manipulating the system with postOptModule wrapFunctionCalls the system is unbalanced. This indicates that the original system is singular. You can use -d=dumpCSE and -d=dumpCSE_verbose for more information.
Error: Too many equations, over-determined system. The model has 1197 equation(s) and 1145 variable(s).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/VLEFluid/MixtureCompatible/VLEFluid_ph.mo:55:3-60:21:writable] Error: (cyclone.fluidIn.q, cyclone.fluidIn.cp, cyclone.fluidIn.cv, cyclone.fluidIn.beta, cyclone.fluidIn.kappa, cyclone.fluidIn.drhodp_hxi, cyclone.fluidIn.drhodh_pxi, _, cyclone.fluidIn.w, cyclone.fluidIn.gamma) = ($cse36, $cse37, $cse38, $cse39, $cse40, $cse41, $cse42, $cse43, $cse44, $cse45) has size 9 but 7 variables ($whenCondition1, timeTable.a, timeTable.b, timeTable.last, timeTable.nextEvent, timeTable.nextEventScaled, timeTable.timeScaled)
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.01103/1.076, allocations: 9.548 MB / 0.9246 GB, free: 154.9 MB / 0.6512 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 799]
[Calling sys.exit(0), Time elapsed: 3.532307416666299]