Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Components.Sensors.Check.TestVLESensors.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.01403/0.01403, allocations: 1.769 MB / 18.12 MB, free: 4.906 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.001589/0.001589, allocations: 103.7 kB / 21.36 MB, free: 1.684 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.002223/0.002223, allocations: 210.8 kB / 24.74 MB, free: 3.691 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.536/1.536, allocations: 230.6 MB / 258.5 MB, free: 13.1 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.139/1.139, allocations: 130.6 MB / 445.6 MB, free: 15.04 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.Sensors.Check.TestVLESensors,fileNamePrefix="ClaRa_ClaRa_Components_Sensors_Check_TestVLESensors",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ClaRa.Components.Sensors.Check.TestVLESensors,fileNamePrefix="ClaRa_ClaRa_Components_Sensors_Check_TestVLESensors",fmuType="me",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: 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.4121/0.4121, allocations: 46.15 MB / 0.6545 GB, free: 30.58 MB / 0.4982 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1948/0.607, allocations: 89.06 MB / 0.7415 GB, free: 2.75 MB / 0.545 GB
Notification: Performance of NFInst.instantiate(ClaRa.Components.Sensors.Check.TestVLESensors): time 0.01132/0.6183, allocations: 7.883 MB / 0.7492 GB, free: 10.84 MB / 0.5607 GB
Notification: Performance of NFInst.instExpressions: time 0.02101/0.6393, allocations: 12.29 MB / 0.7612 GB, free: 14.5 MB / 0.5763 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001333/0.6406, allocations: 39.75 kB / 0.7612 GB, free: 14.46 MB / 0.5763 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/SimCenter.mo:71:5-72:35:writable] Warning: Connector cycleSumPort is not balanced: The number of potential variables (0) is not equal to the number of flow variables (4).
Notification: Performance of NFTyping.typeComponents: time 0.002985/0.6436, allocations: 0.9368 MB / 0.7621 GB, free: 13.52 MB / 0.5763 GB
Notification: Performance of NFTyping.typeBindings: time 0.002213/0.6458, allocations: 0.6877 MB / 0.7628 GB, free: 12.83 MB / 0.5763 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003744/0.6496, allocations: 1.266 MB / 0.764 GB, free: 11.56 MB / 0.5763 GB
Notification: Performance of NFFlatten.flatten: time 0.003124/0.6527, allocations: 1.78 MB / 0.7658 GB, free: 9.777 MB / 0.5763 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001337/0.654, allocations: 0.6104 MB / 0.7663 GB, free: 9.141 MB / 0.5763 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.00159/0.6556, allocations: 0.8367 MB / 0.7672 GB, free: 8.301 MB / 0.5763 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001867/0.6575, allocations: 0.9724 MB / 0.7681 GB, free: 7.324 MB / 0.5763 GB
Notification: Performance of NFPackage.collectConstants: time 0.0004736/0.658, allocations: 140 kB / 0.7682 GB, free: 7.188 MB / 0.5763 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002903/0.6609, allocations: 1.244 MB / 0.7695 GB, free: 5.941 MB / 0.5763 GB
Notification: Performance of NFScalarize.scalarize: time 0.0008449/0.6617, allocations: 0.5216 MB / 0.77 GB, free: 5.418 MB / 0.5763 GB
Notification: Performance of NFVerifyModel.verify: time 0.001404/0.6631, allocations: 0.7924 MB / 0.7707 GB, free: 4.621 MB / 0.5763 GB
Notification: Performance of NFConvertDAE.convert: time 0.004798/0.6679, allocations: 2.896 MB / 0.7736 GB, free: 1.715 MB / 0.5763 GB
Notification: Performance of FrontEnd - DAE generated: time 8.065e-06/0.6679, allocations: 4 kB / 0.7736 GB, free: 1.711 MB / 0.5763 GB
Notification: Performance of FrontEnd: time 1.913e-06/0.6679, allocations: 0 / 0.7736 GB, free: 1.711 MB / 0.5763 GB
Notification: Performance of Transformations before backend: time 3.662e-05/0.668, allocations: 0 / 0.7736 GB, free: 1.711 MB / 0.5763 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 215
 * Number of variables: 215
Notification: Performance of Generate backend data structure: time 0.00393/0.6719, allocations: 2.354 MB / 0.7759 GB, free: 15.28 MB / 0.5919 GB
Notification: Performance of prepare preOptimizeDAE: time 5.567e-05/0.6719, allocations: 8.031 kB / 0.7759 GB, free: 15.27 MB / 0.5919 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0002093/0.6722, allocations: 136.8 kB / 0.776 GB, free: 15.13 MB / 0.5919 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0007976/0.6729, allocations: 243.5 kB / 0.7762 GB, free: 14.89 MB / 0.5919 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004107/0.6771, allocations: 2.335 MB / 0.7785 GB, free: 12.51 MB / 0.5919 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.961e-05/0.6771, allocations: 44.5 kB / 0.7786 GB, free: 12.47 MB / 0.5919 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001019/0.6772, allocations: 63.98 kB / 0.7786 GB, free: 12.41 MB / 0.5919 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003133/0.6804, allocations: 1.456 MB / 0.7801 GB, free: 10.72 MB / 0.5919 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.108e-05/0.6804, allocations: 3.938 kB / 0.7801 GB, free: 10.71 MB / 0.5919 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.638e-05/0.6804, allocations: 40 kB / 0.7801 GB, free: 10.68 MB / 0.5919 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.51e-05/0.6805, allocations: 20 kB / 0.7801 GB, free: 10.66 MB / 0.5919 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002561/0.683, allocations: 0.9705 MB / 0.7811 GB, free: 9.68 MB / 0.5919 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.007015/0.69, allocations: 6.87 MB / 0.7878 GB, free: 2.516 MB / 0.5919 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002275/0.6923, allocations: 0.783 MB / 0.7885 GB, free: 1.688 MB / 0.5919 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004501/0.6928, allocations: 266.2 kB / 0.7888 GB, free: 1.41 MB / 0.5919 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001438/0.6929, allocations: 51.95 kB / 0.7888 GB, free: 1.359 MB / 0.5919 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002051/0.695, allocations: 0.8316 MB / 0.7897 GB, free: 484 kB / 0.5919 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.0006012/0.6956, allocations: 115.2 kB / 0.7898 GB, free: 368 kB / 0.5919 GB
Notification: Performance of pre-optimization done (n=119): time 5.149e-06/0.6956, allocations: 7.938 kB / 0.7898 GB, free: 360 kB / 0.5919 GB
Notification: Performance of matching and sorting (n=119): time 0.004932/0.7005, allocations: 1.827 MB / 0.7916 GB, free: 14.5 MB / 0.6075 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.00029/0.7008, allocations: 460.9 kB / 0.792 GB, free: 13.95 MB / 0.6075 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002305/0.7031, allocations: 1.579 MB / 0.7935 GB, free: 12.37 MB / 0.6075 GB
Notification: Performance of collectPreVariables (initialization): time 7.623e-05/0.7032, allocations: 33.7 kB / 0.7936 GB, free: 12.33 MB / 0.6075 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0005767/0.7038, allocations: 0.6505 MB / 0.7942 GB, free: 11.67 MB / 0.6075 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003717/0.7041, allocations: 400.5 kB / 0.7946 GB, free: 11.28 MB / 0.6075 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005033/0.7046, allocations: 314.2 kB / 0.7949 GB, free: 10.96 MB / 0.6075 GB
Notification: Performance of setup shared object (initialization): time 0.0001337/0.7048, allocations: 301.1 kB / 0.7952 GB, free: 10.66 MB / 0.6075 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007106/0.7055, allocations: 402.8 kB / 0.7956 GB, free: 10.27 MB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001154/0.7066, allocations: 0.8695 MB / 0.7964 GB, free: 9.184 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.002068/0.7087, allocations: 1.476 MB / 0.7978 GB, free: 7.48 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.667e-06/0.7087, allocations: 8 kB / 0.7979 GB, free: 7.473 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=186) (initialization): time 0.002839/0.7115, allocations: 1.585 MB / 0.7994 GB, free: 5.867 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 8.28e-05/0.7116, allocations: 79.38 kB / 0.7995 GB, free: 5.777 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001783/0.7134, allocations: 1.01 MB / 0.8005 GB, free: 4.758 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.827e-05/0.7134, allocations: 20.66 kB / 0.8005 GB, free: 4.738 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009721/0.7144, allocations: 347.4 kB / 0.8008 GB, free: 4.398 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.506e-05/0.7145, allocations: 35.91 kB / 0.8008 GB, free: 4.363 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007471/0.7152, allocations: 47.94 kB / 0.8009 GB, free: 4.316 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000152/0.7154, allocations: 87.95 kB / 0.801 GB, free: 4.23 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 66
 * Number of states: 0 ()
 * Number of discrete variables: 14 (timeTable1.nextEventScaled,timeTable1.nextEvent,$PRE.timeTable1.nextEvent,timeTable1.last,timeTable1.b,timeTable1.a,timeTable.nextEventScaled,timeTable.nextEvent,$PRE.timeTable.nextEvent,timeTable.last,timeTable.b,timeTable.a,$whenCondition2,$whenCondition1)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (176):
 * Single equations (assignments): 174
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0009472/0.7163, allocations: 0.5123 MB / 0.8015 GB, free: 3.695 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003176/0.7166, allocations: 186.7 kB / 0.8017 GB, free: 3.512 MB / 0.6075 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 166 equation(s) and 157 variable(s).
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/VLEFluid/MixtureCompatible/VLEFluid_ph.mo:55:3-60:21:writable] Error: (temperature.fluid.q, temperature.fluid.cp, temperature.fluid.cv, temperature.fluid.beta, temperature.fluid.kappa, temperature.fluid.drhodp_hxi, temperature.fluid.drhodh_pxi, _, temperature.fluid.w, temperature.fluid.gamma) = ($cse1, $cse2, $cse3, $cse4, $cse5, $cse6, $cse7, $cse8, $cse9, $cse10) has size 9 but 7 variables ($whenCondition1, timeTable.a, timeTable.b, timeTable.last, timeTable.nextEvent, timeTable.nextEventScaled, timeTable.timeScaled)
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.002541/0.7192, allocations: 1.411 MB / 0.803 GB, free: 2.066 MB / 0.6075 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 799]
[Calling sys.exit(0), Time elapsed: 4.4895217009907356]
Failed to read output from testmodel.py, exit status != 0:
0.7267281109961914 0.740215241 0.069310891
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f8c428fb340>