Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001723/0.001723, allocations: 113.1 kB / 17.6 MB, free: 4.703 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.002172/0.002172, allocations: 186.7 kB / 20.78 MB, free: 1.547 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.515/1.515, allocations: 225.6 MB / 249.4 MB, free: 3.305 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.1.0-main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.1.0-main/package.mo): time 0.6324/0.6324, allocations: 94.57 MB / 399.1 MB, free: 15.8 MB / 334.1 MB
"
[Timeout remaining time 179]
Using package ThermofluidStream with version 1.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.1.0-main/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU,tolerance=1e-06,outputFormat="mat",numberOfIntervals=1000,variableFilter="Time|counterFlowNTU.h_out_A|counterFlowNTU.h_out_B|mCV.dp_int|mCV1.dp_int|sourceA.outlet.m_flow|sourceB.outlet.m_flow",fileNamePrefix="ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU")
translateModel(ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU,tolerance=1e-06,outputFormat="mat",numberOfIntervals=1000,variableFilter="Time|counterFlowNTU.h_out_A|counterFlowNTU.h_out_B|mCV.dp_int|mCV1.dp_int|sourceA.outlet.m_flow|sourceB.outlet.m_flow",fileNamePrefix="ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.0025/0.0025, allocations: 79.88 kB / 0.5448 GB, free: 12.11 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3895/0.392, allocations: 71.84 MB / 0.615 GB, free: 4.117 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU): time 0.2769/0.6688, allocations: 215.7 MB / 0.8256 GB, free: 12.65 MB / 0.6544 GB
Notification: Performance of NFInst.instExpressions: time 0.02605/0.6949, allocations: 17.61 MB / 0.8428 GB, free: 10.98 MB / 0.67 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001439/0.6963, allocations: 51.69 kB / 0.8428 GB, free: 10.93 MB / 0.67 GB
Notification: Performance of NFTyping.typeComponents: time 0.002124/0.6985, allocations: 0.6208 MB / 0.8434 GB, free: 10.31 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.005852/0.7043, allocations: 1.775 MB / 0.8451 GB, free: 8.527 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005494/0.7098, allocations: 1.689 MB / 0.8468 GB, free: 6.84 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.003051/0.7128, allocations: 1.724 MB / 0.8485 GB, free: 5.102 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005925/0.7134, allocations: 194.5 kB / 0.8487 GB, free: 4.902 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002983/0.7164, allocations: 1.226 MB / 0.8499 GB, free: 3.672 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001219/0.7176, allocations: 0.6374 MB / 0.8505 GB, free: 3.031 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.000294/0.7179, allocations: 92 kB / 0.8506 GB, free: 2.941 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.007628/0.7256, allocations: 3.471 MB / 0.854 GB, free: 15.46 MB / 0.6856 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004805/0.726, allocations: 250.9 kB / 0.8542 GB, free: 15.22 MB / 0.6856 GB
Notification: Performance of NFVerifyModel.verify: time 0.00115/0.7272, allocations: 0.6254 MB / 0.8548 GB, free: 14.59 MB / 0.6856 GB
Notification: Performance of NFConvertDAE.convert: time 0.00681/0.734, allocations: 2.386 MB / 0.8571 GB, free: 12.2 MB / 0.6856 GB
Notification: Performance of FrontEnd - DAE generated: time 7.194e-06/0.734, allocations: 4 kB / 0.8571 GB, free: 12.19 MB / 0.6856 GB
Notification: Performance of FrontEnd: time 1.894e-06/0.734, allocations: 0 / 0.8571 GB, free: 12.19 MB / 0.6856 GB
Notification: Performance of Transformations before backend: time 7.192e-05/0.7341, allocations: 4 kB / 0.8571 GB, free: 12.19 MB / 0.6856 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 227
 * Number of variables: 227
Notification: Performance of Generate backend data structure: time 0.004519/0.7386, allocations: 1.901 MB / 0.859 GB, free: 10.23 MB / 0.6856 GB
Notification: Performance of prepare preOptimizeDAE: time 3.884e-05/0.7386, allocations: 8.031 kB / 0.859 GB, free: 10.22 MB / 0.6856 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002138/0.7408, allocations: 298.8 kB / 0.8593 GB, free: 9.926 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.002117/0.7429, allocations: 0.9336 MB / 0.8602 GB, free: 8.949 MB / 0.6856 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002691/0.7432, allocations: 200.7 kB / 0.8604 GB, free: 8.73 MB / 0.6856 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000219/0.7434, allocations: 91.98 kB / 0.8605 GB, free: 8.641 MB / 0.6856 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002297/0.7457, allocations: 1.013 MB / 0.8615 GB, free: 7.598 MB / 0.6856 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.732e-05/0.7457, allocations: 0 / 0.8615 GB, free: 7.598 MB / 0.6856 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 9.519e-05/0.7458, allocations: 36 kB / 0.8615 GB, free: 7.562 MB / 0.6856 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.436e-05/0.7458, allocations: 24 kB / 0.8615 GB, free: 7.539 MB / 0.6856 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00196/0.7478, allocations: 0.7924 MB / 0.8623 GB, free: 6.75 MB / 0.6856 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.006305/0.7541, allocations: 4.12 MB / 0.8663 GB, free: 2.281 MB / 0.6856 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002056/0.7562, allocations: 0.8159 MB / 0.8671 GB, free: 1.461 MB / 0.6856 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0008466/0.757, allocations: 410 kB / 0.8675 GB, free: 1.062 MB / 0.6856 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.3229/1.08, allocations: 1.295 MB / 0.8688 GB, free: 236.1 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.876e-05/1.08, allocations: 38.92 kB / 0.8688 GB, free: 236 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=80): time 3.236e-06/1.08, allocations: 0.5625 kB / 0.8688 GB, free: 236 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=80): time 0.005823/1.086, allocations: 1.938 MB / 0.8707 GB, free: 235.5 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.355e-05/1.086, allocations: 60.25 kB / 0.8708 GB, free: 235.5 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001188/1.087, allocations: 0.7985 MB / 0.8716 GB, free: 235.1 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 7.002e-05/1.087, allocations: 44.69 kB / 0.8716 GB, free: 235.1 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003646/1.087, allocations: 0.5176 MB / 0.8721 GB, free: 234.8 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002232/1.088, allocations: 288.3 kB / 0.8724 GB, free: 234.6 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002862/1.088, allocations: 154.5 kB / 0.8725 GB, free: 234.5 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 8.21e-05/1.088, allocations: 334.3 kB / 0.8728 GB, free: 234.2 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0007025/1.089, allocations: 317.1 kB / 0.8731 GB, free: 234.1 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0007629/1.089, allocations: 397.2 kB / 0.8735 GB, free: 233.9 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001329/1.091, allocations: 0.6903 MB / 0.8742 GB, free: 233.7 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.749e-06/1.091, allocations: 3.484 kB / 0.8742 GB, free: 233.7 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=93) (initialization): time 0.003063/1.094, allocations: 1.237 MB / 0.8754 GB, free: 233.2 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 2.313e-05/1.094, allocations: 8.969 kB / 0.8754 GB, free: 233.2 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.388e-06/1.094, allocations: 6.469 kB / 0.8754 GB, free: 233.2 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000857/1.095, allocations: 246 kB / 0.8757 GB, free: 233.2 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001017/1.096, allocations: 201 kB / 0.8758 GB, free: 233.1 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005808/1.102, allocations: 5.233 MB / 0.881 GB, free: 228.6 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001062/1.103, allocations: 91.78 kB / 0.881 GB, free: 228.5 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001565/1.103, allocations: 50.12 kB / 0.8811 GB, free: 228.5 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 5
 * 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 (82):
 * Single equations (assignments): 79
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * 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,1,88.9%), (1,4,100.0%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.000541/1.103, allocations: 237.5 kB / 0.8813 GB, free: 228.3 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002712/1.104, allocations: 113.1 kB / 0.8814 GB, free: 228.3 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.004801/1.108, allocations: 1.814 MB / 0.8832 GB, free: 227.7 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.323e-05/1.108, allocations: 9.406 kB / 0.8832 GB, free: 227.7 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.116e-05/1.108, allocations: 2.188 kB / 0.8832 GB, free: 227.7 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.888e-05/1.108, allocations: 10.94 kB / 0.8832 GB, free: 227.7 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.006/1.114, allocations: 2.479 MB / 0.8856 GB, free: 226.6 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.703e-06/1.114, allocations: 0 / 0.8856 GB, free: 226.6 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001046/1.116, allocations: 198 kB / 0.8858 GB, free: 226.6 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0008305/1.116, allocations: 263.1 kB / 0.8861 GB, free: 226.5 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.474e-05/1.116, allocations: 32.02 kB / 0.8861 GB, free: 226.5 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.005415/1.122, allocations: 5.233 MB / 0.8912 GB, free: 221.9 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.49e-06/1.122, allocations: 2.219 kB / 0.8912 GB, free: 221.9 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004305/1.126, allocations: 1.718 MB / 0.8929 GB, free: 221.2 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004937/1.127, allocations: 262.7 kB / 0.8932 GB, free: 221 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002142/1.127, allocations: 21.78 kB / 0.8932 GB, free: 221 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006648/1.128, allocations: 44.03 kB / 0.8932 GB, free: 221 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000258/1.128, allocations: 74.2 kB / 0.8933 GB, free: 221 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001237/1.128, allocations: 47.23 kB / 0.8933 GB, free: 221 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.001074/1.129, allocations: 0.5626 MB / 0.8939 GB, free: 220.7 MB / 0.6856 GB
Notification: Performance of sort global known variables: time 1.1e-07/1.129, allocations: 0 / 0.8939 GB, free: 220.7 MB / 0.6856 GB
Notification: Performance of remove unused functions: time 0.002344/1.131, allocations: 480.2 kB / 0.8943 GB, free: 220.6 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 6 (counterFlowNTU.h_out_A,counterFlowNTU.h_out_B,mCV.inlet.m_flow,mCV.dp_int,mCV1.inlet.m_flow,mCV1.dp_int)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (76):
 * Single equations (assignments): 73
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * 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%), (1,4,100.0%), (3,1,88.9%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001545/1.133, allocations: 0.5876 MB / 0.8949 GB, free: 220.4 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.003352/1.136, allocations: 1.2 MB / 0.8961 GB, free: 219.9 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 7.354e-06/1.136, allocations: 1.188 kB / 0.8961 GB, free: 219.9 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.001702/1.138, allocations: 0.8792 MB / 0.8969 GB, free: 219.4 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001378/1.139, allocations: 266.3 kB / 0.8972 GB, free: 219.3 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004591/1.144, allocations: 2.37 MB / 0.8995 GB, free: 217.9 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007331/1.145, allocations: 1.075 MB / 0.9006 GB, free: 217 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.001501/1.146, allocations: 0.6361 MB / 0.9012 GB, free: 216.7 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0005064/1.147, allocations: 152 kB / 0.9013 GB, free: 216.7 MB / 0.6856 GB
Notification: Performance of SimCode: time 1.222e-06/1.147, allocations: 0 / 0.9013 GB, free: 216.7 MB / 0.6856 GB
Notification: Performance of Templates: time 0.03723/1.184, allocations: 20.1 MB / 0.921 GB, free: 203.3 MB / 0.6856 GB
"
[Timeout remaining time 659]
make -j1 -f ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.makefile [Timeout 660]
(rm -f ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.pipe ; mkfifo ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.pipe ; head -c 1048576 < ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.pipe >> ../files/ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.sim & ./ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU  -abortSlowSimulation -alarm=1200  -emit_protected -lv LOG_STATS > ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.pipe 2>&1) [Timeout 1200]
diffSimulationResults("ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU_res.mat","/mnt/ReferenceFiles/ThermofluidStream-main-regression/ReferenceData/ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU_ref.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ThermofluidStream_dev_ThermofluidStream.HeatExchangers.Tests.CounterFlowNTU.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 660]
Reference file matches
[Calling sys.exit(0), Time elapsed: 7.234678154811263]