Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.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.001833/0.001833, allocations: 110.4 kB / 18.68 MB, free: 3.316 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.002174/0.002174, allocations: 200.4 kB / 21.94 MB, free: 76 kB / 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.519/1.519, allocations: 225.6 MB / 250.6 MB, free: 13.39 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.2.0-main/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.2.0-main/package.mo): time 0.8212/0.8212, allocations: 94.59 MB / 400.4 MB, free: 15.8 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package ThermofluidStream with version 1.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ThermofluidStream 1.2.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.Boundaries.Tests.Volumes,tolerance=1e-06,outputFormat="mat",numberOfIntervals=1000,variableFilter="Time|flexVolume1.M|flexVolume1.U_med|flexVolume1.m_flow_in|heatportVolume.M|heatportVolume.U_med|heatportVolume1.M|heatportVolume1.U_med|heatportVolume1.m_flow_in|heatportVolume2.M|heatportVolume2.U_med|heatportVolume2.m_flow_out|heatportVolume3.M|heatportVolume3.U_med|sink.inlet.m_flow|sink1.inlet.m_flow|sink5.inlet.m_flow|source.outlet.m_flow|source1.outlet.m_flow|source7.outlet.m_flow|volume.M|volume.U_med|volume1.M|volume1.U_med|volumeMix.M|volumeMix.MXi.1.|volumeMix.MXi.2.|volumeMix.U_med|volumeMix.inlet.1..m_flow|volumeMix.inlet.2..m_flow|volumeMix.outlet.m_flow|volumeMix1.M|volumeMix1.MXi.1.|volumeMix1.MXi.2.|volumeMix1.U_med|volumeMix1.inlet.1..m_flow|volumeMix1.inlet.2..m_flow|volumeMix1.outlet.m_flow|volumeMix2.M|volumeMix2.MXi.1.|volumeMix2.MXi.2.|volumeMix2.U_med|volumeMix3.M|volumeMix3.MXi.1.|volumeMix3.MXi.2.|volumeMix3.U_med|volumeMix3.inlet.1..m_flow|volumeMix3.inlet.2..m_flow|volumeMix3.outlet.m_flow",fileNamePrefix="ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes")
translateModel(ThermofluidStream.Boundaries.Tests.Volumes,tolerance=1e-06,outputFormat="mat",numberOfIntervals=1000,variableFilter="Time|flexVolume1.M|flexVolume1.U_med|flexVolume1.m_flow_in|heatportVolume.M|heatportVolume.U_med|heatportVolume1.M|heatportVolume1.U_med|heatportVolume1.m_flow_in|heatportVolume2.M|heatportVolume2.U_med|heatportVolume2.m_flow_out|heatportVolume3.M|heatportVolume3.U_med|sink.inlet.m_flow|sink1.inlet.m_flow|sink5.inlet.m_flow|source.outlet.m_flow|source1.outlet.m_flow|source7.outlet.m_flow|volume.M|volume.U_med|volume1.M|volume1.U_med|volumeMix.M|volumeMix.MXi.1.|volumeMix.MXi.2.|volumeMix.U_med|volumeMix.inlet.1..m_flow|volumeMix.inlet.2..m_flow|volumeMix.outlet.m_flow|volumeMix1.M|volumeMix1.MXi.1.|volumeMix1.MXi.2.|volumeMix1.U_med|volumeMix1.inlet.1..m_flow|volumeMix1.inlet.2..m_flow|volumeMix1.outlet.m_flow|volumeMix2.M|volumeMix2.MXi.1.|volumeMix2.MXi.2.|volumeMix2.U_med|volumeMix3.M|volumeMix3.MXi.1.|volumeMix3.MXi.2.|volumeMix3.U_med|volumeMix3.inlet.1..m_flow|volumeMix3.inlet.2..m_flow|volumeMix3.outlet.m_flow",fileNamePrefix="ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002435/0.002435, allocations: 87.3 kB / 0.5464 GB, free: 42.8 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1493/0.1517, allocations: 71.84 MB / 0.6166 GB, free: 8.582 MB / 462.1 MB
Notification: Performance of NFInst.instantiate(ThermofluidStream.Boundaries.Tests.Volumes): time 0.7635/0.9152, allocations: 346.7 MB / 0.9551 GB, free: 2.125 MB / 0.6856 GB
Notification: Performance of NFInst.instExpressions: time 0.05559/0.9708, allocations: 40.07 MB / 0.9943 GB, free: 9.945 MB / 0.7325 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005488/0.9763, allocations: 178.8 kB / 0.9944 GB, free: 9.77 MB / 0.7325 GB
Notification: Performance of NFTyping.typeComponents: time 0.009212/0.9855, allocations: 3.146 MB / 0.9975 GB, free: 6.605 MB / 0.7325 GB
Notification: Performance of NFTyping.typeBindings: time 0.0259/1.011, allocations: 8.886 MB / 1.006 GB, free: 13.68 MB / 0.7481 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01822/1.03, allocations: 6.465 MB / 1.013 GB, free: 7.227 MB / 0.7481 GB
Notification: Performance of NFFlatten.flatten: time 0.01528/1.045, allocations: 9.211 MB / 1.022 GB, free: 13.98 MB / 0.7637 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002049/1.047, allocations: 0.6907 MB / 1.022 GB, free: 13.23 MB / 0.7637 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01479/1.062, allocations: 6.575 MB / 1.029 GB, free: 6.633 MB / 0.7637 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.007263/1.069, allocations: 3.883 MB / 1.032 GB, free: 2.734 MB / 0.7637 GB
Notification: Performance of NFPackage.collectConstants: time 0.001982/1.071, allocations: 436 kB / 1.033 GB, free: 2.309 MB / 0.7637 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.02481/1.096, allocations: 11.57 MB / 1.044 GB, free: 6.711 MB / 0.7794 GB
Notification: Performance of NFScalarize.scalarize: time 0.003072/1.099, allocations: 1.576 MB / 1.046 GB, free: 5.129 MB / 0.7794 GB
Notification: Performance of NFVerifyModel.verify: time 0.005951/1.105, allocations: 2.876 MB / 1.048 GB, free: 2.238 MB / 0.7794 GB
Notification: Performance of NFConvertDAE.convert: time 0.4014/1.506, allocations: 10.84 MB / 1.059 GB, free: 21.86 MB / 0.7794 GB
Notification: Performance of FrontEnd - DAE generated: time 5.401e-06/1.506, allocations: 0 / 1.059 GB, free: 21.86 MB / 0.7794 GB
Notification: Performance of FrontEnd: time 1.463e-06/1.506, allocations: 1.188 kB / 1.059 GB, free: 21.86 MB / 0.7794 GB
Notification: Performance of Transformations before backend: time 0.0002219/1.506, allocations: 1.094 kB / 1.059 GB, free: 21.86 MB / 0.7794 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 995
 * Number of variables: 995
Notification: Performance of Generate backend data structure: time 0.01737/1.524, allocations: 6.931 MB / 1.066 GB, free: 18.99 MB / 0.7794 GB
Notification: Performance of prepare preOptimizeDAE: time 3.898e-05/1.524, allocations: 8.594 kB / 1.066 GB, free: 18.99 MB / 0.7794 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003683/1.528, allocations: 0.8658 MB / 1.067 GB, free: 18.66 MB / 0.7794 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006654/1.534, allocations: 3.236 MB / 1.07 GB, free: 17.81 MB / 0.7794 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002167/1.534, allocations: 303.5 kB / 1.07 GB, free: 17.8 MB / 0.7794 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006707/1.535, allocations: 416.8 kB / 1.071 GB, free: 17.78 MB / 0.7794 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.008266/1.543, allocations: 4.963 MB / 1.075 GB, free: 17.44 MB / 0.7794 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.721e-05/1.543, allocations: 7.281 kB / 1.075 GB, free: 17.44 MB / 0.7794 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004135/1.544, allocations: 166.9 kB / 1.076 GB, free: 17.44 MB / 0.7794 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003787/1.544, allocations: 319.6 kB / 1.076 GB, free: 17.44 MB / 0.7794 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.006306/1.551, allocations: 4.275 MB / 1.08 GB, free: 17.42 MB / 0.7794 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.0342/1.585, allocations: 22.2 MB / 1.102 GB, free: 22.03 MB / 0.795 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.005456/1.59, allocations: 3.053 MB / 1.105 GB, free: 21.79 MB / 0.795 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002824/1.593, allocations: 1.817 MB / 1.106 GB, free: 21.72 MB / 0.795 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01676/1.61, allocations: 10.91 MB / 1.117 GB, free: 14.53 MB / 0.795 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.251e-05/1.61, allocations: 102.1 kB / 1.117 GB, free: 14.48 MB / 0.795 GB
Notification: Performance of pre-optimization done (n=390): time 6.533e-06/1.61, allocations: 2.297 kB / 1.117 GB, free: 14.48 MB / 0.795 GB
Notification: Performance of matching and sorting (n=390): time 0.01662/1.626, allocations: 8.718 MB / 1.126 GB, free: 11.11 MB / 0.795 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001662/1.627, allocations: 277.6 kB / 1.126 GB, free: 10.83 MB / 0.795 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004938/1.632, allocations: 3.432 MB / 1.129 GB, free: 10.5 MB / 0.795 GB
Notification: Performance of collectPreVariables (initialization): time 0.0007853/1.632, allocations: 104 kB / 1.129 GB, free: 10.47 MB / 0.795 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001338/1.634, allocations: 2.045 MB / 1.131 GB, free: 9.879 MB / 0.795 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001373/1.635, allocations: 1.422 MB / 1.133 GB, free: 8.941 MB / 0.795 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00119/1.636, allocations: 0.7373 MB / 1.134 GB, free: 8.898 MB / 0.795 GB
Notification: Performance of setup shared object (initialization): time 0.0001487/1.636, allocations: 301.1 kB / 1.134 GB, free: 8.602 MB / 0.795 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002407/1.639, allocations: 1.535 MB / 1.135 GB, free: 8.586 MB / 0.795 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002915/1.642, allocations: 2.189 MB / 1.137 GB, free: 7.945 MB / 0.795 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005717/1.647, allocations: 3.907 MB / 1.141 GB, free: 7.293 MB / 0.795 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.098e-05/1.647, allocations: 7.062 kB / 1.141 GB, free: 7.293 MB / 0.795 GB
Notification: Performance of matching and sorting (n=520) (initialization): time 0.01054/1.658, allocations: 5.139 MB / 1.146 GB, free: 6.754 MB / 0.795 GB
Notification: Performance of prepare postOptimizeDAE: time 4.744e-05/1.658, allocations: 12.59 kB / 1.146 GB, free: 6.754 MB / 0.795 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.562e-05/1.658, allocations: 16.19 kB / 1.146 GB, free: 6.754 MB / 0.795 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001986/1.66, allocations: 0.5886 MB / 1.147 GB, free: 6.598 MB / 0.795 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003852/1.664, allocations: 0.8321 MB / 1.148 GB, free: 6.598 MB / 0.795 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02144/1.685, allocations: 26.23 MB / 1.173 GB, free: 14.25 MB / 0.8262 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00549/1.691, allocations: 410.5 kB / 1.174 GB, free: 14.02 MB / 0.8262 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001282/1.692, allocations: 233.9 kB / 1.174 GB, free: 14.02 MB / 0.8262 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 72
 * 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 (478):
 * Single equations (assignments): 451
 * Array equations: 9
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 18
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 18 systems
   {(1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,1,100.0%), (1,1,100.0%), (1,1,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.002833/1.695, allocations: 0.7847 MB / 1.175 GB, free: 13.89 MB / 0.8262 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002076/1.697, allocations: 0.4925 MB / 1.175 GB, free: 13.63 MB / 0.8262 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01064/1.708, allocations: 5.975 MB / 1.181 GB, free: 12.91 MB / 0.8262 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.87e-05/1.708, allocations: 19.36 kB / 1.181 GB, free: 12.91 MB / 0.8262 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.068e-05/1.708, allocations: 3.344 kB / 1.181 GB, free: 12.91 MB / 0.8262 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002023/1.71, allocations: 0.5779 MB / 1.182 GB, free: 12.5 MB / 0.8262 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008284/1.711, allocations: 90.02 kB / 1.182 GB, free: 12.49 MB / 0.8262 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002767/1.713, allocations: 162.1 kB / 1.182 GB, free: 12.48 MB / 0.8262 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004744/1.714, allocations: 290.5 kB / 1.182 GB, free: 12.48 MB / 0.8262 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01317/1.727, allocations: 5.65 MB / 1.188 GB, free: 11.41 MB / 0.8262 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01182/1.739, allocations: 6.734 MB / 1.194 GB, free: 10.21 MB / 0.8262 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.00299/1.742, allocations: 1.58 MB / 1.196 GB, free: 9.531 MB / 0.8262 GB
Notification: Performance of sorting global known variables: time 0.004323/1.746, allocations: 2.361 MB / 1.198 GB, free: 8.223 MB / 0.8262 GB
Notification: Performance of Backend: time 5.21e-07/1.746, allocations: 0 / 1.198 GB, free: 8.223 MB / 0.8262 GB
Notification: Performance of simCode: created initialization part: time 0.02258/1.769, allocations: 7.397 MB / 1.205 GB, free: 2.523 MB / 0.8262 GB
Notification: Performance of SimCode: time 0.02325/1.792, allocations: 11.76 MB / 1.217 GB, free: 8.16 MB / 0.8419 GB
Notification: Performance of Templates: time 0.2049/1.997, allocations: 116.7 MB / 1.331 GB, free: 5.098 MB / 0.9512 GB
"
[Timeout remaining time 658]
make -j1 -f ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.makefile [Timeout 660]
(rm -f ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.pipe ; mkfifo ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.pipe ; head -c 1048576 < ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.pipe >> ../files/ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.sim & ./ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes  -abortSlowSimulation -alarm=1200  -emit_protected -lv LOG_STATS > ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.pipe 2>&1) [Timeout 1200]
diffSimulationResults("ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes_res.mat","/mnt/ReferenceFiles/ThermofluidStream-main-regression/ReferenceData/ThermofluidStream.Boundaries.Tests.Volumes_ref.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ThermofluidStream_dev_ThermofluidStream.Boundaries.Tests.Volumes.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: 12.01806808798574]