Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_dev_ClaRa.Visualisation.Check.TestHEXdisplay.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.01231/0.01231, allocations: 1.447 MB / 20.8 MB, free: 3.59 MB / 18.57 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.007254/0.007254, allocations: 81.09 kB / 24.45 MB, free: 7.512 MB / 18.57 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.001535/0.001535, allocations: 163.1 kB / 28.2 MB, free: 4.641 MB / 18.57 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.448/1.448, allocations: 177.2 MB / 209 MB, free: 2.793 MB / 186.7 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.074/1.074, allocations: 102.5 MB / 368.3 MB, free: 1.27 MB / 330.8 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.5.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo) Running command: translateModel(ClaRa.Visualisation.Check.TestHEXdisplay,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="NTU_counter.PI_1ph_in.outputI|NTU_counter.PI_2ph.outputI|NTU_counter.nTU.Q_flow_s.1.|NTU_counter.nTU.Q_flow_s.2.|NTU_counter.nTU.Q_flow_s.3.|NTU_counter.nTU.T_w_i.1.|NTU_counter.nTU.T_w_i.2.|NTU_counter.nTU.T_w_i.3.|NTU_counter.nTU.T_w_o.1.|NTU_counter.nTU.T_w_o.2.|NTU_counter.nTU.T_w_o.3.|NTU_counter.nTU.yps.1.|NTU_counter.nTU.yps.2.|NTU_counter.nTU.yps.3.|NTU_cross.PI_1ph_in.outputI|NTU_cross.PI_2ph.outputI|NTU_cross.nTU.Q_flow_s.1.|NTU_cross.nTU.Q_flow_s.2.|NTU_cross.nTU.Q_flow_s.3.|NTU_cross.nTU.T_w_i.1.|NTU_cross.nTU.T_w_i.2.|NTU_cross.nTU.T_w_i.3.|NTU_cross.nTU.T_w_o.1.|NTU_cross.nTU.T_w_o.2.|NTU_cross.nTU.T_w_o.3.|NTU_cross.nTU.yps.1.|NTU_cross.nTU.yps.2.|NTU_cross.nTU.yps.3.|NTU_parallel.PI_1ph_in.outputI|NTU_parallel.PI_2ph.outputI|NTU_parallel.nTU.Q_flow_s.1.|NTU_parallel.nTU.Q_flow_s.2.|NTU_parallel.nTU.Q_flow_s.3.|NTU_parallel.nTU.T_w_i.1.|NTU_parallel.nTU.T_w_i.2.|NTU_parallel.nTU.T_w_i.3.|NTU_parallel.nTU.T_w_o.1.|NTU_parallel.nTU.T_w_o.2.|NTU_parallel.nTU.T_w_o.3.|NTU_parallel.nTU.yps.1.|NTU_parallel.nTU.yps.2.|NTU_parallel.nTU.yps.3.|Time",fileNamePrefix="ClaRa_dev_ClaRa.Visualisation.Check.TestHEXdisplay") translateModel(ClaRa.Visualisation.Check.TestHEXdisplay,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="NTU_counter.PI_1ph_in.outputI|NTU_counter.PI_2ph.outputI|NTU_counter.nTU.Q_flow_s.1.|NTU_counter.nTU.Q_flow_s.2.|NTU_counter.nTU.Q_flow_s.3.|NTU_counter.nTU.T_w_i.1.|NTU_counter.nTU.T_w_i.2.|NTU_counter.nTU.T_w_i.3.|NTU_counter.nTU.T_w_o.1.|NTU_counter.nTU.T_w_o.2.|NTU_counter.nTU.T_w_o.3.|NTU_counter.nTU.yps.1.|NTU_counter.nTU.yps.2.|NTU_counter.nTU.yps.3.|NTU_cross.PI_1ph_in.outputI|NTU_cross.PI_2ph.outputI|NTU_cross.nTU.Q_flow_s.1.|NTU_cross.nTU.Q_flow_s.2.|NTU_cross.nTU.Q_flow_s.3.|NTU_cross.nTU.T_w_i.1.|NTU_cross.nTU.T_w_i.2.|NTU_cross.nTU.T_w_i.3.|NTU_cross.nTU.T_w_o.1.|NTU_cross.nTU.T_w_o.2.|NTU_cross.nTU.T_w_o.3.|NTU_cross.nTU.yps.1.|NTU_cross.nTU.yps.2.|NTU_cross.nTU.yps.3.|NTU_parallel.PI_1ph_in.outputI|NTU_parallel.PI_2ph.outputI|NTU_parallel.nTU.Q_flow_s.1.|NTU_parallel.nTU.Q_flow_s.2.|NTU_parallel.nTU.Q_flow_s.3.|NTU_parallel.nTU.T_w_i.1.|NTU_parallel.nTU.T_w_i.2.|NTU_parallel.nTU.T_w_i.3.|NTU_parallel.nTU.T_w_o.1.|NTU_parallel.nTU.T_w_o.2.|NTU_parallel.nTU.T_w_o.3.|NTU_parallel.nTU.yps.1.|NTU_parallel.nTU.yps.2.|NTU_parallel.nTU.yps.3.|Time",fileNamePrefix="ClaRa_dev_ClaRa.Visualisation.Check.TestHEXdisplay") [Timeout 800] "Notification: Performance of FrontEnd - loaded program: time 1.814e-06/1.814e-06, allocations: 0 / 468.3 MB, free: 13.47 MB / 442.8 MB Notification: Performance of FrontEnd - Absyn->SCode: time 5.037e-05/5.218e-05, allocations: 11.8 kB / 468.3 MB, free: 13.46 MB / 442.8 MB 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: Automatically loaded package TILMedia 1.9.0 ClaRa due to usage. Notification: Performance of NFInst.instantiate(ClaRa.Visualisation.Check.TestHEXdisplay): time 0.4458/0.4459, allocations: 76.01 MB / 0.5315 GB, free: 67.73 MB / 490.8 MB Notification: Performance of NFInst.instExpressions: time 0.04489/0.4908, allocations: 28.17 MB / 0.5591 GB, free: 48.7 MB / 490.8 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.01026/0.501, allocations: 187.1 kB / 0.5592 GB, free: 48.62 MB / 490.8 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa main/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.009937/0.511, allocations: 3.051 MB / 0.5622 GB, free: 47.16 MB / 490.8 MB Notification: Performance of NFTyping.typeBindings: time 0.02024/0.5312, allocations: 5.666 MB / 0.5677 GB, free: 43.59 MB / 490.8 MB Notification: Performance of NFTyping.typeClassSections: time 0.0184/0.5496, allocations: 7.353 MB / 0.5749 GB, free: 38.91 MB / 490.8 MB Notification: Performance of NFFlatten.flatten: time 0.02729/0.5769, allocations: 18.29 MB / 0.5928 GB, free: 26.7 MB / 490.8 MB Notification: Performance of NFFlatten.resolveConnections: time 0.003277/0.5802, allocations: 0.5852 MB / 0.5934 GB, free: 26.09 MB / 490.8 MB Notification: Performance of NFEvalConstants.evaluate: time 0.02356/0.6037, allocations: 9.441 MB / 0.6026 GB, free: 16.63 MB / 490.8 MB Notification: Performance of NFSimplifyModel.simplify: time 0.01217/0.6159, allocations: 6.608 MB / 0.609 GB, free: 10.01 MB / 490.8 MB Notification: Performance of NFPackage.collectConstants: time 0.004784/0.6207, allocations: 1.273 MB / 0.6103 GB, free: 8.734 MB / 490.8 MB Notification: Performance of NFFlatten.collectFunctions: time 0.1311/0.7518, allocations: 22.87 MB / 0.6326 GB, free: 3.949 MB / 0.4949 GB Notification: Performance of NFScalarize.scalarize: time 0.009662/0.7615, allocations: 4.487 MB / 0.637 GB, free: 15.45 MB / 0.5105 GB Notification: Performance of NFVerifyModel.verify: time 0.01486/0.7763, allocations: 4.216 MB / 0.6411 GB, free: 11.22 MB / 0.5105 GB Notification: Performance of NFConvertDAE.convert: time 0.03542/0.8118, allocations: 19.84 MB / 0.6605 GB, free: 7.328 MB / 0.5262 GB Notification: Performance of FrontEnd - DAE generated: time 6.843e-06/0.8118, allocations: 2.656 kB / 0.6605 GB, free: 7.328 MB / 0.5262 GB Notification: Performance of FrontEnd: time 2.615e-06/0.8118, allocations: 4 kB / 0.6605 GB, free: 7.324 MB / 0.5262 GB Notification: Performance of Transformations before backend: time 0.000654/0.8124, allocations: 0 / 0.6605 GB, free: 7.324 MB / 0.5262 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 3002 * Number of variables: 3002 Notification: Performance of Generate backend data structure: time 0.04009/0.8525, allocations: 12.63 MB / 0.6728 GB, free: 10.65 MB / 0.5418 GB Notification: Performance of prepare preOptimizeDAE: time 4.972e-05/0.8526, allocations: 8.031 kB / 0.6728 GB, free: 10.64 MB / 0.5418 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.008158/0.8607, allocations: 2.029 MB / 0.6748 GB, free: 8.605 MB / 0.5418 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.02038/0.8811, allocations: 8.11 MB / 0.6827 GB, free: 432 kB / 0.5418 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000894/0.882, allocations: 0.5246 MB / 0.6832 GB, free: 15.89 MB / 0.5574 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002901/0.8849, allocations: 0.6525 MB / 0.6839 GB, free: 15.24 MB / 0.5574 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0532/0.9381, allocations: 20.35 MB / 0.7038 GB, free: 8.824 MB / 0.573 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0002917/0.9384, allocations: 109.6 kB / 0.7039 GB, free: 8.719 MB / 0.573 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001617/0.94, allocations: 331.9 kB / 0.7042 GB, free: 8.395 MB / 0.573 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001953/0.942, allocations: 1.413 MB / 0.7056 GB, free: 6.977 MB / 0.573 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04928/0.9912, allocations: 15.79 MB / 0.721 GB, free: 7.199 MB / 0.5887 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.3226/1.314, allocations: 83.07 MB / 0.8021 GB, free: 119.4 MB / 0.6043 GB Notification: Performance of preOpt comSubExp (simulation): time 0.03669/1.35, allocations: 13 MB / 0.8148 GB, free: 115.5 MB / 0.6043 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.01099/1.361, allocations: 4.34 MB / 0.819 GB, free: 113.6 MB / 0.6043 GB Notification: Performance of preOpt evalFunc (simulation): time 0.001555/1.363, allocations: 357.7 kB / 0.8194 GB, free: 113.6 MB / 0.6043 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001794/1.363, allocations: 143.3 kB / 0.8195 GB, free: 113.5 MB / 0.6043 GB Notification: Performance of pre-optimization done (n=1361): time 2.007e-05/1.363, allocations: 0 / 0.8195 GB, free: 113.5 MB / 0.6043 GB Notification: Performance of matching and sorting (n=1361): time 0.07224/1.435, allocations: 22.51 MB / 0.8415 GB, free: 107.3 MB / 0.6043 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003205/1.436, allocations: 1.068 MB / 0.8425 GB, free: 106.1 MB / 0.6043 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01432/1.45, allocations: 7.047 MB / 0.8494 GB, free: 102.8 MB / 0.6043 GB Notification: Performance of collectPreVariables (initialization): time 0.002426/1.453, allocations: 130.6 kB / 0.8495 GB, free: 102.7 MB / 0.6043 GB Notification: Performance of collectInitialEqns (initialization): time 0.003832/1.456, allocations: 4.711 MB / 0.8541 GB, free: 98.63 MB / 0.6043 GB Notification: Performance of collectInitialBindings (initialization): time 0.005701/1.462, allocations: 4.641 MB / 0.8587 GB, free: 94.39 MB / 0.6043 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.01072/1.473, allocations: 3.083 MB / 0.8617 GB, free: 91.3 MB / 0.6043 GB Notification: Performance of setup shared object (initialization): time 4.654e-05/1.473, allocations: 305.1 kB / 0.862 GB, free: 91 MB / 0.6043 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.01345/1.486, allocations: 6.056 MB / 0.8679 GB, free: 84.94 MB / 0.6043 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.01726/1.504, allocations: 11.07 MB / 0.8787 GB, free: 71.64 MB / 0.6043 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.03184/1.535, allocations: 18.45 MB / 0.8967 GB, free: 51.15 MB / 0.6043 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002858/1.536, allocations: 44 kB / 0.8968 GB, free: 51.11 MB / 0.6043 GB Notification: Performance of matching and sorting (n=2086) (initialization): time 0.05487/1.591, allocations: 20.33 MB / 0.9166 GB, free: 30.89 MB / 0.6043 GB Notification: Performance of prepare postOptimizeDAE: time 0.0002152/1.591, allocations: 77.39 kB / 0.9167 GB, free: 30.82 MB / 0.6043 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.04044/1.631, allocations: 14.58 MB / 0.9309 GB, free: 16.28 MB / 0.6043 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02399/1.655, allocations: 4.06 MB / 0.9349 GB, free: 12.32 MB / 0.6043 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01524/1.67, allocations: 4.121 MB / 0.9389 GB, free: 8.207 MB / 0.6043 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02001/1.69, allocations: 16.25 MB / 0.9548 GB, free: 7.203 MB / 0.6199 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01389/1.704, allocations: 1.803 MB / 0.9565 GB, free: 5.398 MB / 0.6199 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004452/1.709, allocations: 1.008 MB / 0.9575 GB, free: 4.391 MB / 0.6199 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.02691/1.736, allocations: 8.437 MB / 0.9658 GB, free: 11.95 MB / 0.6355 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.2913/2.027, allocations: 11 MB / 0.9765 GB, free: 189.7 MB / 0.6355 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03265/2.06, allocations: 18.33 MB / 0.9944 GB, free: 180.2 MB / 0.6355 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0002859/2.06, allocations: 46.88 kB / 0.9944 GB, free: 180.2 MB / 0.6355 GB Notification: Performance of matching and sorting (n=2086) (initialization_lambda0): time 0.05615/2.116, allocations: 18.96 MB / 1.013 GB, free: 173.4 MB / 0.6355 GB Notification: Performance of prepare postOptimizeDAE: time 0.0003003/2.116, allocations: 75.69 kB / 1.013 GB, free: 173.4 MB / 0.6355 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.03619/2.153, allocations: 13.04 MB / 1.026 GB, free: 168.9 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.001609/2.154, allocations: 439.8 kB / 1.026 GB, free: 168.9 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0181/2.172, allocations: 4.36 MB / 1.03 GB, free: 168.5 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.009145/2.181, allocations: 9.828 MB / 1.04 GB, free: 159 MB / 0.6355 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01392/2.195, allocations: 1.508 MB / 1.042 GB, free: 158.2 MB / 0.6355 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004079/2.199, allocations: 0.9925 MB / 1.042 GB, free: 157.3 MB / 0.6355 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 750 * Number of states: 0 () * Number of discrete variables: 12 (NTU_parallel.nTU.smallShellFlow[3],NTU_parallel.nTU.smallShellFlow[1],NTU_parallel.nTU.mass,NTU_cross.nTU.smallShellFlow[3],NTU_cross.nTU.smallShellFlow[1],NTU_cross.nTU.mass,NTU_counter.nTU.smallShellFlow[3],NTU_counter.nTU.smallShellFlow[1],NTU_counter.nTU.mass,NTU_parallel.nTU.smallShellFlow[2],NTU_cross.nTU.smallShellFlow[2],NTU_counter.nTU.smallShellFlow[2]) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (1952): * Single equations (assignments): 1939 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 6 * Torn equation systems: 7 * Mixed (continuous/discrete) equation systems: 0 Notification: Equation system details (not torn): * Constant Jacobian (size): 0 systems * Linear Jacobian (size,density): 0 systems * Non-linear Jacobian (size): 6 systems {1, 1, 1, 1, 1, 1} * Without analytic Jacobian (size): 0 systems Notification: Torn system details for strict tearing set: * Linear torn systems (#iteration vars, #inner vars, density): 0 systems * Non-linear torn systems (#iteration vars, #inner vars): 7 systems {(4,43), (3,14), (1,12), (3,14), (3,14), (1,12), (3,14)} Notification: Performance of prepare postOptimizeDAE: time 0.007282/2.207, allocations: 1.114 MB / 1.044 GB, free: 156.3 MB / 0.6355 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.008833/2.215, allocations: 1.185 MB / 1.045 GB, free: 155.1 MB / 0.6355 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 1561 equation(s) and 1528 variable(s). [/home/hudson/saved_omc/libraries/.openmodelica/libraries/TILMedia main/VLEFluid/MixtureCompatible/VLEFluid_ph.mo:65:5-70:23:writable] Error: (NTU_cross.nTU.I1_out.VLE.d_l, NTU_cross.nTU.I1_out.VLE.h_l, NTU_cross.nTU.I1_out.VLE.p_l, NTU_cross.nTU.I1_out.VLE.s_l, NTU_cross.nTU.I1_out.VLE.T_l, _, NTU_cross.nTU.I1_out.VLE.d_v, NTU_cross.nTU.I1_out.VLE.h_v, NTU_cross.nTU.I1_out.VLE.p_v, NTU_cross.nTU.I1_out.VLE.s_v, NTU_cross.nTU.I1_out.VLE.T_v, _) = ($cse540, $cse541, $cse542, $cse543, $cse544, $cse545, $cse546, $cse547, $cse548, $cse549, $cse550, $cse551) has size 10 but 2 variables (NTU_counter.nTU.cp_i[1], NTU_counter.nTU.summary.C_flow_i[1]) Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed! Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04287/2.258, allocations: 22.17 MB / 1.066 GB, free: 137.3 MB / 0.6355 GB Error: post-optimization module wrapFunctionCalls (simulation) failed. " [Timeout remaining time 798] [Calling sys.exit(0), Time elapsed: 5.847280623973347]