Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_dev_ClaRa.Components.TurboMachines.Pumps.Check.TestPumpModels.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.01507/0.01507, allocations: 1.772 MB / 18.82 MB, free: 4.246 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.001618/0.001618, allocations: 111.6 kB / 22.07 MB, free: 0.9922 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.002304/0.002304, allocations: 211.8 kB / 25.45 MB, free: 3.254 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.566/1.566, allocations: 230.6 MB / 259.3 MB, free: 13.13 MB / 206.1 MB
"
[Timeout remaining time 178]
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 1.114/1.114, allocations: 130.6 MB / 446.3 MB, free: 14.64 MB / 350.1 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.3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: translateModel(ClaRa.Components.TurboMachines.Pumps.Check.TestPumpModels,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5029,variableFilter="Time|pump_3.rho_CF_ps|pump_3.rho_upstream_ps|pump_3.rpm|speed1.phi",fileNamePrefix="ClaRa_dev_ClaRa.Components.TurboMachines.Pumps.Check.TestPumpModels")
translateModel(ClaRa.Components.TurboMachines.Pumps.Check.TestPumpModels,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5029,variableFilter="Time|pump_3.rho_CF_ps|pump_3.rho_upstream_ps|pump_3.rpm|speed1.phi",fileNamePrefix="ClaRa_dev_ClaRa.Components.TurboMachines.Pumps.Check.TestPumpModels") [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: 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.397/0.397, allocations: 46.14 MB / 0.6556 GB, free: 30.79 MB / 0.4982 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.192/0.5889, allocations: 89.04 MB / 0.7426 GB, free: 2.414 MB / 0.545 GB
Notification: Performance of NFInst.instantiate(ClaRa.Components.TurboMachines.Pumps.Check.TestPumpModels): time 0.01971/0.6087, allocations: 15.75 MB / 0.758 GB, free: 2.602 MB / 0.5607 GB
Notification: Performance of NFInst.instExpressions: time 0.03802/0.6467, allocations: 19.27 MB / 0.7768 GB, free: 15.27 MB / 0.5919 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005259/0.6519, allocations: 79.44 kB / 0.7769 GB, free: 15.2 MB / 0.5919 GB
Notification: Performance of NFTyping.typeComponents: time 0.006082/0.658, allocations: 1.648 MB / 0.7785 GB, free: 13.54 MB / 0.5919 GB
Notification: Performance of NFTyping.typeBindings: time 0.006552/0.6646, allocations: 1.872 MB / 0.7803 GB, free: 11.66 MB / 0.5919 GB
Notification: Performance of NFTyping.typeClassSections: time 0.007499/0.6721, allocations: 2.472 MB / 0.7827 GB, free: 9.184 MB / 0.5919 GB
Notification: Performance of NFFlatten.flatten: time 0.008106/0.6802, allocations: 4.516 MB / 0.7871 GB, free: 4.652 MB / 0.5919 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.003296/0.6835, allocations: 1.346 MB / 0.7884 GB, free: 3.266 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.004327/0.6878, allocations: 1.93 MB / 0.7903 GB, free: 1.328 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004096/0.6919, allocations: 2.182 MB / 0.7925 GB, free: 15.13 MB / 0.6075 GB
Notification: Performance of NFPackage.collectConstants: time 0.001312/0.6932, allocations: 308 kB / 0.7928 GB, free: 14.83 MB / 0.6075 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006167/0.6994, allocations: 2.506 MB / 0.7952 GB, free: 12.33 MB / 0.6075 GB
Notification: Performance of NFScalarize.scalarize: time 0.001906/0.7013, allocations: 0.9963 MB / 0.7962 GB, free: 11.33 MB / 0.6075 GB
Notification: Performance of NFVerifyModel.verify: time 0.003639/0.7049, allocations: 1.791 MB / 0.7979 GB, free: 9.527 MB / 0.6075 GB
Notification: Performance of NFConvertDAE.convert: time 0.01276/0.7177, allocations: 6.172 MB / 0.804 GB, free: 3.332 MB / 0.6075 GB
Notification: Performance of FrontEnd - DAE generated: time 7.234e-06/0.7177, allocations: 0 / 0.804 GB, free: 3.332 MB / 0.6075 GB
Notification: Performance of FrontEnd: time 2.013e-06/0.7177, allocations: 0 / 0.804 GB, free: 3.332 MB / 0.6075 GB
Notification: Performance of Transformations before backend: time 0.000202/0.7179, allocations: 0 / 0.804 GB, free: 3.332 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: 657
 * Number of variables: 657
Notification: Performance of Generate backend data structure: time 0.01115/0.729, allocations: 4.437 MB / 0.8083 GB, free: 14.8 MB / 0.6232 GB
Notification: Performance of prepare preOptimizeDAE: time 4.663e-05/0.7291, allocations: 9.219 kB / 0.8083 GB, free: 14.79 MB / 0.6232 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002121/0.7312, allocations: 0.5019 MB / 0.8088 GB, free: 14.29 MB / 0.6232 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.007769/0.739, allocations: 3.426 MB / 0.8121 GB, free: 10.8 MB / 0.6232 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002049/0.7392, allocations: 147.7 kB / 0.8123 GB, free: 10.65 MB / 0.6232 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006021/0.7398, allocations: 196 kB / 0.8125 GB, free: 10.46 MB / 0.6232 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009575/0.7494, allocations: 3.927 MB / 0.8163 GB, free: 5.977 MB / 0.6232 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.02e-05/0.7494, allocations: 31.75 kB / 0.8163 GB, free: 5.945 MB / 0.6232 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002223/0.7496, allocations: 91.98 kB / 0.8164 GB, free: 5.855 MB / 0.6232 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.783e-05/0.7497, allocations: 67.94 kB / 0.8165 GB, free: 5.789 MB / 0.6232 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008082/0.7578, allocations: 2.776 MB / 0.8192 GB, free: 3.004 MB / 0.6232 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.02112/0.7789, allocations: 19.1 MB / 0.8378 GB, free: 15.14 MB / 0.6544 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.007201/0.7861, allocations: 2.427 MB / 0.8402 GB, free: 12.58 MB / 0.6544 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001955/0.7881, allocations: 0.8222 MB / 0.841 GB, free: 11.72 MB / 0.6544 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0004654/0.7886, allocations: 190.2 kB / 0.8412 GB, free: 11.52 MB / 0.6544 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.006217/0.7948, allocations: 2.173 MB / 0.8433 GB, free: 9.238 MB / 0.6544 GB
Notification: Performance of pre-optimization done (n=332): time 4.959e-06/0.7948, allocations: 0 / 0.8433 GB, free: 9.238 MB / 0.6544 GB
Notification: Performance of matching and sorting (n=333): time 0.03258/0.8274, allocations: 16.37 MB / 0.8593 GB, free: 8.621 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0004968/0.8279, allocations: 0.7513 MB / 0.86 GB, free: 7.699 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004622/0.8325, allocations: 2.795 MB / 0.8628 GB, free: 4.902 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005192/0.833, allocations: 57.7 kB / 0.8628 GB, free: 4.84 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001363/0.8344, allocations: 1.399 MB / 0.8642 GB, free: 3.426 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001514/0.8359, allocations: 1.115 MB / 0.8653 GB, free: 2.301 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001298/0.8372, allocations: 0.722 MB / 0.866 GB, free: 1.57 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001664/0.8373, allocations: 308.9 kB / 0.8663 GB, free: 1.27 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002466/0.8398, allocations: 1.129 MB / 0.8674 GB, free: 136 kB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.3888/1.229, allocations: 2.228 MB / 0.8696 GB, free: 117.3 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.006336/1.235, allocations: 3.837 MB / 0.8733 GB, free: 115.7 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.205e-05/1.235, allocations: 10.56 kB / 0.8733 GB, free: 115.7 MB / 0.67 GB
Notification: Performance of matching and sorting (n=491) (initialization): time 0.00988/1.245, allocations: 4.214 MB / 0.8774 GB, free: 114.7 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001309/1.245, allocations: 149.9 kB / 0.8776 GB, free: 114.5 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.006327/1.251, allocations: 2.997 MB / 0.8805 GB, free: 113.4 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0009192/1.252, allocations: 169.3 kB / 0.8807 GB, free: 113.4 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002809/1.255, allocations: 0.8773 MB / 0.8815 GB, free: 113.4 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00755/1.263, allocations: 3.12 MB / 0.8846 GB, free: 111.2 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0027/1.265, allocations: 165.8 kB / 0.8847 GB, free: 111.1 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007895/1.266, allocations: 233.4 kB / 0.8849 GB, free: 111.1 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003966/1.27, allocations: 1.716 MB / 0.8866 GB, free: 110.3 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003151/1.273, allocations: 2.23 MB / 0.8888 GB, free: 108.5 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.005749/1.279, allocations: 3.842 MB / 0.8926 GB, free: 105.8 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.451e-05/1.279, allocations: 10.67 kB / 0.8926 GB, free: 105.8 MB / 0.67 GB
Notification: Performance of matching and sorting (n=491) (initialization_lambda0): time 0.0106/1.29, allocations: 4.184 MB / 0.8966 GB, free: 104.5 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000147/1.29, allocations: 151.7 kB / 0.8968 GB, free: 104.4 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.007097/1.297, allocations: 2.97 MB / 0.8997 GB, free: 103.3 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0008297/1.298, allocations: 145.4 kB / 0.8998 GB, free: 103.3 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003207/1.301, allocations: 0.8791 MB / 0.9007 GB, free: 103.2 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007273/1.308, allocations: 2.574 MB / 0.9032 GB, free: 101.6 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002995/1.311, allocations: 160.8 kB / 0.9034 GB, free: 101.5 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0009318/1.312, allocations: 231.1 kB / 0.9036 GB, free: 101.5 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 161
 * Number of states: 0 ()
 * Number of discrete variables: 28 (p_out_n3.nextEventScaled,p_out_n3.nextEvent,$PRE.p_out_n3.nextEvent,p_out_n3.last,p_out_n3.b,p_out_n3.a,p_out_n2.nextEventScaled,p_out_n2.nextEvent,$PRE.p_out_n2.nextEvent,p_out_n2.last,p_out_n2.b,p_out_n2.a,p_out_n1.nextEventScaled,p_out_n1.nextEvent,$PRE.p_out_n1.nextEvent,p_out_n1.last,p_out_n1.b,p_out_n1.a,ramp1.nextEventScaled,ramp1.nextEvent,$PRE.ramp1.nextEvent,ramp1.last,ramp1.b,ramp1.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 (467):
 * Single equations (assignments): 461
 * Array equations: 0
 * Algorithm blocks: 4
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * 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,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001748/1.314, allocations: 0.5407 MB / 0.9041 GB, free: 101.1 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001354/1.315, allocations: 382.6 kB / 0.9045 GB, free: 101.1 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 388 equation(s) and 372 variable(s).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/VLEFluid/MixtureCompatible/VLEFluid_ph.mo:55:3-60:21:writable] Error: (pump_1.summary.inlet.steamQuality, pump_1.fluidIn.cp, pump_1.fluidIn.cv, pump_1.fluidIn.beta, pump_1.fluidIn.kappa, pump_1.fluidIn.drhodp_hxi, pump_1.fluidIn.drhodh_pxi, _, pump_1.fluidIn.w, pump_1.fluidIn.gamma) = ($cse49, $cse50, $cse51, $cse52, $cse53, $cse54, $cse55, $cse56, $cse57, $cse58) has size 9 but 7 variables ($whenCondition1, ramp1.a, ramp1.b, ramp1.last, ramp1.nextEvent, ramp1.nextEventScaled, ramp1.timeScaled)
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.008203/1.323, allocations: 3.655 MB / 0.908 GB, free: 99.98 MB / 0.67 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 799]
[Calling sys.exit(0), Time elapsed: 5.071860436000861]
Failed to read output from testmodel.py, exit status != 0:
1.3333410189952701 1.344489608 0.624050188
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fadeceff3a0>