Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple.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.009936/0.009936, allocations: 1.752 MB / 21.38 MB, free: 0.5625 MB / 14.72 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.001224/0.001224, allocations: 108.1 kB / 24.98 MB, free: 2.156 MB / 14.72 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.001406/0.001406, allocations: 203.2 kB / 28.7 MB, free: 3.441 MB / 19.63 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 1.413/1.413, allocations: 230.6 MB / 262.9 MB, free: 6.012 MB / 206.1 MB
"
[Timeout remaining time 178]
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 1.043/1.043, allocations: 130.7 MB / 450.5 MB, free: 6.57 MB / 350.1 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 0.3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: "" <> buildModelFMU(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple,fileNamePrefix="ClaRa_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CU_simple",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple,fileNamePrefix="ClaRa_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CU_simple",fmuType="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: 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: Skipped loading package TILMedia (1.9.0 ClaRa) using MODELICAPATH /home/hudson/saved_omc/libraries/.openmodelica/libraries (uses-annotation may be wrong).
Warning: Requested package SMArtInt of version 0.5.1, but this package was already loaded with version 0.3.1. There are no conversion annotations for this version but 0.5.1 is newer than 0.3.1. There is a possibility that 0.3.1 remains backwards compatible, but it is not loaded so OpenModelica cannot verify this.
Notification: Performance of FrontEnd - loaded program: time 0.03247/0.03247, allocations: 20.61 MB / 0.6363 GB, free: 5.293 MB / 462.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1801/0.2126, allocations: 81.9 MB / 0.7163 GB, free: 3.309 MB / 0.5294 GB
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.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CU_simple): time 0.5634/0.776, allocations: 62.04 MB / 0.7769 GB, free: 8.781 MB / 0.5607 GB
Notification: Performance of NFInst.instExpressions: time 0.0525/0.8285, allocations: 26.32 MB / 0.8026 GB, free: 5.801 MB / 0.5763 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01162/0.8401, allocations: 161.6 kB / 0.8028 GB, free: 5.727 MB / 0.5763 GB
Notification: Performance of NFTyping.typeComponents: time 0.01162/0.8517, allocations: 3.273 MB / 0.806 GB, free: 3.41 MB / 0.5763 GB
Notification: Performance of NFTyping.typeBindings: time 0.01944/0.8712, allocations: 4.914 MB / 0.8108 GB, free: 15.5 MB / 0.5919 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01659/0.8878, allocations: 4.952 MB / 0.8156 GB, free: 11.73 MB / 0.5919 GB
Notification: Performance of NFFlatten.flatten: time 0.0289/0.9167, allocations: 13.91 MB / 0.8292 GB, free: 15.7 MB / 0.6075 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01065/0.9273, allocations: 4.162 MB / 0.8332 GB, free: 11.47 MB / 0.6075 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01439/0.9417, allocations: 5.657 MB / 0.8388 GB, free: 5.797 MB / 0.6075 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01274/0.9545, allocations: 6.003 MB / 0.8446 GB, free: 15.77 MB / 0.6232 GB
Notification: Performance of NFPackage.collectConstants: time 0.004471/0.9589, allocations: 0.9401 MB / 0.8456 GB, free: 14.84 MB / 0.6232 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01196/0.9709, allocations: 3.67 MB / 0.8491 GB, free: 11.17 MB / 0.6232 GB
Notification: Performance of NFScalarize.scalarize: time 0.006076/0.977, allocations: 2.986 MB / 0.8521 GB, free: 8.176 MB / 0.6232 GB
Notification: Performance of NFVerifyModel.verify: time 0.01339/0.9904, allocations: 4.785 MB / 0.8567 GB, free: 3.367 MB / 0.6232 GB
Notification: Performance of NFConvertDAE.convert: time 0.03177/1.022, allocations: 14.92 MB / 0.8713 GB, free: 4.398 MB / 0.6388 GB
Notification: Performance of FrontEnd - DAE generated: time 8.035e-06/1.022, allocations: 4 kB / 0.8713 GB, free: 4.395 MB / 0.6388 GB
Notification: Performance of FrontEnd: time 1.223e-06/1.022, allocations: 0 / 0.8713 GB, free: 4.395 MB / 0.6388 GB
Notification: Performance of Transformations before backend: time 0.000539/1.023, allocations: 2.656 kB / 0.8713 GB, free: 4.395 MB / 0.6388 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.03471/1.057, allocations: 10.96 MB / 0.882 GB, free: 9.375 MB / 0.6544 GB
Notification: Performance of prepare preOptimizeDAE: time 8.091e-05/1.057, allocations: 15.41 kB / 0.882 GB, free: 9.363 MB / 0.6544 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0003/1.058, allocations: 133.1 kB / 0.8821 GB, free: 9.23 MB / 0.6544 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.003894/1.062, allocations: 0.8114 MB / 0.8829 GB, free: 8.41 MB / 0.6544 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006777/1.068, allocations: 1.153 MB / 0.8841 GB, free: 7.254 MB / 0.6544 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01978/1.088, allocations: 6.934 MB / 0.8908 GB, free: 268 kB / 0.6544 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006173/1.089, allocations: 349 kB / 0.8912 GB, free: 15.91 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002597/1.091, allocations: 447.8 kB / 0.8916 GB, free: 15.48 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03421/1.126, allocations: 12.17 MB / 0.9035 GB, free: 2.125 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002786/1.126, allocations: 51.5 kB / 0.9035 GB, free: 2.074 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002031/1.128, allocations: 227.9 kB / 0.9037 GB, free: 1.852 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0006597/1.129, allocations: 430.9 kB / 0.9042 GB, free: 1.43 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03021/1.159, allocations: 9.924 MB / 0.9138 GB, free: 7.516 MB / 0.6857 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.07693/1.236, allocations: 49.03 MB / 0.9617 GB, free: 5.074 MB / 0.7325 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.02345/1.259, allocations: 8.153 MB / 0.9697 GB, free: 12.77 MB / 0.7482 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0074/1.267, allocations: 3.054 MB / 0.9727 GB, free: 9.641 MB / 0.7482 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001218/1.268, allocations: 304.8 kB / 0.973 GB, free: 9.348 MB / 0.7482 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.02006/1.288, allocations: 6.54 MB / 0.9794 GB, free: 2.664 MB / 0.7482 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.004501/1.292, allocations: 0.716 MB / 0.9801 GB, free: 1.945 MB / 0.7482 GB
Notification: Performance of pre-optimization done (n=865): time 2.057e-05/1.292, allocations: 4 kB / 0.9801 GB, free: 1.941 MB / 0.7482 GB
Notification: Performance of matching and sorting (n=866): time 0.1128/1.405, allocations: 56.48 MB / 1.035 GB, free: 9.105 MB / 0.8107 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.001109/1.406, allocations: 1.818 MB / 1.037 GB, free: 6.867 MB / 0.8107 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01164/1.418, allocations: 6.356 MB / 1.043 GB, free: 0.5273 MB / 0.8107 GB
Notification: Performance of collectPreVariables (initialization): time 0.001716/1.42, allocations: 101.7 kB / 1.043 GB, free: 432 kB / 0.8107 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003418/1.423, allocations: 3.285 MB / 1.047 GB, free: 13.11 MB / 0.8263 GB
Notification: Performance of collectInitialBindings (initialization): time 0.004538/1.428, allocations: 2.978 MB / 1.049 GB, free: 10.11 MB / 0.8263 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004151/1.432, allocations: 1.906 MB / 1.051 GB, free: 8.191 MB / 0.8263 GB
Notification: Performance of setup shared object (initialization): time 0.0001254/1.432, allocations: 307.8 kB / 1.052 GB, free: 7.891 MB / 0.8263 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008579/1.441, allocations: 4.151 MB / 1.056 GB, free: 3.73 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.3771/1.818, allocations: 7.018 MB / 1.062 GB, free: 346.3 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.3268/2.144, allocations: 51.44 MB / 1.113 GB, free: 319.8 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.000179/2.145, allocations: 20 kB / 1.113 GB, free: 319.8 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=1248) (initialization): time 0.03259/2.177, allocations: 12.59 MB / 1.125 GB, free: 307.6 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000211/2.177, allocations: 40 kB / 1.125 GB, free: 307.5 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.02314/2.201, allocations: 9.423 MB / 1.134 GB, free: 298 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04612/2.247, allocations: 4.161 MB / 1.138 GB, free: 293.9 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009663/2.256, allocations: 2.545 MB / 1.141 GB, free: 291.4 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01079/2.267, allocations: 12.3 MB / 1.153 GB, free: 278.4 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007887/2.275, allocations: 444.3 kB / 1.153 GB, free: 278 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002236/2.277, allocations: 0.5816 MB / 1.154 GB, free: 277.4 MB / 0.8263 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.01266/2.29, allocations: 5.834 MB / 1.16 GB, free: 271.6 MB / 0.8263 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.009561/2.299, allocations: 6.986 MB / 1.166 GB, free: 263.3 MB / 0.8263 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.3198/2.619, allocations: 51.33 MB / 1.216 GB, free: 210.9 MB / 0.8263 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.918e-05/2.619, allocations: 28 kB / 1.216 GB, free: 210.9 MB / 0.8263 GB
Notification: Performance of matching and sorting (n=1248) (initialization_lambda0): time 0.02962/2.649, allocations: 12.5 MB / 1.229 GB, free: 198.7 MB / 0.8263 GB
Notification: Performance of prepare postOptimizeDAE: time 8.901e-05/2.649, allocations: 44.34 kB / 1.229 GB, free: 198.7 MB / 0.8263 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0224/2.671, allocations: 9.318 MB / 1.238 GB, free: 189.3 MB / 0.8263 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04503/2.716, allocations: 4.049 MB / 1.242 GB, free: 185.3 MB / 0.8263 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009365/2.726, allocations: 2.515 MB / 1.244 GB, free: 182.8 MB / 0.8263 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009936/2.736, allocations: 11.63 MB / 1.256 GB, free: 170.5 MB / 0.8263 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008332/2.744, allocations: 444.4 kB / 1.256 GB, free: 170.1 MB / 0.8263 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00245/2.747, allocations: 0.5777 MB / 1.257 GB, free: 169.5 MB / 0.8263 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.00452/2.751, allocations: 0.7475 MB / 1.257 GB, free: 168.8 MB / 0.8263 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00358/2.755, allocations: 0.8165 MB / 1.258 GB, free: 168.3 MB / 0.8263 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.02149/2.776, allocations: 11.13 MB / 1.269 GB, free: 157.6 MB / 0.8263 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 797]
[Calling sys.exit(0), Time elapsed: 6.570950925000943]
Failed to read output from testmodel.py, exit status != 0:
2.960821854067035 2.972910057 1.946251534
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fad308173a0>