Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Chemical_Chemical.Examples.WaterVaporization.conf.json
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.001316/0.001316, allocations: 115.6 kB / 17.16 MB, free: 5.895 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.001428/0.001428, allocations: 215 kB / 20.13 MB, free: 2.949 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.558/1.558, allocations: 230.6 MB / 253.5 MB, free: 15.1 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.0/package.mo): time 0.1432/0.1432, allocations: 23.85 MB / 333.4 MB, free: 1.219 MB / 254.1 MB
"
[Timeout remaining time 180]
Using package Chemical with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Chemical 2.0.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)
Running command: "" <> buildModelFMU(Chemical.Examples.WaterVaporization,fileNamePrefix="Chemical_Chemical_Examples_WaterVaporization",fmuType="cs",version="2.0",platforms={"static"})
"" <> buildModelFMU(Chemical.Examples.WaterVaporization,fileNamePrefix="Chemical_Chemical_Examples_WaterVaporization",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Chemical 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.002129/0.002129, allocations: 60.5 kB / 466.7 MB, free: 30.82 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1214/0.1236, allocations: 59.83 MB / 0.5142 GB, free: 12.97 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(Chemical.Examples.WaterVaporization): time 0.01681/0.1404, allocations: 13.62 MB / 0.5275 GB, free: 15.29 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.5056/0.646, allocations: 147.1 MB / 0.6711 GB, free: 8.27 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002781/0.6488, allocations: 43.5 kB / 0.6711 GB, free: 8.254 MB / 478.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004167/0.6529, allocations: 1.282 MB / 0.6724 GB, free: 7.363 MB / 478.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005249/0.6582, allocations: 1.318 MB / 0.6737 GB, free: 6.27 MB / 478.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004578/0.6628, allocations: 1.688 MB / 0.6753 GB, free: 4.91 MB / 478.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006585/0.6694, allocations: 3.712 MB / 0.6789 GB, free: 2.582 MB / 478.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001507/0.6709, allocations: 0.783 MB / 0.6797 GB, free: 1.738 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002333/0.6732, allocations: 1.292 MB / 0.681 GB, free: 452 kB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002726/0.6759, allocations: 1.513 MB / 0.6825 GB, free: 14.92 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005451/0.6765, allocations: 336 kB / 0.6828 GB, free: 14.59 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005404/0.6819, allocations: 1.88 MB / 0.6846 GB, free: 12.71 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0009958/0.6829, allocations: 0.9888 MB / 0.6856 GB, free: 11.72 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.00239/0.6853, allocations: 1.912 MB / 0.6874 GB, free: 9.797 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01049/0.6957, allocations: 5.729 MB / 0.693 GB, free: 4.047 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.833e-06/0.6957, allocations: 0 / 0.693 GB, free: 4.047 MB / 494.1 MB
Notification: Performance of FrontEnd: time 2.876e-06/0.6957, allocations: 3.938 kB / 0.693 GB, free: 4.043 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0001302/0.6959, allocations: 0 / 0.693 GB, free: 4.043 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 993
 * Number of variables: 993
Notification: Performance of Generate backend data structure: time 0.008416/0.7043, allocations: 4.081 MB / 0.697 GB, free: 15.89 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 5.42e-05/0.7043, allocations: 15.97 kB / 0.697 GB, free: 15.88 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 0.0001154/0.7045, allocations: 86.62 kB / 0.6971 GB, free: 15.79 MB / 0.4981 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0006927/0.7052, allocations: 499.6 kB / 0.6976 GB, free: 15.29 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001924/0.7071, allocations: 0.6978 MB / 0.6983 GB, free: 14.59 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002982/0.7101, allocations: 1.773 MB / 0.7 GB, free: 12.77 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005499/0.7106, allocations: 480.1 kB / 0.7005 GB, free: 12.28 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004622/0.7111, allocations: 336.2 kB / 0.7008 GB, free: 11.95 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01475/0.7258, allocations: 11.8 MB / 0.7123 GB, free: 132 kB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.482e-05/0.7259, allocations: 7 kB / 0.7123 GB, free: 128 kB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000278/0.7262, allocations: 132 kB / 0.7124 GB, free: 15.99 MB / 0.5137 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005571/0.7267, allocations: 499.5 kB / 0.7129 GB, free: 15.5 MB / 0.5137 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02421/0.7509, allocations: 20.48 MB / 0.7329 GB, free: 11.02 MB / 0.5294 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05043/0.8014, allocations: 30.55 MB / 0.7628 GB, free: 12.13 MB / 0.5606 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008493/0.8098, allocations: 5.318 MB / 0.7679 GB, free: 6.801 MB / 0.5606 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006438/0.8163, allocations: 4.256 MB / 0.7721 GB, free: 2.547 MB / 0.5606 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.003442/0.8197, allocations: 1.826 MB / 0.7739 GB, free: 468 kB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.047e-05/0.8198, allocations: 51.88 kB / 0.7739 GB, free: 408 kB / 0.5606 GB
Notification: Performance of pre-optimization done (n=150): time 4.108e-06/0.8198, allocations: 0 / 0.7739 GB, free: 408 kB / 0.5606 GB
Notification: Performance of matching and sorting (n=232): time 0.5047/1.324, allocations: 48.3 MB / 0.8211 GB, free: 234.7 MB / 0.6075 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.1827/1.507, allocations: 61.02 MB / 0.8807 GB, free: 196.6 MB / 0.6075 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004693/1.512, allocations: 3.739 MB / 0.8843 GB, free: 192.9 MB / 0.6075 GB
Notification: Performance of collectPreVariables (initialization): time 0.000959/1.513, allocations: 61.7 kB / 0.8844 GB, free: 192.9 MB / 0.6075 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0009283/1.514, allocations: 1.622 MB / 0.886 GB, free: 191.5 MB / 0.6075 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001337/1.515, allocations: 0.8116 MB / 0.8868 GB, free: 190.8 MB / 0.6075 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002056/1.517, allocations: 0.7695 MB / 0.8875 GB, free: 190 MB / 0.6075 GB
Notification: Performance of setup shared object (initialization): time 3.961e-05/1.517, allocations: 305.1 kB / 0.8878 GB, free: 189.7 MB / 0.6075 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007657/1.525, allocations: 7.382 MB / 0.895 GB, free: 182.3 MB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008086/1.533, allocations: 7.719 MB / 0.9026 GB, free: 174.5 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2296/1.762, allocations: 43.96 MB / 0.9455 GB, free: 130.5 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.192e-05/1.762, allocations: 4 kB / 0.9455 GB, free: 130.4 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=289) (initialization): time 0.04423/1.807, allocations: 20.43 MB / 0.9655 GB, free: 110 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 3.058e-05/1.807, allocations: 8 kB / 0.9655 GB, free: 110 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.309e-05/1.807, allocations: 9.531 kB / 0.9655 GB, free: 110 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0379/1.845, allocations: 10.37 MB / 0.9756 GB, free: 99.61 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00656/1.851, allocations: 0.9597 MB / 0.9765 GB, free: 98.64 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.05791/1.909, allocations: 41.58 MB / 1.017 GB, free: 56.59 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006213/1.915, allocations: 264.7 kB / 1.017 GB, free: 56.33 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001614/1.917, allocations: 0.527 MB / 1.018 GB, free: 55.8 MB / 0.6075 GB
Warning: Assuming fixed start value for the following 1 variables:
         gasVolatility.rear.n_flow:VARIABLE(flow=true unit = \"mol/s\" fixed = true )  \"Molar change of the substance\" type: Real
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 36
 * 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 (212):
 * Single equations (assignments): 201
 * Array equations: 4
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 7
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(3,5,88.9%), (1,4,100.0%), (5,13,76.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 4 systems
   {(3,8), (2,1), (6,20), (4,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.003698/1.921, allocations: 0.8768 MB / 1.019 GB, free: 54.92 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00183/1.922, allocations: 345.7 kB / 1.019 GB, free: 54.58 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04211/1.965, allocations: 14.04 MB / 1.033 GB, free: 40.5 MB / 0.6075 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.03605/2.001, allocations: 11.51 MB / 1.044 GB, free: 28.98 MB / 0.6075 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.012e-05/2.001, allocations: 4 kB / 1.044 GB, free: 28.97 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.933e-05/2.001, allocations: 15.89 kB / 1.044 GB, free: 28.96 MB / 0.6075 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04738/2.048, allocations: 23.35 MB / 1.067 GB, free: 5.266 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.556e-05/2.048, allocations: 4 kB / 1.067 GB, free: 5.262 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004523/2.053, allocations: 0.4958 MB / 1.067 GB, free: 4.766 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.4346/2.487, allocations: 10.9 MB / 1.078 GB, free: 225.9 MB / 0.6075 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0009704/2.488, allocations: 142 kB / 1.078 GB, free: 225.9 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.04047/2.529, allocations: 20.41 MB / 1.098 GB, free: 215.9 MB / 0.6075 GB
Error: Internal error IndexReduction.pantelidesIndexReduction failed! System is structurally singular and cannot be handled because the number of unassigned equations is larger than the number of states. Use -d=bltdump to get more information.
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.0945/2.623, allocations: 20.45 MB / 1.118 GB, free: 205.7 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03887/2.662, allocations: 17.75 MB / 1.135 GB, free: 189.2 MB / 0.6075 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001516/2.664, allocations: 0.5289 MB / 1.136 GB, free: 188.8 MB / 0.6075 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001149/2.665, allocations: 57.91 kB / 1.136 GB, free: 188.7 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004215/2.669, allocations: 183.8 kB / 1.136 GB, free: 188.6 MB / 0.6075 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001159/2.67, allocations: 284.8 kB / 1.136 GB, free: 188.3 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004446/2.671, allocations: 247.8 kB / 1.137 GB, free: 188 MB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.003752/2.674, allocations: 3.418 MB / 1.14 GB, free: 184.6 MB / 0.6075 GB
Notification: Performance of sort global known variables: time 1.1e-07/2.674, allocations: 0 / 1.14 GB, free: 184.6 MB / 0.6075 GB
Notification: Performance of remove unused functions: time 0.004718/2.679, allocations: 1.487 MB / 1.141 GB, free: 183.2 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 19
 * Number of states: 6 ($STATESET2.x[3],$STATESET2.x[2],$STATESET2.x[1],$STATESET1.x[2],$STATESET1.x[1],gasVolatility.rear.n_flow)
 * Number of discrete variables: 3 ($cse53,$cse46,$cse39)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (136):
 * Single equations (assignments): 131
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 5
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 3 systems
   {(1,4,100.0%), (5,13,76.0%), (3,5,88.9%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 2 systems
   {(15,39), (8,17)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.03684/2.716, allocations: 17.32 MB / 1.158 GB, free: 165.8 MB / 0.6075 GB
Notification: Performance of simCode: created initialization part: time 0.02184/2.738, allocations: 11.49 MB / 1.17 GB, free: 154.3 MB / 0.6075 GB
Notification: Performance of simCode: created event and clocks part: time 1.085e-05/2.738, allocations: 4.125 kB / 1.17 GB, free: 154.3 MB / 0.6075 GB
Notification: Performance of simCode: created simulation system equations: time 0.01088/2.749, allocations: 4.742 MB / 1.174 GB, free: 149.6 MB / 0.6075 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003717/2.752, allocations: 0.7073 MB / 1.175 GB, free: 148.9 MB / 0.6075 GB
[/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/SimCode/SimCodeUtil.mo:4705:9-4705:59:writable] Error: Internal error function createStateSetsSets failed.
[/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelica/OMCompiler/Compiler/SimCode/SimCodeUtil.mo:818:5-818:146:writable] Error: Internal error function createSimCode failed [Transformation from optimised DAE to simulation code structure failed]
"
[Timeout remaining time 657]
[Calling sys.exit(0), Time elapsed: 5.448090585006867]
Failed to read output from testmodel.py, exit status != 0:
2.7607295570196584 2.769887723 2.071853152
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f179da233a0>