Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Basics.ControlVolumes.FluidVolumes.Check.Validation_VolumeVLE_L2_HeatTransfer_2ph_shell.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.01481/0.01481, allocations: 1.771 MB / 18.83 MB, free: 4.332 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.001564/0.001564, allocations: 99.7 kB / 22.07 MB, free: 1.09 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.002094/0.002094, allocations: 206.8 kB / 25.44 MB, free: 2.797 MB / 14.72 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.508/1.508, allocations: 230.6 MB / 259.2 MB, free: 13.13 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.087/1.087, allocations: 130.6 MB / 446.3 MB, free: 14.86 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.Basics.ControlVolumes.FluidVolumes.Check.Validation_VolumeVLE_L2_HeatTransfer_2ph_shell,fileNamePrefix="ClaRa_ClaRa_Basics_ControlVolumes_FluidVolumes_Check_Validation_VolumeVLE_L2_HeatTransfer_2ph_shell",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(ClaRa.Basics.ControlVolumes.FluidVolumes.Check.Validation_VolumeVLE_L2_HeatTransfer_2ph_shell,fileNamePrefix="ClaRa_ClaRa_Basics_ControlVolumes_FluidVolumes_Check_Validation_VolumeVLE_L2_HeatTransfer_2ph_shell",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 800]
"Warning: Ignoring unknown experiment annotation option: Algorithm = \"Dassl\"
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: Automatically loaded package TILMedia 1.9.0 ClaRa due to uses annotation from ClaRa.
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.
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.4164/0.4164, allocations: 46.16 MB / 0.6552 GB, free: 30.33 MB / 0.4982 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1627/0.5792, allocations: 89.05 MB / 0.7421 GB, free: 2.785 MB / 0.545 GB
Notification: Performance of NFInst.instantiate(ClaRa.Basics.ControlVolumes.FluidVolumes.Check.Validation_VolumeVLE_L2_HeatTransfer_2ph_shell): time 0.01578/0.5949, allocations: 12.63 MB / 0.7545 GB, free: 6.105 MB / 0.5607 GB
Notification: Performance of NFInst.instExpressions: time 0.03382/0.6288, allocations: 21.16 MB / 0.7751 GB, free: 0.8789 MB / 0.5763 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002559/0.6313, allocations: 51.62 kB / 0.7752 GB, free: 0.8281 MB / 0.5763 GB
Notification: Performance of NFTyping.typeComponents: time 0.003991/0.6353, allocations: 1.368 MB / 0.7765 GB, free: 15.45 MB / 0.5919 GB
Notification: Performance of NFTyping.typeBindings: time 0.007596/0.6429, allocations: 2.581 MB / 0.779 GB, free: 12.86 MB / 0.5919 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00607/0.649, allocations: 2.166 MB / 0.7812 GB, free: 10.69 MB / 0.5919 GB
Notification: Performance of NFFlatten.flatten: time 0.007388/0.6564, allocations: 4.292 MB / 0.7853 GB, free: 6.383 MB / 0.5919 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002465/0.6588, allocations: 1.126 MB / 0.7864 GB, free: 5.234 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003293/0.6621, allocations: 1.658 MB / 0.7881 GB, free: 3.57 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.003499/0.6656, allocations: 1.879 MB / 0.7899 GB, free: 1.684 MB / 0.5919 GB
Notification: Performance of NFPackage.collectConstants: time 0.001018/0.6666, allocations: 300 kB / 0.7902 GB, free: 1.391 MB / 0.5919 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.03452/0.7012, allocations: 6.666 MB / 0.7967 GB, free: 11.37 MB / 0.6075 GB
Notification: Performance of NFScalarize.scalarize: time 0.001503/0.7027, allocations: 0.9032 MB / 0.7976 GB, free: 10.46 MB / 0.6075 GB
Notification: Performance of NFVerifyModel.verify: time 0.002204/0.7049, allocations: 1.504 MB / 0.799 GB, free: 8.953 MB / 0.6075 GB
Notification: Performance of NFConvertDAE.convert: time 0.01342/0.7183, allocations: 6.794 MB / 0.8057 GB, free: 2.137 MB / 0.6075 GB
Notification: Performance of FrontEnd - DAE generated: time 1.293e-05/0.7183, allocations: 3.984 kB / 0.8057 GB, free: 2.133 MB / 0.6075 GB
Notification: Performance of FrontEnd: time 2.745e-06/0.7183, allocations: 0 / 0.8057 GB, free: 2.133 MB / 0.6075 GB
Notification: Performance of Transformations before backend: time 0.0001191/0.7184, allocations: 0 / 0.8057 GB, free: 2.133 MB / 0.6075 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 438
 * Number of variables: 438
Notification: Performance of Generate backend data structure: time 0.01035/0.7288, allocations: 3.901 MB / 0.8095 GB, free: 14.16 MB / 0.6232 GB
Notification: Performance of prepare preOptimizeDAE: time 4.919e-05/0.7288, allocations: 9.219 kB / 0.8095 GB, free: 14.15 MB / 0.6232 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 4.838e-05/0.7289, allocations: 34.23 kB / 0.8095 GB, free: 14.11 MB / 0.6232 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0003378/0.7292, allocations: 217.3 kB / 0.8097 GB, free: 13.89 MB / 0.6232 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001481/0.7307, allocations: 390.7 kB / 0.8101 GB, free: 13.51 MB / 0.6232 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00801/0.7387, allocations: 3.999 MB / 0.814 GB, free: 9.438 MB / 0.6232 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001044/0.7388, allocations: 82.05 kB / 0.8141 GB, free: 9.355 MB / 0.6232 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0002433/0.7391, allocations: 104 kB / 0.8142 GB, free: 9.254 MB / 0.6232 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.008942/0.748, allocations: 2.82 MB / 0.817 GB, free: 6.195 MB / 0.6232 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.214e-05/0.748, allocations: 7.938 kB / 0.817 GB, free: 6.188 MB / 0.6232 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001685/0.7482, allocations: 51.98 kB / 0.817 GB, free: 6.137 MB / 0.6232 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.504e-05/0.7482, allocations: 39.98 kB / 0.817 GB, free: 6.098 MB / 0.6232 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008807/0.757, allocations: 2.525 MB / 0.8195 GB, free: 3.566 MB / 0.6232 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0122/0.7692, allocations: 8.608 MB / 0.8279 GB, free: 10.71 MB / 0.6388 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.007955/0.7772, allocations: 2.366 MB / 0.8302 GB, free: 8.27 MB / 0.6388 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002068/0.7793, allocations: 0.8396 MB / 0.8311 GB, free: 7.402 MB / 0.6388 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0009382/0.7802, allocations: 0.6284 MB / 0.8317 GB, free: 6.66 MB / 0.6388 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.129e-05/0.7803, allocations: 63.44 kB / 0.8317 GB, free: 6.59 MB / 0.6388 GB
Notification: Performance of pre-optimization done (n=315): time 4.478e-06/0.7803, allocations: 0 / 0.8317 GB, free: 6.59 MB / 0.6388 GB
Notification: Performance of matching and sorting (n=315): time 0.01991/0.8002, allocations: 5.265 MB / 0.8369 GB, free: 1.219 MB / 0.6388 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003308/0.8005, allocations: 0.5264 MB / 0.8374 GB, free: 0.5664 MB / 0.6388 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004471/0.805, allocations: 2.378 MB / 0.8397 GB, free: 14.17 MB / 0.6544 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004462/0.8054, allocations: 53.7 kB / 0.8398 GB, free: 14.11 MB / 0.6544 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008472/0.8063, allocations: 0.845 MB / 0.8406 GB, free: 13.26 MB / 0.6544 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001304/0.8076, allocations: 1.053 MB / 0.8416 GB, free: 12.2 MB / 0.6544 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001048/0.8086, allocations: 0.5555 MB / 0.8421 GB, free: 11.63 MB / 0.6544 GB
Notification: Performance of setup shared object (initialization): time 0.0001379/0.8088, allocations: 301.1 kB / 0.8424 GB, free: 11.34 MB / 0.6544 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002322/0.8111, allocations: 1.038 MB / 0.8435 GB, free: 10.3 MB / 0.6544 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003027/0.8141, allocations: 1.548 MB / 0.845 GB, free: 8.555 MB / 0.6544 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.1227/0.9368, allocations: 13.71 MB / 0.8583 GB, free: 10.62 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.274e-05/0.9368, allocations: 4 kB / 0.8584 GB, free: 10.62 MB / 0.67 GB
Notification: Performance of matching and sorting (n=364) (initialization): time 0.008716/0.9456, allocations: 3.223 MB / 0.8615 GB, free: 7.352 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 3.062e-05/0.9456, allocations: 8 kB / 0.8615 GB, free: 7.344 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.006543/0.9521, allocations: 2.714 MB / 0.8642 GB, free: 4.582 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0008825/0.953, allocations: 198.4 kB / 0.8643 GB, free: 4.379 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002724/0.9557, allocations: 0.7137 MB / 0.865 GB, free: 3.664 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3751/1.331, allocations: 3.511 MB / 0.8685 GB, free: 116 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0027/1.334, allocations: 287.2 kB / 0.8687 GB, free: 116 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004758/1.334, allocations: 179 kB / 0.8689 GB, free: 116 MB / 0.67 GB
Warning: Assuming fixed start value for the following 2 variables:
         Volume.h:VARIABLE(start = Volume.h_start unit = \"J/kg\" fixed = true nominal = 1000.0 protected = true )  \"spec. enthalpy state\" type: Real
         Volume.p:VARIABLE(min = 0.0 start = Volume.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"System pressure\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00332/1.337, allocations: 1.49 MB / 0.8704 GB, free: 115.9 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002354/1.34, allocations: 1.516 MB / 0.8719 GB, free: 115.4 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.09269/1.432, allocations: 11.73 MB / 0.8833 GB, free: 114 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 8.606e-06/1.432, allocations: 3.156 kB / 0.8833 GB, free: 114 MB / 0.67 GB
Notification: Performance of matching and sorting (n=364) (initialization_lambda0): time 0.00635/1.439, allocations: 3.123 MB / 0.8864 GB, free: 112.9 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 1.769e-05/1.439, allocations: 13 kB / 0.8864 GB, free: 112.9 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.005022/1.444, allocations: 2.607 MB / 0.8889 GB, free: 111.9 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007239/1.445, allocations: 182.4 kB / 0.8891 GB, free: 111.9 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002429/1.447, allocations: 0.7082 MB / 0.8898 GB, free: 111.9 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001998/1.449, allocations: 3.498 MB / 0.8932 GB, free: 108.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002071/1.451, allocations: 261 kB / 0.8934 GB, free: 108.4 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002944/1.451, allocations: 172.5 kB / 0.8936 GB, free: 108.4 MB / 0.67 GB
Warning: Assuming fixed start value for the following 2 variables:
         Volume.h:VARIABLE(start = Volume.h_start unit = \"J/kg\" fixed = true nominal = 1000.0 protected = true )  \"spec. enthalpy state\" type: Real
         Volume.p:VARIABLE(min = 0.0 start = Volume.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"System pressure\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 51
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (358):
 * Single equations (assignments): 355
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * 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,1,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.0008816/1.452, allocations: 326.4 kB / 0.8939 GB, free: 108.2 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008615/1.453, allocations: 296.6 kB / 0.8942 GB, free: 108 MB / 0.67 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 501 equation(s) and 483 variable(s).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/VLEFluid/MixtureCompatible/VLEFluid_pT.mo:133:7-139:25:writable] Error: (Volume.heattransfer.fluid_wall.VLETransp.Pr_l, Volume.heattransfer.fluid_wall.VLETransp.Pr_v, Volume.heattransfer.fluid_wall.VLETransp.lambda_l, Volume.heattransfer.fluid_wall.VLETransp.lambda_v, Volume.heattransfer.fluid_wall.VLETransp.eta_l, Volume.heattransfer.fluid_wall.VLETransp.eta_v) = ($cse101, $cse102, $cse103, $cse104, $cse105, $cse106) has size 6 but 2 variables (Volume.inlet.m_flow, Volume.pressureLoss.Delta_p)
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.006893/1.46, allocations: 3.853 MB / 0.898 GB, free: 106.9 MB / 0.67 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 799]
[Calling sys.exit(0), Time elapsed: 5.222603006986901]
Failed to read output from testmodel.py, exit status != 0:
1.467716594983358 1.478989811 0.7580274300000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f84ea8ef3a0>