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.01061/0.01061, allocations: 1.754 MB / 20.61 MB, free: 1.004 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.9575/0.9575, allocations: 130.7 MB / 154.8 MB, free: 4.293 MB / 126.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.001548/0.001548, allocations: 109.2 kB / 186.5 MB, free: 2.082 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.002532/0.002532, allocations: 211.3 kB / 218.4 MB, free: 2.246 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.745/1.745, allocations: 230.6 MB / 480.7 MB, free: 152 kB / 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.1268/0.1268, allocations: 25.58 MB / 0.5774 GB, free: 2.086 MB / 430.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: "" <> buildModelFMU(TILMedia.Testers.TestVLEFluid,fileNamePrefix="TILMedia_TILMedia_Testers_TestVLEFluid",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(TILMedia.Testers.TestVLEFluid,fileNamePrefix="TILMedia_TILMedia_Testers_TestVLEFluid",fmuType="me",version="2.0",platforms={"static"}) [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.004315/0.004315, allocations: 145.8 kB / 0.7695 GB, free: 7.973 MB / 0.5607 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.6598/0.6641, allocations: 89.26 MB / 0.8567 GB, free: 29.52 MB / 0.5763 GB
Notification: Performance of NFInst.instantiate(TILMedia.Testers.TestVLEFluid): time 0.006247/0.6703, allocations: 3.833 MB / 0.8605 GB, free: 26.94 MB / 0.5763 GB
Notification: Performance of NFInst.instExpressions: time 0.01606/0.6864, allocations: 10.58 MB / 0.8708 GB, free: 19.75 MB / 0.5763 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009271/0.6873, allocations: 15.69 kB / 0.8708 GB, free: 19.74 MB / 0.5763 GB
Notification: Performance of NFTyping.typeComponents: time 0.001882/0.6892, allocations: 429.4 kB / 0.8712 GB, free: 19.54 MB / 0.5763 GB
Notification: Performance of NFTyping.typeBindings: time 0.001759/0.691, allocations: 420.3 kB / 0.8716 GB, free: 19.28 MB / 0.5763 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006172/0.6971, allocations: 1.79 MB / 0.8734 GB, free: 18.4 MB / 0.5763 GB
Notification: Performance of NFFlatten.flatten: time 0.002018/0.6992, allocations: 0.9849 MB / 0.8743 GB, free: 17.93 MB / 0.5763 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0001828/0.6993, allocations: 44.27 kB / 0.8744 GB, free: 17.89 MB / 0.5763 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009089/0.7003, allocations: 371.4 kB / 0.8747 GB, free: 17.74 MB / 0.5763 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001045/0.7013, allocations: 401.1 kB / 0.8751 GB, free: 17.58 MB / 0.5763 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001862/0.7015, allocations: 40.62 kB / 0.8751 GB, free: 17.58 MB / 0.5763 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005738/0.7072, allocations: 1.635 MB / 0.8767 GB, free: 17.05 MB / 0.5763 GB
Notification: Performance of NFScalarize.scalarize: time 0.000395/0.7076, allocations: 144.6 kB / 0.8769 GB, free: 16.96 MB / 0.5763 GB
Notification: Performance of NFVerifyModel.verify: time 0.0007891/0.7084, allocations: 262.8 kB / 0.8771 GB, free: 16.83 MB / 0.5763 GB
Notification: Performance of NFConvertDAE.convert: time 0.00794/0.7163, allocations: 2.223 MB / 0.8793 GB, free: 15.83 MB / 0.5763 GB
Notification: Performance of FrontEnd - DAE generated: time 1.108e-05/0.7164, allocations: 3.984 kB / 0.8793 GB, free: 15.82 MB / 0.5763 GB
Notification: Performance of FrontEnd: time 3.767e-06/0.7164, allocations: 0 / 0.8793 GB, free: 15.82 MB / 0.5763 GB
Notification: Performance of Transformations before backend: time 6.49e-05/0.7164, allocations: 1.031 kB / 0.8793 GB, free: 15.82 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: 189
 * Number of variables: 189
Notification: Performance of Generate backend data structure: time 0.003611/0.72, allocations: 1.212 MB / 0.8805 GB, free: 14.85 MB / 0.5763 GB
Notification: Performance of prepare preOptimizeDAE: time 6.243e-05/0.7201, allocations: 8.906 kB / 0.8805 GB, free: 14.85 MB / 0.5763 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.000163/0.7203, allocations: 108.2 kB / 0.8806 GB, free: 14.75 MB / 0.5763 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0005867/0.7208, allocations: 107.2 kB / 0.8807 GB, free: 14.7 MB / 0.5763 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001918/0.7228, allocations: 0.7575 MB / 0.8814 GB, free: 14.15 MB / 0.5763 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.253e-05/0.7228, allocations: 33.88 kB / 0.8815 GB, free: 14.13 MB / 0.5763 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 7.951e-05/0.7229, allocations: 39.09 kB / 0.8815 GB, free: 14.12 MB / 0.5763 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002968/0.7259, allocations: 1.241 MB / 0.8827 GB, free: 13.07 MB / 0.5763 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.329e-05/0.7259, allocations: 17.25 kB / 0.8827 GB, free: 13.06 MB / 0.5763 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.755e-05/0.726, allocations: 21.94 kB / 0.8828 GB, free: 13.05 MB / 0.5763 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.022e-05/0.726, allocations: 21.89 kB / 0.8828 GB, free: 13.05 MB / 0.5763 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002383/0.7284, allocations: 0.7287 MB / 0.8835 GB, free: 12.7 MB / 0.5763 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.004178/0.7326, allocations: 6.916 MB / 0.8902 GB, free: 5.848 MB / 0.5763 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002099/0.7347, allocations: 0.6396 MB / 0.8909 GB, free: 5.465 MB / 0.5763 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002866/0.735, allocations: 161.8 kB / 0.891 GB, free: 5.355 MB / 0.5763 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002057/0.7352, allocations: 60.66 kB / 0.8911 GB, free: 5.34 MB / 0.5763 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.947e-05/0.7352, allocations: 39.16 kB / 0.8911 GB, free: 5.297 MB / 0.5763 GB
Notification: Performance of pre-optimization done (n=110): time 2.875e-06/0.7352, allocations: 0 / 0.8911 GB, free: 5.297 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=110): time 0.004522/0.7397, allocations: 1.38 MB / 0.8925 GB, free: 4.66 MB / 0.5763 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.943e-05/0.7398, allocations: 167.2 kB / 0.8926 GB, free: 4.465 MB / 0.5763 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008144/0.7406, allocations: 0.554 MB / 0.8932 GB, free: 4.109 MB / 0.5763 GB
Notification: Performance of collectPreVariables (initialization): time 0.000107/0.7407, allocations: 35.02 kB / 0.8932 GB, free: 4.07 MB / 0.5763 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002422/0.741, allocations: 361.8 kB / 0.8935 GB, free: 3.742 MB / 0.5763 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003108/0.7413, allocations: 381.3 kB / 0.8939 GB, free: 3.406 MB / 0.5763 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004217/0.7417, allocations: 254 kB / 0.8941 GB, free: 3.262 MB / 0.5763 GB
Notification: Performance of setup shared object (initialization): time 0.0001452/0.7419, allocations: 379.5 kB / 0.8945 GB, free: 2.898 MB / 0.5763 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006449/0.7425, allocations: 307 kB / 0.8948 GB, free: 2.742 MB / 0.5763 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001006/0.7435, allocations: 0.7552 MB / 0.8955 GB, free: 1.973 MB / 0.5763 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001858/0.7454, allocations: 1.189 MB / 0.8967 GB, free: 0.957 MB / 0.5763 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.427e-05/0.7454, allocations: 4.219 kB / 0.8967 GB, free: 0.957 MB / 0.5763 GB
Notification: Performance of matching and sorting (n=174) (initialization): time 0.002642/0.748, allocations: 1.185 MB / 0.8979 GB, free: 336 kB / 0.5763 GB
Notification: Performance of prepare postOptimizeDAE: time 4.47e-05/0.7481, allocations: 11.53 kB / 0.8979 GB, free: 336 kB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.001525/0.7496, allocations: 0.7335 MB / 0.8986 GB, free: 15.99 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 7.535e-05/0.7497, allocations: 23.38 kB / 0.8986 GB, free: 15.99 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009657/0.7506, allocations: 343.5 kB / 0.8989 GB, free: 15.9 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001129/0.7508, allocations: 42.56 kB / 0.899 GB, free: 15.88 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008845/0.7516, allocations: 137.4 kB / 0.8991 GB, free: 15.79 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002001/0.7518, allocations: 96.88 kB / 0.8992 GB, free: 15.75 MB / 0.5919 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.0005263/0.7524, allocations: 245.3 kB / 0.8994 GB, free: 15.55 MB / 0.5919 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003222/0.7527, allocations: 79.41 kB / 0.8995 GB, free: 15.5 MB / 0.5919 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.003094/0.7558, allocations: 1.354 MB / 0.9008 GB, free: 14.69 MB / 0.5919 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 5.53313671797514]
Failed to read output from testmodel.py, exit status != 0:
0.7658974989317358 0.7847418389999999 0.06471247099999994
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f623d4eefb0>