Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt 0.5.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt 0.5.1/package.mo): time 0.009448/0.009448, allocations: 1.766 MB / 20.61 MB, free: 3.863 MB / 13.93 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 1.9.0-ClaRa/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 1.9.0-ClaRa/package.mo): time 0.08309/0.08309, allocations: 25.61 MB / 49.71 MB, free: 2.715 MB / 33.03 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.0009318/0.0009318, allocations: 114.1 kB / 60.66 MB, free: 2.906 MB / 44.04 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.00105/0.00105, allocations: 212.3 kB / 71.73 MB, free: 6.465 MB / 44.04 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.9568/0.9568, allocations: 230.6 MB / 313.3 MB, free: 11.78 MB / 234.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0-main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0-main/package.mo): time 0.7786/0.7786, allocations: 130.8 MB / 0.4964 GB, free: 2.387 MB / 362.8 MB
"
[Timeout remaining time 179]
Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0-main/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 TILMedia with version 1.9.0 ClaRa (/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 1.9.0-ClaRa/package.mo)
Using package SMArtInt with version 0.5.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt 0.5.1/package.mo)
Running command: setCommandLineOptions("--fmiFlags=s:cvode")
Running command: "" <> buildModelFMU(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple,fileNamePrefix="ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CU_simple",fmuType="me_cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple,fileNamePrefix="ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CU_simple",fmuType="me_cs",version="2.0",platforms={"static"}) [Timeout 800]
"Notification: SMArtInt 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: 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: ClaRa 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: Performance of FrontEnd - loaded program: time 0.002574/0.002574, allocations: 156.2 kB / 0.6902 GB, free: 46.71 MB / 0.5105 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1306/0.1331, allocations: 89.26 MB / 0.7773 GB, free: 3.074 MB / 0.5418 GB
Notification: Performance of NFInst.instantiate(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple): time 0.01939/0.1525, allocations: 25.18 MB / 0.8019 GB, free: 9.785 MB / 0.573 GB
Notification: Performance of NFInst.instExpressions: time 0.03275/0.1853, allocations: 25.25 MB / 0.8266 GB, free: 472 kB / 0.5887 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005028/0.1903, allocations: 170.8 kB / 0.8267 GB, free: 300 kB / 0.5887 GB
Notification: Performance of NFTyping.typeComponents: time 0.005183/0.1955, allocations: 3.113 MB / 0.8298 GB, free: 13.16 MB / 0.6043 GB
Notification: Performance of NFTyping.typeBindings: time 0.008643/0.2041, allocations: 4.927 MB / 0.8346 GB, free: 8.215 MB / 0.6043 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007206/0.2113, allocations: 4.92 MB / 0.8394 GB, free: 3.301 MB / 0.6043 GB
Notification: Performance of NFFlatten.flatten: time 0.01271/0.224, allocations: 13.9 MB / 0.853 GB, free: 5.363 MB / 0.6199 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.005078/0.2291, allocations: 4.389 MB / 0.8573 GB, free: 0.9023 MB / 0.6199 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007788/0.2369, allocations: 5.684 MB / 0.8628 GB, free: 11.2 MB / 0.6355 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00767/0.2446, allocations: 5.701 MB / 0.8684 GB, free: 5.477 MB / 0.6355 GB
Notification: Performance of NFPackage.collectConstants: time 0.002046/0.2466, allocations: 0.9375 MB / 0.8693 GB, free: 4.539 MB / 0.6355 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005216/0.2518, allocations: 2.784 MB / 0.872 GB, free: 1.754 MB / 0.6355 GB
Notification: Performance of NFScalarize.scalarize: time 0.001789/0.2536, allocations: 2.986 MB / 0.8749 GB, free: 14.76 MB / 0.6512 GB
Notification: Performance of NFVerifyModel.verify: time 0.00369/0.2573, allocations: 3.716 MB / 0.8786 GB, free: 11.02 MB / 0.6512 GB
Notification: Performance of NFConvertDAE.convert: time 0.01415/0.2715, allocations: 14.8 MB / 0.893 GB, free: 12.17 MB / 0.6668 GB
Notification: Performance of FrontEnd - DAE generated: time 3.737e-06/0.2715, allocations: 3.938 kB / 0.893 GB, free: 12.16 MB / 0.6668 GB
Notification: Performance of FrontEnd: time 1.562e-06/0.2715, allocations: 0 / 0.893 GB, free: 12.16 MB / 0.6668 GB
Notification: Performance of Transformations before backend: time 0.0003015/0.2718, allocations: 0 / 0.893 GB, free: 12.16 MB / 0.6668 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1739
 * Number of variables: 1739
Notification: Performance of Generate backend data structure: time 0.01381/0.2856, allocations: 10.96 MB / 0.9037 GB, free: 1.109 MB / 0.6668 GB
Notification: Performance of prepare preOptimizeDAE: time 4.325e-05/0.2856, allocations: 8.031 kB / 0.9037 GB, free: 1.102 MB / 0.6668 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0007437/0.2864, allocations: 0.8075 MB / 0.9045 GB, free: 292 kB / 0.6668 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.3182/0.6046, allocations: 1.145 MB / 0.9056 GB, free: 57.73 MB / 0.6668 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01203/0.6166, allocations: 6.95 MB / 0.9124 GB, free: 56.95 MB / 0.6668 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003067/0.617, allocations: 345.8 kB / 0.9127 GB, free: 56.93 MB / 0.6668 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001213/0.6182, allocations: 445.5 kB / 0.9132 GB, free: 56.93 MB / 0.6668 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02252/0.6407, allocations: 14.22 MB / 0.9271 GB, free: 50.95 MB / 0.6668 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001082/0.6408, allocations: 53.91 kB / 0.9271 GB, free: 50.95 MB / 0.6668 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005664/0.6414, allocations: 230.1 kB / 0.9273 GB, free: 50.95 MB / 0.6668 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0004881/0.6419, allocations: 427.6 kB / 0.9277 GB, free: 50.94 MB / 0.6668 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01863/0.6605, allocations: 9.892 MB / 0.9374 GB, free: 50.9 MB / 0.6668 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.04451/0.705, allocations: 46.27 MB / 0.9826 GB, free: 15.24 MB / 0.6668 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0169/0.7219, allocations: 8.118 MB / 0.9905 GB, free: 10.31 MB / 0.6668 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004457/0.7263, allocations: 3.055 MB / 0.9935 GB, free: 7.172 MB / 0.6668 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0007277/0.7271, allocations: 265.4 kB / 0.9937 GB, free: 6.914 MB / 0.6668 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01057/0.7376, allocations: 6.551 MB / 1 GB, free: 224 kB / 0.6668 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.002775/0.7404, allocations: 0.7172 MB / 1.001 GB, free: 15.68 MB / 0.6824 GB
Notification: Performance of pre-optimization done (n=865): time 1.495e-05/0.7404, allocations: 0 / 1.001 GB, free: 15.68 MB / 0.6824 GB
Notification: Performance of matching and sorting (n=866): time 0.07117/0.8116, allocations: 56.48 MB / 1.056 GB, free: 6.922 MB / 0.7293 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0006714/0.8123, allocations: 1.816 MB / 1.058 GB, free: 4.691 MB / 0.7293 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008418/0.8207, allocations: 6.489 MB / 1.064 GB, free: 14.33 MB / 0.7449 GB
Notification: Performance of collectPreVariables (initialization): time 0.001447/0.8221, allocations: 98.67 kB / 1.064 GB, free: 14.23 MB / 0.7449 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00238/0.8245, allocations: 3.281 MB / 1.067 GB, free: 11.04 MB / 0.7449 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002982/0.8275, allocations: 2.978 MB / 1.07 GB, free: 8.102 MB / 0.7449 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003269/0.8308, allocations: 1.917 MB / 1.072 GB, free: 6.172 MB / 0.7449 GB
Notification: Performance of setup shared object (initialization): time 6.419e-05/0.8308, allocations: 301.1 kB / 1.072 GB, free: 5.875 MB / 0.7449 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006053/0.8369, allocations: 4.149 MB / 1.077 GB, free: 1.711 MB / 0.7449 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009468/0.8464, allocations: 7.936 MB / 1.084 GB, free: 8.348 MB / 0.7605 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1616/1.008, allocations: 51.44 MB / 1.135 GB, free: 4.09 MB / 0.8074 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.902e-05/1.008, allocations: 27.98 kB / 1.135 GB, free: 4.062 MB / 0.8074 GB
Notification: Performance of matching and sorting (n=1248) (initialization): time 0.02043/1.028, allocations: 12.59 MB / 1.147 GB, free: 7.91 MB / 0.823 GB
Notification: Performance of prepare postOptimizeDAE: time 5.84e-05/1.029, allocations: 40.94 kB / 1.147 GB, free: 7.871 MB / 0.823 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.01333/1.042, allocations: 9.432 MB / 1.156 GB, free: 14.43 MB / 0.8387 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02256/1.064, allocations: 4.152 MB / 1.16 GB, free: 10.29 MB / 0.8387 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00634/1.071, allocations: 2.541 MB / 1.163 GB, free: 7.887 MB / 0.8387 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008361/1.079, allocations: 12.24 MB / 1.175 GB, free: 11.02 MB / 0.8543 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004747/1.084, allocations: 451.7 kB / 1.175 GB, free: 10.58 MB / 0.8543 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00114/1.085, allocations: 0.5816 MB / 1.176 GB, free: 9.996 MB / 0.8543 GB
Warning: Assuming fixed start value for the following 4 variables:
         hex.shell.h_liq:VARIABLE(start = hex.shell.h_liq_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of liquid phase\" type: Real
         hex.shell.h_vap:VARIABLE(start = hex.shell.h_vap_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of vapour phase\" type: Real
         hex.shell.p_vap:VARIABLE(min = 0.0 start = hex.shell.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"Vapour pressure\" type: Real
         PI.switch_OnOff.u2:DISCRETE(flow=false fixed = true )  \"Connector of Boolean input signal\" type: Boolean
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008473/1.093, allocations: 5.833 MB / 1.181 GB, free: 4.156 MB / 0.8543 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.008358/1.102, allocations: 7.896 MB / 1.189 GB, free: 10.83 MB / 0.8699 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.2025/1.304, allocations: 51.35 MB / 1.239 GB, free: 6.656 MB / 0.9168 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.472e-05/1.304, allocations: 24 kB / 1.239 GB, free: 6.633 MB / 0.9168 GB
Notification: Performance of matching and sorting (n=1248) (initialization_lambda0): time 0.3188/1.623, allocations: 12.5 MB / 1.251 GB, free: 340.8 MB / 0.9168 GB
Notification: Performance of prepare postOptimizeDAE: time 7.842e-05/1.623, allocations: 40.83 kB / 1.251 GB, free: 340.8 MB / 0.9168 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.01797/1.641, allocations: 9.322 MB / 1.261 GB, free: 338.1 MB / 0.9168 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02376/1.665, allocations: 4.041 MB / 1.264 GB, free: 337.9 MB / 0.9168 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008075/1.673, allocations: 2.515 MB / 1.267 GB, free: 337.8 MB / 0.9168 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.008271/1.681, allocations: 11.59 MB / 1.278 GB, free: 328.1 MB / 0.9168 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006047/1.687, allocations: 449.9 kB / 1.279 GB, free: 327.9 MB / 0.9168 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001581/1.689, allocations: 0.5765 MB / 1.279 GB, free: 327.8 MB / 0.9168 GB
Warning: Assuming fixed start value for the following 4 variables:
         hex.shell.h_liq:VARIABLE(start = hex.shell.h_liq_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of liquid phase\" type: Real
         hex.shell.h_vap:VARIABLE(start = hex.shell.h_vap_start unit = \"J/kg\" fixed = true nominal = 1000.0 )  \"Specific enthalpy of vapour phase\" type: Real
         hex.shell.p_vap:VARIABLE(min = 0.0 start = hex.shell.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"Vapour pressure\" type: Real
         PI.switch_OnOff.u2:DISCRETE(flow=false fixed = true )  \"Connector of Boolean input signal\" type: Boolean
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 370
 * Number of states: 0 ()
 * Number of discrete variables: 7 ($PRE.PI.time_lag_I_activation.entryTime,$PRE.PI.switch_OnOff.u2,PI.I_activation.y,PI.time_lag_I_activation.entryTime,PI.switch_OnOff.u2,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1214):
 * Single equations (assignments): 1206
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 5
 * Torn equation systems: 3
 * 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): 5 systems
   {1, 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): 2 systems
   {(4,3,87.5%), (1,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(3,20)}
Notification: Performance of prepare postOptimizeDAE: time 0.00215/1.691, allocations: 0.7385 MB / 1.28 GB, free: 327.4 MB / 0.9168 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002061/1.693, allocations: 0.803 MB / 1.281 GB, free: 327.4 MB / 0.9168 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 1325 equation(s) and 1265 variable(s).
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.01929/1.712, allocations: 11.18 MB / 1.292 GB, free: 323.6 MB / 0.9168 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 798]
[Calling sys.exit(0), Time elapsed: 4.6366464756429195]