Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr TILMedia_TILMedia.Testers.TestVLEFluid.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.01022/0.01022, allocations: 1.349 MB / 21.03 MB, free: 0.7617 MB / 14.72 MB
"
[Timeout remaining time 180]
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 0.9647/0.9647, allocations: 102.5 MB / 127 MB, free: 136 kB / 110.1 MB
"
[Timeout remaining time 179]
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.001466/0.001466, allocations: 91.48 kB / 158.8 MB, free: 0.9258 MB / 142.1 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.001867/0.001867, allocations: 156.7 kB / 190.6 MB, free: 1.074 MB / 174.1 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.572/1.572, allocations: 177.2 MB / 399.5 MB, free: 10.71 MB / 318.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/package.mo): time 0.1304/0.1304, allocations: 18.23 MB / 0.4909 GB, free: 4.246 MB / 414.1 MB
"
[Timeout remaining time 179]
Using package TILMedia with version 1.9.0 ClaRa (/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia 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 ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo)
Using package SMArtInt with version 0.3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo)
Running command: translateModel(TILMedia.Testers.TestVLEFluid,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="TILMedia_TILMedia.Testers.TestVLEFluid")
translateModel(TILMedia.Testers.TestVLEFluid,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="TILMedia_TILMedia.Testers.TestVLEFluid") [Timeout 660]
"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.
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: 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: Performance of FrontEnd - loaded program: time 0.004574/0.004574, allocations: 135.8 kB / 0.6833 GB, free: 14.53 MB / 0.5607 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1851/0.1896, allocations: 89.17 MB / 0.7704 GB, free: 5.172 MB / 0.6388 GB
Notification: Performance of NFInst.instantiate(TILMedia.Testers.TestVLEFluid): time 0.00643/0.1961, allocations: 3.852 MB / 0.7741 GB, free: 1.301 MB / 0.6388 GB
Notification: Performance of NFInst.instExpressions: time 0.0178/0.2139, allocations: 10.57 MB / 0.7845 GB, free: 6.695 MB / 0.6544 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0007654/0.2146, allocations: 19.88 kB / 0.7845 GB, free: 6.676 MB / 0.6544 GB
Notification: Performance of NFTyping.typeComponents: time 0.001853/0.2165, allocations: 425.7 kB / 0.7849 GB, free: 6.258 MB / 0.6544 GB
Notification: Performance of NFTyping.typeBindings: time 0.001655/0.2181, allocations: 417.5 kB / 0.7853 GB, free: 5.848 MB / 0.6544 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005701/0.2238, allocations: 1.793 MB / 0.787 GB, free: 4.047 MB / 0.6544 GB
Notification: Performance of NFFlatten.flatten: time 0.001996/0.2258, allocations: 0.9859 MB / 0.788 GB, free: 3.059 MB / 0.6544 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0002125/0.2261, allocations: 38.31 kB / 0.788 GB, free: 3.012 MB / 0.6544 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008363/0.2269, allocations: 378.5 kB / 0.7884 GB, free: 2.641 MB / 0.6544 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0008998/0.2278, allocations: 386 kB / 0.7888 GB, free: 2.262 MB / 0.6544 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001785/0.228, allocations: 40 kB / 0.7888 GB, free: 2.223 MB / 0.6544 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004591/0.2326, allocations: 1.649 MB / 0.7904 GB, free: 0.5703 MB / 0.6544 GB
Notification: Performance of NFScalarize.scalarize: time 0.0003542/0.2329, allocations: 143.3 kB / 0.7906 GB, free: 440 kB / 0.6544 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006593/0.2336, allocations: 262.5 kB / 0.7908 GB, free: 176 kB / 0.6544 GB
Notification: Performance of NFConvertDAE.convert: time 0.006328/0.2399, allocations: 2.219 MB / 0.793 GB, free: 13.95 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 1.02e-05/0.2399, allocations: 0 / 0.793 GB, free: 13.95 MB / 0.67 GB
Notification: Performance of FrontEnd: time 2.405e-06/0.2399, allocations: 3.938 kB / 0.793 GB, free: 13.94 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 5.729e-05/0.24, allocations: 0 / 0.793 GB, free: 13.94 MB / 0.67 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 189
 * Number of variables: 189
Notification: Performance of Generate backend data structure: time 0.002492/0.2425, allocations: 1.214 MB / 0.7942 GB, free: 12.69 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.863e-05/0.2425, allocations: 12.03 kB / 0.7942 GB, free: 12.68 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0005163/0.243, allocations: 100.4 kB / 0.7943 GB, free: 12.58 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001912/0.2449, allocations: 0.7652 MB / 0.795 GB, free: 11.79 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.028e-05/0.245, allocations: 29.38 kB / 0.795 GB, free: 11.76 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 9.401e-05/0.2451, allocations: 44.38 kB / 0.7951 GB, free: 11.72 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003039/0.2481, allocations: 1.249 MB / 0.7963 GB, free: 10.24 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.205e-05/0.2482, allocations: 11.88 kB / 0.7963 GB, free: 10.23 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.481e-05/0.2482, allocations: 27.94 kB / 0.7963 GB, free: 10.2 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.661e-05/0.2482, allocations: 16 kB / 0.7964 GB, free: 10.19 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002245/0.2505, allocations: 0.7338 MB / 0.7971 GB, free: 9.449 MB / 0.67 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.005422/0.2559, allocations: 6.84 MB / 0.8038 GB, free: 2.309 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002207/0.2581, allocations: 0.6384 MB / 0.8044 GB, free: 1.625 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0003175/0.2584, allocations: 162.4 kB / 0.8045 GB, free: 1.453 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001883/0.2586, allocations: 63.84 kB / 0.8046 GB, free: 1.391 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.704e-05/0.2587, allocations: 46.56 kB / 0.8046 GB, free: 1.336 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=110): time 3.526e-06/0.2587, allocations: 0 / 0.8046 GB, free: 1.336 MB / 0.67 GB
Notification: Performance of matching and sorting (n=110): time 0.3946/0.6532, allocations: 1.378 MB / 0.806 GB, free: 107.5 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001224/0.6533, allocations: 164.4 kB / 0.8061 GB, free: 107.3 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00109/0.6544, allocations: 0.5386 MB / 0.8067 GB, free: 107.2 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002016/0.6546, allocations: 30.44 kB / 0.8067 GB, free: 107.2 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002802/0.6549, allocations: 367.4 kB / 0.807 GB, free: 107 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004614/0.6554, allocations: 383.2 kB / 0.8074 GB, free: 106.8 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0005112/0.6559, allocations: 248.2 kB / 0.8077 GB, free: 106.8 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 0.0001879/0.6561, allocations: 379.9 kB / 0.808 GB, free: 106.5 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006588/0.6567, allocations: 305.7 kB / 0.8083 GB, free: 106.5 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001081/0.6578, allocations: 0.7525 MB / 0.809 GB, free: 105.9 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001797/0.6596, allocations: 1.185 MB / 0.8102 GB, free: 105.3 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.899e-05/0.6596, allocations: 7.25 kB / 0.8102 GB, free: 105.3 MB / 0.67 GB
Notification: Performance of matching and sorting (n=174) (initialization): time 0.002374/0.662, allocations: 1.183 MB / 0.8114 GB, free: 105.1 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 2.972e-05/0.662, allocations: 17.39 kB / 0.8114 GB, free: 105.1 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001161/0.6632, allocations: 0.737 MB / 0.8121 GB, free: 104.8 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.772e-05/0.6633, allocations: 22.27 kB / 0.8121 GB, free: 104.8 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009234/0.6642, allocations: 342.8 kB / 0.8124 GB, free: 104.8 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 3.712e-05/0.6642, allocations: 33.95 kB / 0.8125 GB, free: 104.8 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0007635/0.665, allocations: 132.6 kB / 0.8126 GB, free: 104.8 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001576/0.6651, allocations: 93.58 kB / 0.8127 GB, free: 104.7 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 69
 * 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 (174):
 * Single equations (assignments): 174
 * Array equations: 0
 * Algorithm blocks: 0
 * 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.0004914/0.6656, allocations: 242 kB / 0.8129 GB, free: 104.5 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003792/0.666, allocations: 75.86 kB / 0.813 GB, free: 104.5 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 178 equation(s) and 166 variable(s).
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.002174/0.6682, allocations: 1.366 MB / 0.8143 GB, free: 104.1 MB / 0.67 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 4.80007212900091]
Failed to read output from testmodel.py, exit status != 0:
0.6785561159485951 0.6905928370000001 0.44761074700000003
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f97747e6fe0>