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.008554/0.008554, allocations: 1.791 MB / 21.89 MB, free: 2.637 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.0008936/0.0008936, allocations: 85.2 kB / 25.62 MB, free: 6.969 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.0008995/0.0008995, allocations: 172.9 kB / 29.46 MB, free: 3.172 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 0.9183/0.9183, allocations: 177.1 MB / 210.3 MB, free: 3.609 MB / 186.7 MB
"
[Timeout remaining time 179]
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.6043/0.6043, allocations: 102.4 MB / 369.8 MB, free: 400 kB / 330.7 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.[0-9,]+.|NTU_counter.nTU.T_w_i.[0-9,]+.|NTU_counter.nTU.T_w_o.[0-9,]+.|NTU_counter.nTU.yps.[0-9,]+.|NTU_cross.PI_1ph_in.outputI|NTU_cross.PI_2ph.outputI|NTU_cross.nTU.Q_flow_s.[0-9,]+.|NTU_cross.nTU.T_w_i.[0-9,]+.|NTU_cross.nTU.T_w_o.[0-9,]+.|NTU_cross.nTU.yps.[0-9,]+.|NTU_parallel.PI_1ph_in.outputI|NTU_parallel.PI_2ph.outputI|NTU_parallel.nTU.Q_flow_s.[0-9,]+.|NTU_parallel.nTU.T_w_i.[0-9,]+.|NTU_parallel.nTU.T_w_o.[0-9,]+.|NTU_parallel.nTU.yps.[0-9,]+.|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.[0-9,]+.|NTU_counter.nTU.T_w_i.[0-9,]+.|NTU_counter.nTU.T_w_o.[0-9,]+.|NTU_counter.nTU.yps.[0-9,]+.|NTU_cross.PI_1ph_in.outputI|NTU_cross.PI_2ph.outputI|NTU_cross.nTU.Q_flow_s.[0-9,]+.|NTU_cross.nTU.T_w_i.[0-9,]+.|NTU_cross.nTU.T_w_o.[0-9,]+.|NTU_cross.nTU.yps.[0-9,]+.|NTU_parallel.PI_1ph_in.outputI|NTU_parallel.PI_2ph.outputI|NTU_parallel.nTU.Q_flow_s.[0-9,]+.|NTU_parallel.nTU.T_w_i.[0-9,]+.|NTU_parallel.nTU.T_w_o.[0-9,]+.|NTU_parallel.nTU.yps.[0-9,]+.|Time",fileNamePrefix="ClaRa_ClaRa.Visualisation.Check.TestHEXdisplay") [Timeout 800]
"Notification: Performance of FrontEnd - loaded program: time 1.443e-06/1.443e-06, allocations: 0 / 469.9 MB, free: 19 MB / 410.8 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.804e-05/1.949e-05, allocations: 6.25 kB / 469.9 MB, free: 19 MB / 410.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.1196/0.1196, allocations: 82.81 MB / 0.5397 GB, free: 5.43 MB / 458.8 MB
Notification: Performance of NFInst.instExpressions: time 0.03229/0.1519, allocations: 28.85 MB / 0.5679 GB, free: 8.512 MB / 490.8 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004847/0.1567, allocations: 190.6 kB / 0.5681 GB, free: 8.324 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.004106/0.1608, allocations: 3.06 MB / 0.5711 GB, free: 5.246 MB / 490.8 MB
Notification: Performance of NFTyping.typeBindings: time 0.007969/0.1688, allocations: 5.672 MB / 0.5766 GB, free: 15.54 MB / 0.4949 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009776/0.1786, allocations: 7.358 MB / 0.5838 GB, free: 8.168 MB / 0.4949 GB
Notification: Performance of NFFlatten.flatten: time 0.01858/0.1972, allocations: 18.29 MB / 0.6016 GB, free: 5.824 MB / 0.5105 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001595/0.1988, allocations: 0.5786 MB / 0.6022 GB, free: 5.207 MB / 0.5105 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01383/0.2126, allocations: 9.451 MB / 0.6114 GB, free: 11.74 MB / 0.5262 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.009807/0.2224, allocations: 6.631 MB / 0.6179 GB, free: 5.09 MB / 0.5262 GB
Notification: Performance of NFPackage.collectConstants: time 0.002806/0.2252, allocations: 1.273 MB / 0.6192 GB, free: 3.816 MB / 0.5262 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01843/0.2436, allocations: 7.442 MB / 0.6264 GB, free: 13.82 MB / 0.5418 GB
Notification: Performance of NFScalarize.scalarize: time 0.002888/0.2465, allocations: 4.506 MB / 0.6308 GB, free: 9.297 MB / 0.5418 GB
Notification: Performance of NFVerifyModel.verify: time 0.004498/0.251, allocations: 4.216 MB / 0.6349 GB, free: 5.07 MB / 0.5418 GB
Notification: Performance of NFConvertDAE.convert: time 0.2083/0.4593, allocations: 11.61 MB / 0.6463 GB, free: 58.97 MB / 0.5418 GB
Notification: Performance of FrontEnd - DAE generated: time 3.978e-06/0.4593, allocations: 5.594 kB / 0.6463 GB, free: 58.97 MB / 0.5418 GB
Notification: Performance of FrontEnd: time 1.273e-06/0.4594, allocations: 0 / 0.6463 GB, free: 58.97 MB / 0.5418 GB
Notification: Performance of Transformations before backend: time 0.0004201/0.4598, allocations: 0.6562 kB / 0.6463 GB, free: 58.97 MB / 0.5418 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.01485/0.4746, allocations: 12.82 MB / 0.6588 GB, free: 54.05 MB / 0.5418 GB
Notification: Performance of prepare preOptimizeDAE: time 3.584e-05/0.4747, allocations: 8.719 kB / 0.6588 GB, free: 54.05 MB / 0.5418 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002838/0.4775, allocations: 2.027 MB / 0.6608 GB, free: 53.3 MB / 0.5418 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.007225/0.4847, allocations: 7.526 MB / 0.6681 GB, free: 51.84 MB / 0.5418 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002672/0.485, allocations: 0.5329 MB / 0.6687 GB, free: 51.82 MB / 0.5418 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006989/0.4857, allocations: 0.6421 MB / 0.6693 GB, free: 51.82 MB / 0.5418 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02158/0.5073, allocations: 16.01 MB / 0.6849 GB, free: 43.68 MB / 0.5418 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001755/0.5074, allocations: 107.2 kB / 0.685 GB, free: 43.68 MB / 0.5418 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006348/0.5081, allocations: 328.6 kB / 0.6853 GB, free: 43.67 MB / 0.5418 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.001136/0.5092, allocations: 1.414 MB / 0.6867 GB, free: 43.66 MB / 0.5418 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01899/0.5282, allocations: 8.554 MB / 0.6951 GB, free: 43.37 MB / 0.5418 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05568/0.5839, allocations: 108.9 MB / 0.8015 GB, free: 9.008 MB / 0.6043 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01597/0.5998, allocations: 7.767 MB / 0.8091 GB, free: 5.285 MB / 0.6043 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003618/0.6035, allocations: 2.047 MB / 0.811 GB, free: 4.27 MB / 0.6043 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0005562/0.604, allocations: 355 kB / 0.8114 GB, free: 4.152 MB / 0.6043 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 8.995e-05/0.6041, allocations: 145 kB / 0.8115 GB, free: 4.047 MB / 0.6043 GB
Notification: Performance of pre-optimization done (n=1361): time 8.356e-06/0.6041, allocations: 0 / 0.8115 GB, free: 4.047 MB / 0.6043 GB
Notification: Performance of matching and sorting (n=1361): time 0.03383/0.6379, allocations: 14.04 MB / 0.8252 GB, free: 9.859 MB / 0.6199 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0006218/0.6386, allocations: 1.069 MB / 0.8263 GB, free: 8.559 MB / 0.6199 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009993/0.6486, allocations: 6.506 MB / 0.8326 GB, free: 2.043 MB / 0.6199 GB
Notification: Performance of collectPreVariables (initialization): time 0.001395/0.65, allocations: 129.7 kB / 0.8328 GB, free: 1.91 MB / 0.6199 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003445/0.6534, allocations: 4.928 MB / 0.8376 GB, free: 13 MB / 0.6355 GB
Notification: Performance of collectInitialBindings (initialization): time 0.004998/0.6584, allocations: 4.766 MB / 0.8422 GB, free: 8.301 MB / 0.6355 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.004625/0.663, allocations: 3.253 MB / 0.8454 GB, free: 5.031 MB / 0.6355 GB
Notification: Performance of setup shared object (initialization): time 0.0001122/0.6631, allocations: 476.8 kB / 0.8459 GB, free: 4.562 MB / 0.6355 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006271/0.6694, allocations: 2.265 MB / 0.8481 GB, free: 2.281 MB / 0.6355 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01043/0.6798, allocations: 9.539 MB / 0.8574 GB, free: 5.656 MB / 0.6512 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01798/0.6978, allocations: 13.02 MB / 0.8701 GB, free: 6.375 MB / 0.6668 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.822e-05/0.6979, allocations: 44 kB / 0.8701 GB, free: 6.332 MB / 0.6668 GB
Notification: Performance of matching and sorting (n=2086) (initialization): time 0.2105/0.9084, allocations: 12.55 MB / 0.8824 GB, free: 229.7 MB / 0.6668 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001067/0.9085, allocations: 74.91 kB / 0.8825 GB, free: 229.7 MB / 0.6668 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.01887/0.9274, allocations: 7.62 MB / 0.8899 GB, free: 229.4 MB / 0.6668 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01013/0.9375, allocations: 2.871 MB / 0.8927 GB, free: 229.3 MB / 0.6668 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009429/0.9469, allocations: 3.938 MB / 0.8966 GB, free: 229.2 MB / 0.6668 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007494/0.9544, allocations: 18.47 MB / 0.9146 GB, free: 212.4 MB / 0.6668 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008176/0.9626, allocations: 1.695 MB / 0.9162 GB, free: 212.4 MB / 0.6668 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002143/0.9647, allocations: 1.008 MB / 0.9172 GB, free: 212.4 MB / 0.6668 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.008374/0.9731, allocations: 2.497 MB / 0.9197 GB, free: 212.3 MB / 0.6668 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.007293/0.9804, allocations: 9.514 MB / 0.929 GB, free: 203.2 MB / 0.6668 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01302/0.9934, allocations: 12.97 MB / 0.9416 GB, free: 193.5 MB / 0.6668 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001173/0.9935, allocations: 46.81 kB / 0.9417 GB, free: 193.5 MB / 0.6668 GB
Notification: Performance of matching and sorting (n=2086) (initialization_lambda0): time 0.02404/1.018, allocations: 11.81 MB / 0.9532 GB, free: 190.8 MB / 0.6668 GB
Notification: Performance of prepare postOptimizeDAE: time 7.274e-05/1.018, allocations: 76 kB / 0.9533 GB, free: 190.7 MB / 0.6668 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.01332/1.031, allocations: 6.708 MB / 0.9598 GB, free: 184 MB / 0.6668 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006241/1.032, allocations: 378.2 kB / 0.9602 GB, free: 183.6 MB / 0.6668 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009016/1.041, allocations: 4.178 MB / 0.9643 GB, free: 179.5 MB / 0.6668 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003786/1.044, allocations: 12.45 MB / 0.9764 GB, free: 166.3 MB / 0.6668 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007114/1.051, allocations: 1.399 MB / 0.9778 GB, free: 165.1 MB / 0.6668 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001702/1.053, allocations: 0.9993 MB / 0.9788 GB, free: 164.1 MB / 0.6668 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.003499/1.057, allocations: 1.203 MB / 0.9799 GB, free: 162.9 MB / 0.6668 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003433/1.06, allocations: 1.184 MB / 0.9811 GB, free: 161.8 MB / 0.6668 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 <failed> postOpt wrapFunctionCalls (simulation): time 0.02026/1.08, allocations: 20.87 MB / 1.001 GB, free: 141.3 MB / 0.6668 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.
"
[Timeout remaining time 799]
[Calling sys.exit(0), Time elapsed: 3.5871910501737148]