Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_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.006009/0.006009, allocations: 1.34 MB / 11.13 MB, free: 3.035 MB / 13.93 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.001301/0.00731, allocations: 0.8268 MB / 11.95 MB, free: 2.203 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.08881/0.08881, allocations: 18.25 MB / 30.93 MB, free: 4.926 MB / 33.03 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.008951/0.09777, allocations: 7.681 MB / 38.62 MB, free: 8.23 MB / 44.04 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.0005696/0.0005696, allocations: 80.02 kB / 39.42 MB, free: 7.426 MB / 44.04 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.0009949/0.001564, allocations: 451.1 kB / 39.86 MB, free: 6.996 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.0007083/0.0007083, allocations: 156.6 kB / 40.75 MB, free: 6.113 MB / 44.04 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.0009389/0.001647, allocations: 474.5 kB / 41.21 MB, free: 5.648 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.927/0.927, allocations: 177.2 MB / 219.1 MB, free: 11.4 MB / 218.7 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.11/1.037, allocations: 53.66 MB / 272.8 MB, free: 5.742 MB / 266.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.7164/0.7164, allocations: 102.5 MB / 376.1 MB, free: 8.809 MB / 362.8 MB
Notification: Performance of NFInstanceAPI.mkTop(<interactive>): time 0.04214/0.7586, allocations: 28.55 MB / 404.6 MB, free: 12.29 MB / 394.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_CH_simple,fileNamePrefix="ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple",fmuType="me_cs",version="2.0+3.0",platforms={"static"})
"" <> buildModelFMU(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple,fileNamePrefix="ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple",fmuType="me_cs",version="2.0+3.0",platforms={"static"}) [Timeout 800]
"Notification: Performance of FrontEnd - loaded program: time 1.553e-06/1.553e-06, allocations: 0 / 406.4 MB, free: 10.46 MB / 394.8 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.821e-05/1.977e-05, allocations: 2.312 kB / 406.4 MB, free: 10.46 MB / 394.8 MB
Notification: Performance of NFInst.instantiate(ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple): time 0.02867/0.02869, allocations: 25.12 MB / 431.6 MB, free: 1.242 MB / 410.8 MB
Notification: Performance of NFInst.instExpressions: time 0.03338/0.06207, allocations: 25.03 MB / 456.6 MB, free: 8.129 MB / 442.8 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001979/0.06404, allocations: 139 kB / 456.7 MB, free: 7.992 MB / 442.8 MB
Notification: Performance of NFTyping.typeComponents: time 0.003506/0.06755, allocations: 2.942 MB / 459.7 MB, free: 5.035 MB / 442.8 MB
Notification: Performance of NFTyping.typeBindings: time 0.004799/0.07235, allocations: 4.477 MB / 464.2 MB, free: 0.5312 MB / 442.8 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004615/0.07696, allocations: 4.416 MB / 468.6 MB, free: 12.11 MB / 458.8 MB
Notification: Performance of NFFlatten.flatten: time 0.007756/0.08472, allocations: 12.75 MB / 481.3 MB, free: 15.32 MB / 474.8 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.003023/0.08774, allocations: 4.144 MB / 485.5 MB, free: 11.08 MB / 474.8 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.3058/0.3936, allocations: 54.2 MB / 0.527 GB, free: 23.31 MB / 490.8 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006968/0.4006, allocations: 4.311 MB / 0.5312 GB, free: 23.31 MB / 490.8 MB
Notification: Performance of NFPackage.collectConstants: time 0.002111/0.4027, allocations: 0.8065 MB / 0.532 GB, free: 23.31 MB / 490.8 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005662/0.4083, allocations: 2.581 MB / 0.5345 GB, free: 23.31 MB / 490.8 MB
Notification: Performance of NFScalarize.scalarize: time 0.00235/0.4107, allocations: 2.167 MB / 0.5366 GB, free: 23.31 MB / 490.8 MB
Notification: Performance of NFVerifyModel.verify: time 0.00378/0.4145, allocations: 2.164 MB / 0.5388 GB, free: 23.31 MB / 490.8 MB
Notification: Performance of NFConvertDAE.convert: time 0.009634/0.4241, allocations: 7.94 MB / 0.5465 GB, free: 22.65 MB / 490.8 MB
Notification: Performance of FrontEnd - DAE generated: time 1.571e-05/0.4241, allocations: 0 / 0.5465 GB, free: 22.65 MB / 490.8 MB
Notification: Performance of FrontEnd: time 1.794e-06/0.4241, allocations: 1.031 kB / 0.5465 GB, free: 22.65 MB / 490.8 MB
Notification: Performance of Transformations before backend: time 0.0002842/0.4244, allocations: 0 / 0.5465 GB, free: 22.65 MB / 490.8 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1530
 * Number of variables: 1530
Notification: Performance of Generate backend data structure: time 0.01159/0.436, allocations: 9.601 MB / 0.5559 GB, free: 16.35 MB / 490.8 MB
Notification: Performance of prepare preOptimizeDAE: time 3.824e-05/0.436, allocations: 12.75 kB / 0.5559 GB, free: 16.34 MB / 490.8 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.000698/0.4367, allocations: 0.7497 MB / 0.5566 GB, free: 16.09 MB / 490.8 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001451/0.4382, allocations: 0.9268 MB / 0.5575 GB, free: 15.48 MB / 490.8 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004969/0.4431, allocations: 3.69 MB / 0.5611 GB, free: 12.91 MB / 490.8 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001612/0.4433, allocations: 304.3 kB / 0.5614 GB, free: 12.74 MB / 490.8 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004757/0.4438, allocations: 426.4 kB / 0.5618 GB, free: 12.46 MB / 490.8 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.008022/0.4518, allocations: 9.003 MB / 0.5706 GB, free: 3.121 MB / 490.8 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.642e-05/0.4519, allocations: 50.34 kB / 0.5707 GB, free: 3.098 MB / 490.8 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000366/0.4522, allocations: 201 kB / 0.5709 GB, free: 3.031 MB / 490.8 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002488/0.4525, allocations: 401.4 kB / 0.5713 GB, free: 2.684 MB / 490.8 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006184/0.4587, allocations: 3.818 MB / 0.575 GB, free: 15.21 MB / 0.4949 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.02389/0.4826, allocations: 32.93 MB / 0.6071 GB, free: 0.6523 MB / 0.5105 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.006351/0.4889, allocations: 3.981 MB / 0.611 GB, free: 12.98 MB / 0.5262 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001212/0.4901, allocations: 0.9706 MB / 0.612 GB, free: 12.02 MB / 0.5262 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004738/0.4906, allocations: 273.8 kB / 0.6122 GB, free: 11.78 MB / 0.5262 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.005064/0.4957, allocations: 3.465 MB / 0.6156 GB, free: 8.562 MB / 0.5262 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001615/0.4973, allocations: 0.6235 MB / 0.6162 GB, free: 8.031 MB / 0.5262 GB
Notification: Performance of pre-optimization done (n=765): time 1.449e-05/0.4973, allocations: 0 / 0.6162 GB, free: 8.031 MB / 0.5262 GB
Notification: Performance of matching and sorting (n=767): time 0.0185/0.5158, allocations: 10.17 MB / 0.6262 GB, free: 14.32 MB / 0.5418 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0005285/0.5163, allocations: 2.016 MB / 0.6281 GB, free: 11.85 MB / 0.5418 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004136/0.5205, allocations: 4.506 MB / 0.6325 GB, free: 8.445 MB / 0.5418 GB
Notification: Performance of collectPreVariables (initialization): time 0.000325/0.5208, allocations: 99.7 kB / 0.6326 GB, free: 8.391 MB / 0.5418 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001035/0.5218, allocations: 2.063 MB / 0.6346 GB, free: 6.645 MB / 0.5418 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001284/0.5231, allocations: 2.608 MB / 0.6372 GB, free: 4.375 MB / 0.5418 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00129/0.5244, allocations: 1.345 MB / 0.6385 GB, free: 3.191 MB / 0.5418 GB
Notification: Performance of setup shared object (initialization): time 6.237e-05/0.5245, allocations: 301.1 kB / 0.6388 GB, free: 2.895 MB / 0.5418 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001484/0.5259, allocations: 0.8531 MB / 0.6396 GB, free: 2.027 MB / 0.5418 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002499/0.5284, allocations: 4.427 MB / 0.6439 GB, free: 12.42 MB / 0.5574 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005141/0.5336, allocations: 5.373 MB / 0.6492 GB, free: 6.465 MB / 0.5574 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.819e-05/0.5336, allocations: 20 kB / 0.6492 GB, free: 6.445 MB / 0.5574 GB
Notification: Performance of matching and sorting (n=1092) (initialization): time 0.006286/0.5399, allocations: 4.296 MB / 0.6534 GB, free: 2.555 MB / 0.5574 GB
Notification: Performance of prepare postOptimizeDAE: time 3.7e-05/0.5399, allocations: 42.17 kB / 0.6534 GB, free: 2.516 MB / 0.5574 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002417/0.5402, allocations: 239.8 kB / 0.6537 GB, free: 2.281 MB / 0.5574 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02178/0.562, allocations: 3.845 MB / 0.6574 GB, free: 14.46 MB / 0.573 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004029/0.566, allocations: 1.464 MB / 0.6589 GB, free: 13.08 MB / 0.573 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003875/0.5699, allocations: 6.275 MB / 0.665 GB, free: 6.57 MB / 0.573 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003207/0.5731, allocations: 272.1 kB / 0.6652 GB, free: 6.305 MB / 0.573 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004318/0.5735, allocations: 255.9 kB / 0.6655 GB, free: 6.055 MB / 0.573 GB
Warning: Assuming fixed start value for the following 2 variables:
         hex.tubes.h:VARIABLE(start = hex.tubes.h_start unit = \"J/kg\" fixed = true nominal = 1000.0 protected = true )  \"spec. enthalpy state\" 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.002722/0.5762, allocations: 1.032 MB / 0.6665 GB, free: 5.016 MB / 0.573 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002618/0.5788, allocations: 4.412 MB / 0.6708 GB, free: 15.42 MB / 0.5887 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.005119/0.584, allocations: 5.342 MB / 0.676 GB, free: 9.504 MB / 0.5887 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.272e-05/0.584, allocations: 20 kB / 0.676 GB, free: 9.484 MB / 0.5887 GB
Notification: Performance of matching and sorting (n=1092) (initialization_lambda0): time 0.006152/0.5901, allocations: 4.287 MB / 0.6802 GB, free: 5.602 MB / 0.5887 GB
Notification: Performance of prepare postOptimizeDAE: time 3.077e-05/0.5902, allocations: 35.98 kB / 0.6803 GB, free: 5.566 MB / 0.5887 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002306/0.5904, allocations: 239.8 kB / 0.6805 GB, free: 5.332 MB / 0.5887 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.02184/0.6122, allocations: 3.796 MB / 0.6842 GB, free: 1.559 MB / 0.5887 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003546/0.6158, allocations: 1.404 MB / 0.6856 GB, free: 244 kB / 0.5887 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003424/0.6192, allocations: 4.494 MB / 0.69 GB, free: 11.57 MB / 0.6043 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003649/0.6229, allocations: 284.8 kB / 0.6902 GB, free: 11.29 MB / 0.6043 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005769/0.6234, allocations: 251.9 kB / 0.6905 GB, free: 11.05 MB / 0.6043 GB
Warning: Assuming fixed start value for the following 2 variables:
         hex.tubes.h:VARIABLE(start = hex.tubes.h_start unit = \"J/kg\" fixed = true nominal = 1000.0 protected = true )  \"spec. enthalpy state\" 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: 313
 * 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 (651):
 * Single equations (assignments): 580
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 64
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 5
 * Torn equation systems: 2
 * 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): 1 system
   {(4,3,87.5%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(6,18)}
Notification: Performance of prepare postOptimizeDAE: time 0.001102/0.6245, allocations: 0.5693 MB / 0.691 GB, free: 10.55 MB / 0.6043 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009836/0.6255, allocations: 471.1 kB / 0.6915 GB, free: 10.1 MB / 0.6043 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.007113/0.6326, allocations: 5.508 MB / 0.6969 GB, free: 4.512 MB / 0.6043 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.533e-05/0.6327, allocations: 60.02 kB / 0.6969 GB, free: 4.453 MB / 0.6043 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.706e-06/0.6327, allocations: 4 kB / 0.6969 GB, free: 4.449 MB / 0.6043 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.002894/0.6356, allocations: 2.204 MB / 0.6991 GB, free: 2.156 MB / 0.6043 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01005/0.6456, allocations: 7.12 MB / 0.706 GB, free: 11.59 MB / 0.6199 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001604/0.6458, allocations: 151.8 kB / 0.7062 GB, free: 11.44 MB / 0.6199 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002151/0.6479, allocations: 0.7959 MB / 0.7069 GB, free: 10.64 MB / 0.6199 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001893/0.6498, allocations: 1.155 MB / 0.7081 GB, free: 9.465 MB / 0.6199 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02202/0.6718, allocations: 4.031 MB / 0.712 GB, free: 5.422 MB / 0.6199 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002568/0.6721, allocations: 103.9 kB / 0.7121 GB, free: 5.32 MB / 0.6199 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003703/0.6758, allocations: 6.955 MB / 0.7189 GB, free: 14.04 MB / 0.6355 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.282e-06/0.6758, allocations: 11.94 kB / 0.7189 GB, free: 14.03 MB / 0.6355 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.005617/0.6814, allocations: 4.103 MB / 0.7229 GB, free: 9.871 MB / 0.6355 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006679/0.6821, allocations: 87.95 kB / 0.723 GB, free: 9.785 MB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001571/0.6837, allocations: 152.4 kB / 0.7231 GB, free: 9.637 MB / 0.6355 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004586/0.6841, allocations: 314.2 kB / 0.7234 GB, free: 9.328 MB / 0.6355 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.000244/0.6844, allocations: 107.9 kB / 0.7235 GB, free: 9.223 MB / 0.6355 GB
Notification: Performance of sorting global known variables: time 0.002126/0.6865, allocations: 2.222 MB / 0.7257 GB, free: 7.281 MB / 0.6355 GB
Notification: Performance of sort global known variables: time 3.6e-07/0.6865, allocations: 0 / 0.7257 GB, free: 7.281 MB / 0.6355 GB
Notification: Performance of remove unused functions: time 0.005773/0.6923, allocations: 2.736 MB / 0.7284 GB, free: 4.547 MB / 0.6355 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 19
 * Number of states: 15 (PI.smoothPIDInput.y_aux,PI.smoothPIDOutput.y_aux,PI.smoothPIDOutput1.y_aux,PI.resetValueI,hex.tubes.h,hex.shell.h_liq,hex.shell.h_vap,hex.shell.volume_vap,hex.shell.p_vap,hex.wall.U[1],hex.wall.U[2],hex.wall.U[3],PI.resetValueP,PI.resetValueID,PI.I.y)
 * Number of discrete variables: 5 ($whenCondition2,$whenCondition1,PI.switch_OnOff.u2,PI.time_lag_I_activation.entryTime,PI.I_activation.y)
 * Number of discrete states: 2 (PI.switch_OnOff.u2,PI.toPU.y)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (353):
 * Single equations (assignments): 297
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 47
 * When equations: 1
 * If-equations: 0
 * Equation systems (not torn): 4
 * Torn equation systems: 4
 * 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): 2 systems
   {(4,3,87.5%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(1,4), (4,12)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.1897/0.882, allocations: 6.198 MB / 0.7344 GB, free: 180.9 MB / 0.6355 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0-main/Basics/ControlVolumes/FluidVolumes/VolumeVLE_L3_TwoZonesNPort.mo:130:3-130:139:writable] Warning: Variable $DER.hex.shell.volume_vap has attribute stateSelect=StateSelect.always, but can't be selected as a state.
Notification: Performance of simCode: created initialization part: time 0.01111/0.8931, allocations: 9.556 MB / 0.7438 GB, free: 176.5 MB / 0.6355 GB
Notification: Performance of simCode: created event and clocks part: time 0.0001234/0.8932, allocations: 93.34 kB / 0.7439 GB, free: 176.5 MB / 0.6355 GB
Notification: Performance of simCode: created simulation system equations: time 0.002677/0.8959, allocations: 3.093 MB / 0.7469 GB, free: 174.7 MB / 0.6355 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004537/0.9004, allocations: 2.183 MB / 0.749 GB, free: 174.5 MB / 0.6355 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03171/0.9321, allocations: 27.8 MB / 0.7762 GB, free: 165.2 MB / 0.6355 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001185/0.9333, allocations: 1.45 MB / 0.7776 GB, free: 165 MB / 0.6355 GB
Notification: Performance of simCode: alias equations: time 0.003903/0.9372, allocations: 1.639 MB / 0.7792 GB, free: 164.7 MB / 0.6355 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.006006/0.9432, allocations: 4.599 MB / 0.7837 GB, free: 164.6 MB / 0.6355 GB
Notification: Performance of SimCode: time 1.814e-06/0.9432, allocations: 0 / 0.7837 GB, free: 164.6 MB / 0.6355 GB
Notification: FMU-only work: backend 0.1865, simCode 0.01717, templates 0
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.4097/1.353, allocations: 202.6 MB / 0.9816 GB, free: 205.3 MB / 0.6668 GB
Notification: Building FMU for platform 'static' (1/1).
Notification: Finished FMU for platform 'static' (1/1).
Notification: Performance of buildModelFMU: Generate platform static: time 5.303/6.656, allocations: 18.89 kB / 0.9816 GB, free: 205.3 MB / 0.6668 GB
"
[Timeout remaining time 793]
(rm -f ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.pipe ; mkfifo ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.pipe ; head -c 1048576 < ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.pipe >> ../files/ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.sim & (cd run_OMSimulator && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --resultFile=../ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_res.mat --stripRoot --tempDir=temp_ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple_fmu --startTime=0 --stopTime=12000 --stepSize=4.8 --mode=me --timeout=240 --tolerance=1e-05 ../ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple.fmu) > ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
""
[Timeout remaining time 800]
Reference file matches
(rm -f ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode.pipe ; mkfifo ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode.pipe ; head -c 1048576 < ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode.sim & (cd run_OMSimulator-cs-cvode && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --resultFile=../ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode_res.mat --stripRoot --tempDir=temp_ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode_fmu --startTime=0 --stopTime=12000 --stepSize=4.8 --mode=cs --timeout=240 --tolerance=1e-05 ../ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode.fmu) > ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-cvode_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
""
[Timeout remaining time 800]
Reference file matches
(rm -f ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler.pipe ; mkfifo ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler.pipe ; head -c 1048576 < ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler.sim & (cd run_OMSimulator-cs-euler && /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator --resultFile=../ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler_res.mat --stripRoot --tempDir=temp_ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler_fmu --startTime=0 --stopTime=12000 --stepSize=4.8 --mode=cs --timeout=240 --tolerance=1e-05 ../ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler.fmu) > ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_OMSimulator-cs-euler.pipe 2>&1) [Timeout 252.0]
OMSimulator-cs-euler timed out simulating the FMU
(rm -f ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy.pipe ; mkfifo ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy.pipe ; head -c 1048576 < ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy.sim & (cd run_fmpy && python3 -m fmpy simulate --output-file ../ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy_res.csv --output-variables PI.I.y PI.resetValueI PI.resetValueID PI.resetValueP PI.smoothPIDInput.y_aux PI.smoothPIDOutput.y_aux PI.smoothPIDOutput1.y_aux hex.shell.h_liq hex.shell.h_vap hex.shell.p_vap hex.shell.volume_vap hex.tubes.h 'hex.wall.U[1]' 'hex.wall.U[2]' 'hex.wall.U[3]' --start-time 0 --stop-time 12000 --timeout 240 --relative-tolerance 1e-05 --interface-type ModelExchange --solver CVode --output-interval 4.8 ../ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple.fmu) > ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy_res.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
""
[Timeout remaining time 800]
Reference file matches
(rm -f ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode.pipe ; mkfifo ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode.pipe ; head -c 1048576 < ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode.pipe >> /var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode.sim & (cd run_fmpy-fmi3-cs-cvode && python3 -m fmpy simulate --output-file ../ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode_res.csv --output-variables PI.I.y PI.resetValueI PI.resetValueID PI.resetValueP PI.smoothPIDInput.y_aux PI.smoothPIDOutput.y_aux PI.smoothPIDOutput1.y_aux hex.shell.h_liq hex.shell.h_vap hex.shell.p_vap hex.shell.volume_vap hex.tubes.h 'hex.wall.U[1]' 'hex.wall.U[2]' 'hex.wall.U[3]' hex.wall.U --start-time 0 --stop-time 12000 --timeout 240 --relative-tolerance 1e-05 --interface-type CoSimulation --output-interval 4.8 ../ClaRa_dev_ClaRa_Components_HeatExchangers_Check_Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode.fmu) > ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode.pipe 2>&1) [Timeout 252.0]
diffSimulationResults("ClaRa_dev_ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple_fmpy-fmi3-cs-cvode_res.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/ClaRa/ReferenceResults/ClaRa.Components.HeatExchangers.Check.Test_HEXvle2vle_L3_2ph_CH_simple.mat","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 800]
""
[Timeout remaining time 800]
Reference file matches
[Calling sys.exit(0), Time elapsed: 12.498108984902501]