Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.conf.json 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.001571/0.001571, allocations: 100.3 kB / 19.7 MB, free: 2.012 MB / 14.72 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.001806/0.001806, allocations: 219.5 kB / 22.99 MB, free: 4.914 MB / 14.72 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.445/1.445, allocations: 230.6 MB / 256.8 MB, free: 7.789 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/package.mo): time 3.212/3.212, allocations: 0.5107 GB / 0.8166 GB, free: 17.8 MB / 0.6231 GB " [Timeout remaining time 176] Using package Buildings with version 13.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings master/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) Running command: translateModel(Buildings.Fluid.Movers.Validation.Pump_y_stratos,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pump1.m_flow|pump2.m_flow|pump3.m_flow|pump4.m_flow|pump5.m_flow|forcedPump1.dp|forcedPump2.dp|forcedPump3.dp|forcedPump4.dp|forcedPump5.dp|pump1.P|pump2.P|pump3.P|pump4.P|pump5.P",fileNamePrefix="Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos") translateModel(Buildings.Fluid.Movers.Validation.Pump_y_stratos,tolerance=1e-08,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|pump1.m_flow|pump2.m_flow|pump3.m_flow|pump4.m_flow|pump5.m_flow|forcedPump1.dp|forcedPump2.dp|forcedPump3.dp|forcedPump4.dp|forcedPump5.dp|pump1.P|pump2.P|pump3.P|pump4.P|pump5.P",fileNamePrefix="Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos") [Timeout 300] "Notification: Buildings 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: Performance of FrontEnd - loaded program: time 0.002347/0.002347, allocations: 63.31 kB / 1.187 GB, free: 13.46 MB / 0.9044 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3833/0.3857, allocations: 181.9 MB / 1.365 GB, free: 13.56 MB / 1.061 GB Notification: Performance of NFInst.instantiate(Buildings.Fluid.Movers.Validation.Pump_y_stratos): time 0.2247/0.6104, allocations: 193.7 MB / 1.554 GB, free: 11.19 MB / 1.248 GB Notification: Performance of NFInst.instExpressions: time 1.004/1.614, allocations: 65.71 MB / 1.618 GB, free: 2.891 MB / 1.295 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.01689/1.631, allocations: 494 kB / 1.618 GB, free: 2.891 MB / 1.295 GB Notification: Performance of NFTyping.typeComponents: time 0.01706/1.648, allocations: 5.442 MB / 1.624 GB, free: 2.875 MB / 1.295 GB Notification: Performance of NFTyping.typeBindings: time 0.03577/1.684, allocations: 11.99 MB / 1.635 GB, free: 2.566 MB / 1.295 GB Notification: Performance of NFTyping.typeClassSections: time 0.02315/1.707, allocations: 9.17 MB / 1.644 GB, free: 2.395 MB / 1.295 GB Notification: Performance of NFFlatten.flatten: time 0.07098/1.778, allocations: 50.37 MB / 1.694 GB, free: 1.902 MB / 1.295 GB Notification: Performance of NFFlatten.resolveConnections: time 0.01608/1.794, allocations: 6.846 MB / 1.7 GB, free: 1.387 MB / 1.295 GB Notification: Performance of NFEvalConstants.evaluate: time 0.0291/1.823, allocations: 12.1 MB / 1.712 GB, free: 0.543 MB / 1.295 GB Notification: Performance of NFSimplifyModel.simplify: time 0.01685/1.84, allocations: 7.249 MB / 1.719 GB, free: 14.4 MB / 1.311 GB Notification: Performance of NFPackage.collectConstants: time 0.004458/1.845, allocations: 0.885 MB / 1.72 GB, free: 14.4 MB / 1.311 GB Notification: Performance of NFFlatten.collectFunctions: time 0.01346/1.858, allocations: 3.292 MB / 1.723 GB, free: 13.88 MB / 1.311 GB Notification: Performance of NFScalarize.scalarize: time 0.008213/1.866, allocations: 5.152 MB / 1.728 GB, free: 11.92 MB / 1.311 GB Notification: Performance of NFVerifyModel.verify: time 0.01596/1.882, allocations: 5.829 MB / 1.734 GB, free: 9.82 MB / 1.311 GB Notification: Performance of NFConvertDAE.convert: time 0.03824/1.92, allocations: 20.81 MB / 1.754 GB, free: 13.82 MB / 1.326 GB Notification: Performance of FrontEnd - DAE generated: time 6.332e-06/1.92, allocations: 0 / 1.754 GB, free: 13.82 MB / 1.326 GB Notification: Performance of FrontEnd: time 2.124e-06/1.92, allocations: 0 / 1.754 GB, free: 13.82 MB / 1.326 GB Notification: Performance of Transformations before backend: time 0.0007837/1.921, allocations: 0 / 1.754 GB, free: 13.82 MB / 1.326 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 1224 * Number of variables: 1224 Notification: Performance of Generate backend data structure: time 0.04445/1.966, allocations: 15.91 MB / 1.77 GB, free: 15.81 MB / 1.342 GB Notification: Performance of prepare preOptimizeDAE: time 4.325e-05/1.966, allocations: 8.031 kB / 1.77 GB, free: 15.8 MB / 1.342 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02814/1.994, allocations: 4.825 MB / 1.775 GB, free: 10.96 MB / 1.342 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.04797/2.042, allocations: 20.6 MB / 1.795 GB, free: 6.711 MB / 1.357 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006407/2.043, allocations: 423.5 kB / 1.795 GB, free: 6.297 MB / 1.357 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.002456/2.045, allocations: 0.5374 MB / 1.796 GB, free: 5.762 MB / 1.357 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.02313/2.068, allocations: 8.081 MB / 1.804 GB, free: 13.36 MB / 1.373 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0001109/2.068, allocations: 10.09 kB / 1.804 GB, free: 13.36 MB / 1.373 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000952/2.069, allocations: 207.8 kB / 1.804 GB, free: 13.16 MB / 1.373 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001101/2.069, allocations: 116 kB / 1.804 GB, free: 13.05 MB / 1.373 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02012/2.089, allocations: 6.136 MB / 1.81 GB, free: 6.949 MB / 1.373 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.06771/2.157, allocations: 26.85 MB / 1.836 GB, free: 12.07 MB / 1.404 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01617/2.173, allocations: 4.738 MB / 1.841 GB, free: 7.352 MB / 1.404 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.007446/2.181, allocations: 2.352 MB / 1.843 GB, free: 5.004 MB / 1.404 GB Notification: Performance of preOpt evalFunc (simulation): time 0.02248/2.203, allocations: 7.199 MB / 1.85 GB, free: 13.86 MB / 1.42 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.755e-05/2.203, allocations: 62.31 kB / 1.85 GB, free: 13.79 MB / 1.42 GB Notification: Performance of pre-optimization done (n=237): time 5.06e-06/2.203, allocations: 0 / 1.85 GB, free: 13.79 MB / 1.42 GB Notification: Performance of matching and sorting (n=237): time 0.02681/2.23, allocations: 7.978 MB / 1.858 GB, free: 5.801 MB / 1.42 GB Notification: Performance of inlineWhenForInitialization (initialization): time 8.47e-05/2.23, allocations: 81.39 kB / 1.858 GB, free: 5.707 MB / 1.42 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.03598/2.266, allocations: 15.96 MB / 1.874 GB, free: 5.816 MB / 1.436 GB Notification: Performance of collectPreVariables (initialization): time 0.001155/2.267, allocations: 104.4 kB / 1.874 GB, free: 5.711 MB / 1.436 GB Notification: Performance of collectInitialEqns (initialization): time 0.008826/2.276, allocations: 6.203 MB / 1.88 GB, free: 15.55 MB / 1.451 GB Notification: Performance of collectInitialBindings (initialization): time 0.001889/2.278, allocations: 0.9163 MB / 1.881 GB, free: 14.65 MB / 1.451 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.004384/2.282, allocations: 2.141 MB / 1.883 GB, free: 12.5 MB / 1.451 GB Notification: Performance of setup shared object (initialization): time 0.000144/2.283, allocations: 304.9 kB / 1.883 GB, free: 12.21 MB / 1.451 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.01294/2.295, allocations: 6.092 MB / 1.889 GB, free: 6.102 MB / 1.451 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.01699/2.312, allocations: 9.254 MB / 1.898 GB, free: 11.75 MB / 1.467 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.02608/2.339, allocations: 13.53 MB / 1.911 GB, free: 13.61 MB / 1.482 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.245e-05/2.339, allocations: 16 kB / 1.911 GB, free: 13.59 MB / 1.482 GB Notification: Performance of matching and sorting (n=1247) (initialization): time 0.03498/2.374, allocations: 16.01 MB / 1.927 GB, free: 13.99 MB / 1.498 GB Notification: Performance of prepare postOptimizeDAE: time 6.851e-05/2.374, allocations: 28 kB / 1.927 GB, free: 13.96 MB / 1.498 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001001/2.374, allocations: 64 kB / 1.927 GB, free: 13.9 MB / 1.498 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0001266/2.374, allocations: 96 kB / 1.927 GB, free: 13.8 MB / 1.498 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008267/2.382, allocations: 1.878 MB / 1.929 GB, free: 12.03 MB / 1.498 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001559/2.382, allocations: 139.4 kB / 1.929 GB, free: 11.92 MB / 1.498 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01115/2.393, allocations: 1.192 MB / 1.93 GB, free: 10.72 MB / 1.498 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001499/2.395, allocations: 459.8 kB / 1.931 GB, free: 10.27 MB / 1.498 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.01815/2.413, allocations: 8.433 MB / 1.939 GB, free: 1.828 MB / 1.498 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.01518/2.428, allocations: 8.668 MB / 1.947 GB, free: 8.074 MB / 1.514 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02265/2.451, allocations: 12.36 MB / 1.959 GB, free: 11.07 MB / 1.529 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 6.166e-05/2.451, allocations: 20 kB / 1.959 GB, free: 11.05 MB / 1.529 GB Notification: Performance of matching and sorting (n=1247) (initialization_lambda0): time 0.03333/2.484, allocations: 14.8 MB / 1.974 GB, free: 12.25 MB / 1.545 GB Notification: Performance of prepare postOptimizeDAE: time 6.331e-05/2.484, allocations: 35.94 kB / 1.974 GB, free: 12.21 MB / 1.545 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000108/2.484, allocations: 64.16 kB / 1.974 GB, free: 12.15 MB / 1.545 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0001231/2.485, allocations: 103.9 kB / 1.974 GB, free: 12.05 MB / 1.545 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.008102/2.493, allocations: 1.81 MB / 1.976 GB, free: 10.24 MB / 1.545 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0001783/2.493, allocations: 139.6 kB / 1.976 GB, free: 10.1 MB / 1.545 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01141/2.504, allocations: 0.9955 MB / 1.977 GB, free: 9.102 MB / 1.545 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001643/2.506, allocations: 419.5 kB / 1.977 GB, free: 8.695 MB / 1.545 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 281 * 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 (872): * Single equations (assignments): 852 * Array equations: 10 * Algorithm blocks: 0 * Record equations: 10 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of prepare postOptimizeDAE: time 0.001663/2.508, allocations: 0.5825 MB / 1.978 GB, free: 8.105 MB / 1.545 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003542/2.511, allocations: 1.28 MB / 1.979 GB, free: 6.816 MB / 1.545 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02088/2.532, allocations: 7.033 MB / 1.986 GB, free: 15.74 MB / 1.561 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.645e-05/2.532, allocations: 35.98 kB / 1.986 GB, free: 15.7 MB / 1.561 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.189e-05/2.532, allocations: 4 kB / 1.986 GB, free: 15.7 MB / 1.561 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.797e-05/2.532, allocations: 11.95 kB / 1.986 GB, free: 15.69 MB / 1.561 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04001/2.572, allocations: 13.05 MB / 1.999 GB, free: 2.621 MB / 1.561 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.476e-05/2.572, allocations: 0 / 1.999 GB, free: 2.621 MB / 1.561 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005036/2.577, allocations: 0.8778 MB / 2 GB, free: 1.742 MB / 1.561 GB Notification: Performance of postOpt tearingSystem (simulation): time 3.015e-05/2.577, allocations: 12 kB / 2 GB, free: 1.73 MB / 1.561 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004769/2.578, allocations: 111.8 kB / 2 GB, free: 1.621 MB / 1.561 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.681e-05/2.578, allocations: 16 kB / 2 GB, free: 1.605 MB / 1.561 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.424e-06/2.578, allocations: 0 / 2 GB, free: 1.605 MB / 1.561 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02735/2.605, allocations: 9.673 MB / 2.009 GB, free: 7.832 MB / 1.576 GB Notification: Performance of postOpt removeConstants (simulation): time 0.007669/2.613, allocations: 2.039 MB / 2.011 GB, free: 5.785 MB / 1.576 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.003626/2.616, allocations: 51.94 kB / 2.011 GB, free: 5.734 MB / 1.576 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.008567/2.625, allocations: 479.5 kB / 2.012 GB, free: 5.266 MB / 1.576 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004824/2.625, allocations: 189.9 kB / 2.012 GB, free: 5.078 MB / 1.576 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005578/2.626, allocations: 167.8 kB / 2.012 GB, free: 4.914 MB / 1.576 GB Notification: Performance of sorting global known variables: time 0.02432/2.65, allocations: 10.74 MB / 2.022 GB, free: 10.24 MB / 1.592 GB Notification: Performance of sort global known variables: time 2.1e-07/2.65, allocations: 0 / 2.022 GB, free: 10.24 MB / 1.592 GB Notification: Performance of remove unused functions: time 0.02002/2.67, allocations: 5.24 MB / 2.028 GB, free: 4.992 MB / 1.592 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 5 * Number of states: 10 (pump1.motSpe.y,pump2.motSpe.y,pump3.motSpe.y,pump4.motSpe.y,pump5.motSpe.y,forcedPump1.motSpe.y,forcedPump2.motSpe.y,forcedPump3.motSpe.y,forcedPump4.motSpe.y,forcedPump5.motSpe.y) * 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 (294): * Single equations (assignments): 272 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 22 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 0 * Torn equation systems: 0 * Mixed (continuous/discrete) equation systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.003215/2.674, allocations: 1.178 MB / 2.029 GB, free: 3.875 MB / 1.592 GB Notification: Performance of simCode: created initialization part: time 0.03645/2.71, allocations: 15.94 MB / 2.044 GB, free: 3.938 MB / 1.607 GB Notification: Performance of simCode: created event and clocks part: time 1.114e-05/2.71, allocations: 8.938 kB / 2.044 GB, free: 3.93 MB / 1.607 GB Notification: Performance of simCode: created simulation system equations: time 0.008113/2.718, allocations: 2.597 MB / 2.047 GB, free: 1.328 MB / 1.607 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.03257/2.751, allocations: 6.747 MB / 2.053 GB, free: 10.8 MB / 1.623 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.06041/2.811, allocations: 25.63 MB / 2.078 GB, free: 1.082 MB / 1.639 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01152/2.823, allocations: 6.175 MB / 2.085 GB, free: 10.87 MB / 1.654 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004731/2.827, allocations: 1.069 MB / 2.086 GB, free: 9.805 MB / 1.654 GB Notification: Performance of SimCode: time 1.693e-06/2.827, allocations: 0 / 2.086 GB, free: 9.805 MB / 1.654 GB Notification: Performance of Templates: time 1.68/4.508, allocations: 229.1 MB / 2.309 GB, free: 0.7294 GB / 1.858 GB " [Timeout remaining time 295] make -j1 -f Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.makefile [Timeout 300] (rm -f Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.pipe ; mkfifo Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.pipe ; head -c 1048576 < Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.pipe >> ../files/Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.sim & ./Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos -abortSlowSimulation -alarm=800 -emit_protected -lv LOG_STATS > Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.pipe 2>&1) [Timeout 800] diffSimulationResults("Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos_res.mat","/mnt/ReferenceFiles/Buildings/csv/master/Buildings_Fluid_Movers_Validation_Pump_y_stratos.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_latest_Buildings.Fluid.Movers.Validation.Pump_y_stratos.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300] "" [Timeout remaining time 300] Reference file matches [Calling sys.exit(0), Time elapsed: 68.68408344592899]