Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_dev_ClaRa.Components.MechanicalSeparation.Check.TestSeparator_L1.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.006512/0.006512, allocations: 1.442 MB / 20.54 MB, free: 4.012 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.000763/0.000763, allocations: 91.45 kB / 24.16 MB, free: 396 kB / 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.0008399/0.0008399, allocations: 159.2 kB / 27.85 MB, free: 5.496 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.8985/0.8985, allocations: 177.2 MB / 208.6 MB, free: 2.973 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa main/package.mo): time 0.7888/0.7888, allocations: 102.4 MB / 367.9 MB, free: 1.445 MB / 330.8 MB
"
[Timeout remaining time 179]
Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 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 SMArtInt with version 0.5.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: translateModel(ClaRa.Components.MechanicalSeparation.Check.TestSeparator_L1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=20036,variableFilter="Time|volumeVLE_2_1.h|volumeVLE_2_1.p",fileNamePrefix="ClaRa_dev_ClaRa.Components.MechanicalSeparation.Check.TestSeparator_L1")
translateModel(ClaRa.Components.MechanicalSeparation.Check.TestSeparator_L1,tolerance=1e-06,outputFormat="mat",numberOfIntervals=20036,variableFilter="Time|volumeVLE_2_1.h|volumeVLE_2_1.p",fileNamePrefix="ClaRa_dev_ClaRa.Components.MechanicalSeparation.Check.TestSeparator_L1") [Timeout 800]
"Notification: Performance of FrontEnd - loaded program: time 1.553e-06/1.553e-06, allocations: 0 / 0.5366 GB, free: 39.82 MB / 490.8 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.378e-05/2.533e-05, allocations: 2.281 kB / 0.5366 GB, free: 39.82 MB / 490.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.TestSeparator_L1): time 0.1187/0.1187, allocations: 61.53 MB / 0.5967 GB, free: 14.49 MB / 0.4949 GB
Notification: Performance of NFInst.instExpressions: time 0.0216/0.1403, allocations: 18.88 MB / 0.6152 GB, free: 11.55 MB / 0.5105 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002673/0.143, allocations: 95.31 kB / 0.6153 GB, free: 11.45 MB / 0.5105 GB
Notification: Performance of NFTyping.typeComponents: time 0.00322/0.1462, allocations: 1.872 MB / 0.6171 GB, free: 9.57 MB / 0.5105 GB
Notification: Performance of NFTyping.typeBindings: time 0.004202/0.1504, allocations: 2.489 MB / 0.6195 GB, free: 7.066 MB / 0.5105 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0045/0.1549, allocations: 2.762 MB / 0.6222 GB, free: 4.297 MB / 0.5105 GB
Notification: Performance of NFFlatten.flatten: time 0.005978/0.1609, allocations: 5.831 MB / 0.6279 GB, free: 14.45 MB / 0.5262 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002328/0.1632, allocations: 1.656 MB / 0.6295 GB, free: 12.75 MB / 0.5262 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003561/0.1668, allocations: 3.156 MB / 0.6326 GB, free: 9.582 MB / 0.5262 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001712/0.1685, allocations: 2.08 MB / 0.6346 GB, free: 7.496 MB / 0.5262 GB
Notification: Performance of NFPackage.collectConstants: time 0.0003064/0.1688, allocations: 364 kB / 0.635 GB, free: 7.141 MB / 0.5262 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.003172/0.172, allocations: 1.949 MB / 0.6369 GB, free: 5.188 MB / 0.5262 GB
Notification: Performance of NFScalarize.scalarize: time 0.000716/0.1727, allocations: 1.195 MB / 0.6381 GB, free: 3.988 MB / 0.5262 GB
Notification: Performance of NFVerifyModel.verify: time 0.001309/0.174, allocations: 1.285 MB / 0.6393 GB, free: 2.699 MB / 0.5262 GB
Notification: Performance of NFConvertDAE.convert: time 0.005723/0.1797, allocations: 6.698 MB / 0.6459 GB, free: 11.98 MB / 0.5418 GB
Notification: Performance of FrontEnd - DAE generated: time 4.008e-06/0.1797, allocations: 0 / 0.6459 GB, free: 11.98 MB / 0.5418 GB
Notification: Performance of FrontEnd: time 1.453e-06/0.1797, allocations: 0 / 0.6459 GB, free: 11.98 MB / 0.5418 GB
Notification: Performance of Transformations before backend: time 4.992e-05/0.1798, allocations: 0 / 0.6459 GB, free: 11.98 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: 801
 * Number of variables: 801
Notification: Performance of Generate backend data structure: time 0.004645/0.1844, allocations: 4.805 MB / 0.6505 GB, free: 7.086 MB / 0.5418 GB
Notification: Performance of prepare preOptimizeDAE: time 4.556e-05/0.1845, allocations: 8.031 kB / 0.6506 GB, free: 7.078 MB / 0.5418 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0008409/0.1853, allocations: 0.5795 MB / 0.6511 GB, free: 6.496 MB / 0.5418 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004049/0.1894, allocations: 3.594 MB / 0.6546 GB, free: 2.84 MB / 0.5418 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 8.754e-05/0.1895, allocations: 160.4 kB / 0.6548 GB, free: 2.68 MB / 0.5418 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001878/0.1896, allocations: 200.4 kB / 0.655 GB, free: 2.484 MB / 0.5418 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.006302/0.1959, allocations: 5.544 MB / 0.6604 GB, free: 12.15 MB / 0.5574 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.053e-05/0.196, allocations: 23.75 kB / 0.6604 GB, free: 12.12 MB / 0.5574 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000142/0.1961, allocations: 104 kB / 0.6605 GB, free: 12.02 MB / 0.5574 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.16e-05/0.1962, allocations: 68 kB / 0.6606 GB, free: 11.96 MB / 0.5574 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005114/0.2013, allocations: 3.643 MB / 0.6641 GB, free: 8.277 MB / 0.5574 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.01222/0.2135, allocations: 19.85 MB / 0.6835 GB, free: 3.738 MB / 0.573 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00451/0.218, allocations: 3.145 MB / 0.6866 GB, free: 464 kB / 0.573 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001464/0.2195, allocations: 1.21 MB / 0.6878 GB, free: 15.18 MB / 0.5887 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002637/0.2197, allocations: 137.1 kB / 0.6879 GB, free: 15.04 MB / 0.5887 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004164/0.2239, allocations: 3.078 MB / 0.6909 GB, free: 11.84 MB / 0.5887 GB
Notification: Performance of pre-optimization done (n=448): time 4.529e-06/0.2239, allocations: 0 / 0.6909 GB, free: 11.84 MB / 0.5887 GB
Notification: Performance of matching and sorting (n=448): time 0.01063/0.2345, allocations: 6.99 MB / 0.6977 GB, free: 4.754 MB / 0.5887 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002711/0.2348, allocations: 0.805 MB / 0.6985 GB, free: 3.766 MB / 0.5887 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003155/0.238, allocations: 3.119 MB / 0.7016 GB, free: 0.6523 MB / 0.5887 GB
Notification: Performance of collectPreVariables (initialization): time 0.000127/0.2381, allocations: 53.7 kB / 0.7016 GB, free: 0.5938 MB / 0.5887 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0008249/0.2389, allocations: 1.534 MB / 0.7031 GB, free: 15.05 MB / 0.6043 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007712/0.2397, allocations: 1.467 MB / 0.7046 GB, free: 13.57 MB / 0.6043 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0008358/0.2405, allocations: 0.8764 MB / 0.7054 GB, free: 12.68 MB / 0.6043 GB
Notification: Performance of setup shared object (initialization): time 7.428e-05/0.2406, allocations: 305.1 kB / 0.7057 GB, free: 12.38 MB / 0.6043 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001725/0.2423, allocations: 1.635 MB / 0.7073 GB, free: 10.71 MB / 0.6043 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002632/0.245, allocations: 2.995 MB / 0.7102 GB, free: 7.105 MB / 0.6043 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.08608/0.331, allocations: 25.86 MB / 0.7355 GB, free: 12.59 MB / 0.6355 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.009e-05/0.3311, allocations: 12 kB / 0.7355 GB, free: 12.58 MB / 0.6355 GB
Notification: Performance of matching and sorting (n=611) (initialization): time 0.006715/0.3378, allocations: 5.314 MB / 0.7407 GB, free: 7.191 MB / 0.6355 GB
Notification: Performance of prepare postOptimizeDAE: time 9.697e-05/0.3379, allocations: 150.8 kB / 0.7408 GB, free: 7.02 MB / 0.6355 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.004395/0.3423, allocations: 4.044 MB / 0.7448 GB, free: 2.898 MB / 0.6355 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002022/0.3425, allocations: 104.7 kB / 0.7449 GB, free: 2.793 MB / 0.6355 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001777/0.3442, allocations: 1.119 MB / 0.746 GB, free: 1.672 MB / 0.6355 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0008987/0.3451, allocations: 1.39 MB / 0.7473 GB, free: 184 kB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001703/0.3468, allocations: 452.8 kB / 0.7477 GB, free: 15.73 MB / 0.6512 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002922/0.3471, allocations: 295.8 kB / 0.748 GB, free: 15.44 MB / 0.6512 GB
Warning: Assuming fixed start value for the following 2 variables:
         volumeVLE_2_1.h:VARIABLE(start = volumeVLE_2_1.h_start unit = \"J/kg\" fixed = true nominal = 1000.0 protected = true )  \"spec. enthalpy state\" type: Real
         volumeVLE_2_1.p:VARIABLE(min = 0.0 start = volumeVLE_2_1.p_start unit = \"Pa\" fixed = true nominal = 1e5 stateSelect=StateSelect.prefer )  \"System pressure\" type: Real
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003276/0.3504, allocations: 2.303 MB / 0.7503 GB, free: 13.11 MB / 0.6512 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002396/0.3528, allocations: 2.992 MB / 0.7532 GB, free: 9.504 MB / 0.6512 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.3767/0.7295, allocations: 25.73 MB / 0.7783 GB, free: 178.9 MB / 0.6512 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.676e-05/0.7295, allocations: 14.41 kB / 0.7783 GB, free: 178.9 MB / 0.6512 GB
Notification: Performance of matching and sorting (n=611) (initialization_lambda0): time 0.007582/0.7371, allocations: 5.273 MB / 0.7835 GB, free: 176.6 MB / 0.6512 GB
Notification: Performance of prepare postOptimizeDAE: time 8.649e-05/0.7372, allocations: 152.4 kB / 0.7836 GB, free: 176.4 MB / 0.6512 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.00445/0.7417, allocations: 3.988 MB / 0.7875 GB, free: 174.4 MB / 0.6512 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0001543/0.7418, allocations: 93.92 kB / 0.7876 GB, free: 174.4 MB / 0.6512 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002119/0.7439, allocations: 1.119 MB / 0.7887 GB, free: 174.1 MB / 0.6512 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0007443/0.7447, allocations: 1.376 MB / 0.7901 GB, free: 172.8 MB / 0.6512 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001622/0.7463, allocations: 374.5 kB / 0.7904 GB, free: 172.8 MB / 0.6512 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003342/0.7466, allocations: 300 kB / 0.7907 GB, free: 172.7 MB / 0.6512 GB
Warning: Assuming fixed start value for the following 2 variables:
         volumeVLE_2_1.h:VARIABLE(start = volumeVLE_2_1.h_start unit = \"J/kg\" fixed = true nominal = 1000.0 protected = true )  \"spec. enthalpy state\" type: Real
         volumeVLE_2_1.p:VARIABLE(min = 0.0 start = volumeVLE_2_1.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: 164
 * Number of states: 0 ()
 * Number of discrete variables: 28 (timeTable_p1.nextEventScaled,timeTable_p1.nextEvent,$PRE.timeTable_p1.nextEvent,timeTable_p1.last,timeTable_p1.b,timeTable_p1.a,timeTable2.nextEventScaled,timeTable2.nextEvent,$PRE.timeTable2.nextEvent,timeTable2.last,timeTable2.b,timeTable2.a,timeTable1.nextEventScaled,timeTable1.nextEvent,$PRE.timeTable1.nextEvent,timeTable1.last,timeTable1.b,timeTable1.a,timeTable_p.nextEventScaled,timeTable_p.nextEvent,$PRE.timeTable_p.nextEvent,timeTable_p.last,timeTable_p.b,timeTable_p.a,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (590):
 * Single equations (assignments): 585
 * Array equations: 0
 * Algorithm blocks: 4
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0009702/0.7476, allocations: 0.5896 MB / 0.7913 GB, free: 172.2 MB / 0.6512 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005411/0.7481, allocations: 418.9 kB / 0.7917 GB, free: 172.1 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 697 equation(s) and 664 variable(s).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/VLEFluid/MixtureCompatible/VLEFluid_ph.mo:55:3-60:21:writable] Error: (steamSeparator.fluidIn.q, steamSeparator.fluidIn.cp, steamSeparator.fluidIn.cv, steamSeparator.fluidIn.beta, steamSeparator.fluidIn.kappa, steamSeparator.fluidIn.drhodp_hxi, steamSeparator.fluidIn.drhodh_pxi, _, steamSeparator.fluidIn.w, steamSeparator.fluidIn.gamma) = ($cse6, $cse7, $cse8, $cse9, $cse10, $cse11, $cse12, $cse13, $cse14, $cse15) has size 9 but 7 variables ($whenCondition1, timeTable_p.a, timeTable_p.b, timeTable_p.last, timeTable_p.nextEvent, timeTable_p.nextEventScaled, timeTable_p.timeScaled)
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.006035/0.7542, allocations: 5.484 MB / 0.797 GB, free: 169.2 MB / 0.6512 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 799]
[Calling sys.exit(0), Time elapsed: 3.7208439419046044]
Failed to read output from testmodel.py, exit status != 0:
0.9107048595324159 0.917008761 0.7358786509999999
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7587b7d60140>