Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_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.01741/0.01741, allocations: 1.792 MB / 21.15 MB, free: 3.246 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.001705/0.001705, allocations: 75.67 kB / 24.88 MB, free: 6.828 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.001902/0.001902, allocations: 164.4 kB / 28.74 MB, free: 3.656 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.586/1.586, allocations: 177.2 MB / 209.6 MB, free: 2.391 MB / 186.7 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.117/1.117, allocations: 102.5 MB / 369.1 MB, free: 0.9062 MB / 330.8 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 1.0.0 (/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_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_ClaRa.Visualisation.Check.TestHEXdisplay") [Timeout 800] "Notification: Performance of FrontEnd - loaded program: time 1.783e-06/1.783e-06, allocations: 0 / 469.1 MB, free: 13.04 MB / 442.8 MB Notification: Performance of FrontEnd - Absyn->SCode: time 2.863e-05/3.042e-05, allocations: 2.312 kB / 469.1 MB, free: 13.04 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.4046/0.4046, allocations: 80.51 MB / 0.5367 GB, free: 65.1 MB / 490.8 MB Notification: Performance of NFInst.instExpressions: time 0.03867/0.4433, allocations: 28.74 MB / 0.5648 GB, free: 45.2 MB / 490.8 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.008216/0.4515, allocations: 187.8 kB / 0.565 GB, free: 45.12 MB / 490.8 MB [/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.008678/0.4602, allocations: 3.051 MB / 0.568 GB, free: 43.66 MB / 490.8 MB Notification: Performance of NFTyping.typeBindings: time 0.01551/0.4757, allocations: 5.676 MB / 0.5735 GB, free: 40.09 MB / 490.8 MB Notification: Performance of NFTyping.typeClassSections: time 0.0145/0.4902, allocations: 7.352 MB / 0.5807 GB, free: 35.16 MB / 490.8 MB Notification: Performance of NFFlatten.flatten: time 0.02021/0.5104, allocations: 18.29 MB / 0.5986 GB, free: 21.63 MB / 490.8 MB Notification: Performance of NFFlatten.resolveConnections: time 0.001948/0.5123, allocations: 0.5813 MB / 0.5991 GB, free: 21.02 MB / 490.8 MB Notification: Performance of NFEvalConstants.evaluate: time 0.01776/0.5301, allocations: 9.441 MB / 0.6083 GB, free: 11.56 MB / 490.8 MB Notification: Performance of NFSimplifyModel.simplify: time 0.01032/0.5404, allocations: 6.637 MB / 0.6148 GB, free: 4.906 MB / 490.8 MB Notification: Performance of NFPackage.collectConstants: time 0.003026/0.5434, allocations: 1.276 MB / 0.6161 GB, free: 3.633 MB / 490.8 MB Notification: Performance of NFFlatten.collectFunctions: time 0.04459/0.588, allocations: 7.447 MB / 0.6233 GB, free: 13.93 MB / 0.4949 GB Notification: Performance of NFScalarize.scalarize: time 0.008266/0.5963, allocations: 4.502 MB / 0.6277 GB, free: 9.41 MB / 0.4949 GB Notification: Performance of NFVerifyModel.verify: time 0.01346/0.6098, allocations: 4.224 MB / 0.6319 GB, free: 5.176 MB / 0.4949 GB Notification: Performance of NFConvertDAE.convert: time 0.03412/0.6439, allocations: 19.88 MB / 0.6513 GB, free: 1.238 MB / 0.5105 GB Notification: Performance of FrontEnd - DAE generated: time 6.201e-06/0.6439, allocations: 0 / 0.6513 GB, free: 1.238 MB / 0.5105 GB Notification: Performance of FrontEnd: time 1.994e-06/0.6439, allocations: 4 kB / 0.6513 GB, free: 1.234 MB / 0.5105 GB Notification: Performance of Transformations before backend: time 0.0005739/0.6445, allocations: 2.688 kB / 0.6513 GB, free: 1.234 MB / 0.5105 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.04149/0.686, allocations: 14.19 MB / 0.6651 GB, free: 2.977 MB / 0.5262 GB Notification: Performance of prepare preOptimizeDAE: time 4.636e-05/0.686, allocations: 8.031 kB / 0.6652 GB, free: 2.969 MB / 0.5262 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007436/0.6934, allocations: 2.037 MB / 0.6671 GB, free: 0.9258 MB / 0.5262 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.01906/0.7125, allocations: 7.616 MB / 0.6746 GB, free: 9.234 MB / 0.5418 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007427/0.7132, allocations: 0.5246 MB / 0.6751 GB, free: 8.707 MB / 0.5418 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002741/0.716, allocations: 0.6551 MB / 0.6757 GB, free: 8.055 MB / 0.5418 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.05044/0.7664, allocations: 19.55 MB / 0.6948 GB, free: 1.668 MB / 0.5574 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0003099/0.7667, allocations: 110.9 kB / 0.6949 GB, free: 1.559 MB / 0.5574 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002088/0.7688, allocations: 319.9 kB / 0.6952 GB, free: 1.246 MB / 0.5574 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001997/0.7708, allocations: 1.42 MB / 0.6966 GB, free: 15.82 MB / 0.573 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04068/0.8115, allocations: 11.98 MB / 0.7083 GB, free: 3.848 MB / 0.573 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.3246/1.136, allocations: 136.7 MB / 0.8418 GB, free: 75.2 MB / 0.6043 GB Notification: Performance of preOpt comSubExp (simulation): time 0.03312/1.169, allocations: 10.02 MB / 0.8516 GB, free: 71.94 MB / 0.6043 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.008769/1.178, allocations: 3.036 MB / 0.8546 GB, free: 70.73 MB / 0.6043 GB Notification: Performance of preOpt evalFunc (simulation): time 0.001475/1.179, allocations: 353.1 kB / 0.8549 GB, free: 70.66 MB / 0.6043 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001415/1.18, allocations: 145.7 kB / 0.855 GB, free: 70.57 MB / 0.6043 GB Notification: Performance of pre-optimization done (n=1361): time 1.817e-05/1.18, allocations: 2.688 kB / 0.855 GB, free: 70.57 MB / 0.6043 GB Notification: Performance of matching and sorting (n=1361): time 0.06219/1.242, allocations: 18.16 MB / 0.8728 GB, free: 66.79 MB / 0.6043 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003076/1.242, allocations: 1.073 MB / 0.8738 GB, free: 65.55 MB / 0.6043 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01488/1.257, allocations: 9.519 MB / 0.8831 GB, free: 59.75 MB / 0.6043 GB Notification: Performance of collectPreVariables (initialization): time 0.002374/1.259, allocations: 129.7 kB / 0.8833 GB, free: 59.62 MB / 0.6043 GB Notification: Performance of collectInitialEqns (initialization): time 0.00378/1.263, allocations: 5.475 MB / 0.8886 GB, free: 54.71 MB / 0.6043 GB Notification: Performance of collectInitialBindings (initialization): time 0.006511/1.27, allocations: 5.218 MB / 0.8937 GB, free: 49.83 MB / 0.6043 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.00758/1.277, allocations: 3.962 MB / 0.8976 GB, free: 45.86 MB / 0.6043 GB Notification: Performance of setup shared object (initialization): time 6.527e-05/1.277, allocations: 484.7 kB / 0.898 GB, free: 45.38 MB / 0.6043 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.009522/1.287, allocations: 4.174 MB / 0.9021 GB, free: 41.2 MB / 0.6043 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.01406/1.301, allocations: 12.14 MB / 0.914 GB, free: 25.96 MB / 0.6043 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.02405/1.325, allocations: 17.85 MB / 0.9314 GB, free: 5.305 MB / 0.6043 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001926/1.325, allocations: 48 kB / 0.9314 GB, free: 5.258 MB / 0.6043 GB Notification: Performance of matching and sorting (n=2086) (initialization): time 0.04994/1.375, allocations: 16.4 MB / 0.9474 GB, free: 4.945 MB / 0.6199 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001645/1.375, allocations: 79.94 kB / 0.9475 GB, free: 4.867 MB / 0.6199 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.03602/1.411, allocations: 10.66 MB / 0.9579 GB, free: 10.21 MB / 0.6355 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.02183/1.433, allocations: 2.937 MB / 0.9608 GB, free: 7.352 MB / 0.6355 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01646/1.45, allocations: 3.944 MB / 0.9647 GB, free: 3.406 MB / 0.6355 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.2558/1.705, allocations: 19.11 MB / 0.9833 GB, free: 192.5 MB / 0.6512 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01123/1.717, allocations: 1.696 MB / 0.985 GB, free: 192.5 MB / 0.6512 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003041/1.72, allocations: 1.003 MB / 0.9859 GB, free: 192.5 MB / 0.6512 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01509/1.735, allocations: 5.462 MB / 0.9913 GB, free: 192.4 MB / 0.6512 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01461/1.749, allocations: 12.12 MB / 1.003 GB, free: 183.4 MB / 0.6512 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02686/1.776, allocations: 17.78 MB / 1.02 GB, free: 174.1 MB / 0.6512 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0003285/1.776, allocations: 47.19 kB / 1.021 GB, free: 174.1 MB / 0.6512 GB Notification: Performance of matching and sorting (n=2086) (initialization_lambda0): time 0.03911/1.816, allocations: 15.6 MB / 1.036 GB, free: 169.8 MB / 0.6512 GB Notification: Performance of prepare postOptimizeDAE: time 0.0001616/1.816, allocations: 77.53 kB / 1.036 GB, free: 169.8 MB / 0.6512 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.02471/1.84, allocations: 9.697 MB / 1.045 GB, free: 166.8 MB / 0.6512 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.001175/1.842, allocations: 392.2 kB / 1.046 GB, free: 166.8 MB / 0.6512 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01578/1.857, allocations: 4.175 MB / 1.05 GB, free: 166.3 MB / 0.6512 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007274/1.865, allocations: 13 MB / 1.062 GB, free: 152.9 MB / 0.6512 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01121/1.876, allocations: 1.393 MB / 1.064 GB, free: 151.8 MB / 0.6512 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.003257/1.879, allocations: 1.002 MB / 1.065 GB, free: 151 MB / 0.6512 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.005141/1.884, allocations: 1.197 MB / 1.066 GB, free: 149.9 MB / 0.6512 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.005035/1.889, allocations: 1.185 MB / 1.067 GB, free: 148.7 MB / 0.6512 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.03592/1.925, allocations: 26.94 MB / 1.093 GB, free: 126.1 MB / 0.6512 GB Error: post-optimization module wrapFunctionCalls (simulation) failed. " [Timeout remaining time 798] [Calling sys.exit(0), Time elapsed: 5.558860459947027]