Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.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.001009/0.001009, allocations: 87.58 kB / 20.45 MB, free: 4.117 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.00204/0.00204, allocations: 161 kB / 23.82 MB, free: 0.75 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.9277/0.9277, allocations: 177.1 MB / 204.2 MB, free: 5.254 MB / 186.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo): time 1.766/1.766, allocations: 359 MB / 0.6052 GB, free: 11.01 MB / 0.573 GB " [Timeout remaining time 178] Using package Buildings with version 11.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/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.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer,tolerance=1e-07,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|flo.TAirCor|flo.TAirSou|flo.TAirNor|flo.TAirEas|flo.TAirWes|hea.[0-9,]+..conPID.y|flo.sou.heaPorAir.Q_flow|flo.eas.heaPorAir.Q_flow|flo.nor.heaPorAir.Q_flow|flo.wes.heaPorAir.Q_flow|flo.cor.heaPorAir.Q_flow|EHea.y|ECoo.y|coo.[0-9,]+..conPID.y",fileNamePrefix="Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer") translateModel(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer,tolerance=1e-07,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|flo.TAirCor|flo.TAirSou|flo.TAirNor|flo.TAirEas|flo.TAirWes|hea.[0-9,]+..conPID.y|flo.sou.heaPorAir.Q_flow|flo.eas.heaPorAir.Q_flow|flo.nor.heaPorAir.Q_flow|flo.wes.heaPorAir.Q_flow|flo.cor.heaPorAir.Q_flow|EHea.y|ECoo.y|coo.[0-9,]+..conPID.y",fileNamePrefix="Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer") [Timeout 300] "Notification: Performance of FrontEnd - loaded program: time 1.733e-06/1.733e-06, allocations: 0 / 0.7871 GB, free: 12.47 MB / 0.7448 GB Notification: Performance of FrontEnd - Absyn->SCode: time 2.785e-05/2.959e-05, allocations: 2.312 kB / 0.7871 GB, free: 12.47 MB / 0.7448 GB Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer): time 0.1507/0.1507, allocations: 187.2 MB / 0.9699 GB, free: 0.707 MB / 0.9167 GB Notification: Performance of NFInst.instExpressions: time 0.0415/0.1922, allocations: 34.68 MB / 1.004 GB, free: 13.93 MB / 0.9636 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.008022/0.2002, allocations: 258.1 kB / 1.004 GB, free: 13.68 MB / 0.9636 GB Notification: Performance of NFTyping.typeComponents: time 0.006434/0.2066, allocations: 2.072 MB / 1.006 GB, free: 11.59 MB / 0.9636 GB Notification: Performance of NFTyping.typeBindings: time 0.01606/0.2227, allocations: 7.041 MB / 1.013 GB, free: 4.508 MB / 0.9636 GB Notification: Performance of NFTyping.typeClassSections: time 0.01129/0.234, allocations: 6.052 MB / 1.019 GB, free: 14.45 MB / 0.9792 GB Notification: Performance of NFFlatten.flatten: time 0.7475/0.9815, allocations: 33.09 MB / 1.051 GB, free: 58.98 MB / 0.9949 GB Notification: Performance of NFFlatten.resolveConnections: time 0.01979/1.001, allocations: 15.13 MB / 1.066 GB, free: 58.02 MB / 0.9949 GB Notification: Performance of NFEvalConstants.evaluate: time 0.0305/1.032, allocations: 16.98 MB / 1.082 GB, free: 57.95 MB / 0.9949 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0175/1.049, allocations: 10.45 MB / 1.093 GB, free: 57.92 MB / 0.9949 GB Notification: Performance of NFPackage.collectConstants: time 0.01009/1.059, allocations: 2.292 MB / 1.095 GB, free: 57.92 MB / 0.9949 GB Notification: Performance of NFFlatten.collectFunctions: time 0.01387/1.073, allocations: 3.739 MB / 1.099 GB, free: 57.92 MB / 0.9949 GB Notification: Performance of NFScalarize.scalarize: time 0.01243/1.086, allocations: 6.113 MB / 1.105 GB, free: 57.92 MB / 0.9949 GB Notification: Performance of NFVerifyModel.verify: time 0.02036/1.106, allocations: 6.748 MB / 1.111 GB, free: 57.92 MB / 0.9949 GB Notification: Performance of NFConvertDAE.convert: time 0.02807/1.134, allocations: 16.72 MB / 1.127 GB, free: 56.2 MB / 0.9949 GB Notification: Performance of FrontEnd - DAE generated: time 7.293e-06/1.134, allocations: 2.562 kB / 1.127 GB, free: 56.2 MB / 0.9949 GB Notification: Performance of FrontEnd: time 1.693e-06/1.134, allocations: 0.5625 kB / 1.127 GB, free: 56.2 MB / 0.9949 GB Notification: Performance of Transformations before backend: time 0.0009011/1.135, allocations: 0.7188 kB / 1.127 GB, free: 56.2 MB / 0.9949 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 3473 * Number of variables: 3473 Notification: Performance of Generate backend data structure: time 0.04642/1.181, allocations: 35.1 MB / 1.162 GB, free: 40.46 MB / 0.9949 GB Notification: Performance of prepare preOptimizeDAE: time 4.313e-05/1.181, allocations: 9.156 kB / 1.162 GB, free: 40.46 MB / 0.9949 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01767/1.199, allocations: 5.317 MB / 1.167 GB, free: 38.61 MB / 0.9949 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.01758/1.217, allocations: 9.413 MB / 1.176 GB, free: 34.11 MB / 0.9949 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001094/1.218, allocations: 1.37 MB / 1.177 GB, free: 33.27 MB / 0.9949 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.004741/1.223, allocations: 1.435 MB / 1.179 GB, free: 32.82 MB / 0.9949 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.03326/1.256, allocations: 13.32 MB / 1.192 GB, free: 26.11 MB / 0.9949 GB Notification: Performance of preOpt findStateOrder (simulation): time 0.0003035/1.256, allocations: 10.77 kB / 1.192 GB, free: 26.11 MB / 0.9949 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002506/1.259, allocations: 0.5579 MB / 1.192 GB, free: 25.69 MB / 0.9949 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003517/1.259, allocations: 488.6 kB / 1.193 GB, free: 25.66 MB / 0.9949 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01989/1.279, allocations: 6.32 MB / 1.199 GB, free: 24.7 MB / 0.9949 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.07412/1.353, allocations: 38.8 MB / 1.237 GB, free: 292 kB / 0.9949 GB Notification: Performance of preOpt comSubExp (simulation): time 0.01324/1.366, allocations: 7.479 MB / 1.244 GB, free: 9.309 MB / 1.011 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.005361/1.372, allocations: 2.553 MB / 1.247 GB, free: 6.73 MB / 1.011 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0011/1.373, allocations: 380.1 kB / 1.247 GB, free: 6.359 MB / 1.011 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.007403/1.38, allocations: 5.631 MB / 1.253 GB, free: 168 kB / 1.011 GB Notification: Performance of pre-optimization done (n=887): time 1.48e-05/1.38, allocations: 0 / 1.253 GB, free: 168 kB / 1.011 GB Notification: Performance of matching and sorting (n=887): time 0.01823/1.398, allocations: 8.681 MB / 1.261 GB, free: 7.402 MB / 1.026 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0003182/1.399, allocations: 0.8702 MB / 1.262 GB, free: 6.383 MB / 1.026 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008743/1.407, allocations: 5.971 MB / 1.268 GB, free: 452 kB / 1.026 GB Notification: Performance of collectPreVariables (initialization): time 0.002015/1.409, allocations: 306.7 kB / 1.268 GB, free: 140 kB / 1.026 GB Notification: Performance of collectInitialEqns (initialization): time 0.004529/1.414, allocations: 5.848 MB / 1.274 GB, free: 10.28 MB / 1.042 GB Notification: Performance of collectInitialBindings (initialization): time 0.003682/1.418, allocations: 3.081 MB / 1.277 GB, free: 7.242 MB / 1.042 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.003246/1.421, allocations: 2.183 MB / 1.279 GB, free: 5.047 MB / 1.042 GB Notification: Performance of setup shared object (initialization): time 9.52e-05/1.421, allocations: 484.7 kB / 1.279 GB, free: 4.57 MB / 1.042 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.003582/1.425, allocations: 1.829 MB / 1.281 GB, free: 2.727 MB / 1.042 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.006184/1.431, allocations: 6.855 MB / 1.288 GB, free: 10.45 MB / 1.057 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.01011/1.441, allocations: 5.733 MB / 1.293 GB, free: 4.254 MB / 1.057 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.161e-05/1.441, allocations: 11.94 kB / 1.293 GB, free: 4.242 MB / 1.057 GB Notification: Performance of matching and sorting (n=1367) (initialization): time 0.01519/1.456, allocations: 7.759 MB / 1.301 GB, free: 12.54 MB / 1.073 GB Notification: Performance of prepare postOptimizeDAE: time 6.116e-05/1.456, allocations: 45.69 kB / 1.301 GB, free: 12.49 MB / 1.073 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.019e-05/1.456, allocations: 24 kB / 1.301 GB, free: 12.47 MB / 1.073 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.000614/1.457, allocations: 340.9 kB / 1.301 GB, free: 12.11 MB / 1.073 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006267/1.463, allocations: 2.397 MB / 1.304 GB, free: 9.836 MB / 1.073 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004442/1.468, allocations: 10.79 MB / 1.314 GB, free: 14.48 MB / 1.089 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.007478/1.475, allocations: 1.021 MB / 1.315 GB, free: 13.46 MB / 1.089 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001562/1.477, allocations: 439.9 kB / 1.316 GB, free: 13.03 MB / 1.089 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00765/1.484, allocations: 2.092 MB / 1.318 GB, free: 10.93 MB / 1.089 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.00574/1.49, allocations: 6.824 MB / 1.324 GB, free: 2.691 MB / 1.089 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.008755/1.499, allocations: 5.705 MB / 1.33 GB, free: 12.52 MB / 1.104 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 4.29e-05/1.499, allocations: 8 kB / 1.33 GB, free: 12.51 MB / 1.104 GB Notification: Performance of matching and sorting (n=1367) (initialization_lambda0): time 0.01383/1.513, allocations: 7.728 MB / 1.338 GB, free: 4.844 MB / 1.104 GB Notification: Performance of prepare postOptimizeDAE: time 5.356e-05/1.513, allocations: 49.69 kB / 1.338 GB, free: 4.789 MB / 1.104 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.394e-05/1.513, allocations: 24 kB / 1.338 GB, free: 4.766 MB / 1.104 GB Notification: Performance of postOpt tearingSystem (initialization): time 0.0005127/1.513, allocations: 332.8 kB / 1.338 GB, free: 4.41 MB / 1.104 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005448/1.519, allocations: 2.272 MB / 1.34 GB, free: 2.266 MB / 1.104 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003221/1.522, allocations: 7.218 MB / 1.347 GB, free: 10.59 MB / 1.12 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006541/1.528, allocations: 0.946 MB / 1.348 GB, free: 9.648 MB / 1.12 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001093/1.529, allocations: 438.7 kB / 1.349 GB, free: 9.223 MB / 1.12 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 119 * Number of states: 0 () * Number of discrete variables: 298 (flo.att.fmuZon.EGaiRadLast,$PRE.flo.att.fmuZon.EGaiRadLast,flo.att.fmuZon.tLast,$PRE.flo.att.fmuZon.tLast,flo.att.fmuZon.dtLast,$PRE.flo.att.fmuZon.dtLast,flo.sou.fmuZon.EGaiRadLast,$PRE.flo.sou.fmuZon.EGaiRadLast,flo.sou.fmuZon.tLast,$PRE.flo.sou.fmuZon.tLast,flo.sou.fmuZon.dtLast,$PRE.flo.sou.fmuZon.dtLast,flo.eas.fmuZon.EGaiRadLast,$PRE.flo.eas.fmuZon.EGaiRadLast,flo.eas.fmuZon.tLast,$PRE.flo.eas.fmuZon.tLast,flo.eas.fmuZon.dtLast,$PRE.flo.eas.fmuZon.dtLast,flo.nor.fmuZon.EGaiRadLast,$PRE.flo.nor.fmuZon.EGaiRadLast,flo.nor.fmuZon.tLast,$PRE.flo.nor.fmuZon.tLast,flo.nor.fmuZon.dtLast,$PRE.flo.nor.fmuZon.dtLast,flo.wes.fmuZon.EGaiRadLast,$PRE.flo.wes.fmuZon.EGaiRadLast,flo.wes.fmuZon.tLast,$PRE.flo.wes.fmuZon.tLast,flo.wes.fmuZon.dtLast,$PRE.flo.wes.fmuZon.dtLast,flo.cor.fmuZon.EGaiRadLast,$PRE.flo.cor.fmuZon.EGaiRadLast,flo.cor.fmuZon.tLast,$PRE.flo.cor.fmuZon.tLast,flo.cor.fmuZon.dtLast,$PRE.flo.cor.fmuZon.dtLast,flo.building.weaDat.conTim.canRepeatWeatherFile,flo.building.weaDat.conTimMin.canRepeatWeatherFile,flo.building.weaDat.decAng.k,$PRE.flo.building.weaDat.decAng.k,flo.building.weaDat.decAng.tNext,$PRE.flo.building.weaDat.decAng.tNext,flo.building.weaDat.eqnTim.k,$PRE.flo.building.weaDat.eqnTim.k,flo.building.weaDat.eqnTim.tNext,$PRE.flo.building.weaDat.eqnTim.tNext,flo.building.weaDat.conTim.k,$PRE.flo.building.weaDat.conTim.k,flo.building.weaDat.conTim.tNext,$PRE.flo.building.weaDat.conTim.tNext,flo.building.weaDat.conTimMin.k,$PRE.flo.building.weaDat.conTimMin.k,flo.building.weaDat.conTimMin.tNext,$PRE.flo.building.weaDat.conTimMin.tNext,flo.building.weaDat.locTim.k,$PRE.flo.building.weaDat.locTim.k,flo.building.weaDat.locTim.tNext,$PRE.flo.building.weaDat.locTim.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,coo[5].conPID.con1.y,coo[4].conPID.con1.y,coo[3].conPID.con1.y,coo[2].conPID.con1.y,coo[1].conPID.con1.y,hea[5].conPID.con1.y,hea[4].conPID.con1.y,hea[3].conPID.con1.y,hea[2].conPID.con1.y,hea[1].conPID.con1.y,ECoo.local_reset,EHea.local_reset,coo[5].conPID.reverseActing,coo[4].conPID.reverseActing,coo[3].conPID.reverseActing,coo[2].conPID.reverseActing,coo[1].conPID.reverseActing,hea[5].conPID.reverseActing,hea[4].conPID.reverseActing,hea[3].conPID.reverseActing,hea[2].conPID.reverseActing,hea[1].conPID.reverseActing,flo.att.vol.computeCSen,flo.att.fmuZon.nObj,flo.cor.vol.computeCSen,flo.cor.fmuZon.nObj,flo.wes.vol.computeCSen,flo.wes.fmuZon.nObj,flo.nor.vol.computeCSen,flo.nor.fmuZon.nObj,flo.eas.vol.computeCSen,flo.eas.fmuZon.nObj,flo.sou.vol.computeCSen,flo.sou.fmuZon.nObj,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,flo.att.fmuZon.QConLast_flow,$PRE.flo.att.fmuZon.QConLast_flow,flo.att.fmuZon.QGaiRadAve_flow,$PRE.flo.att.fmuZon.QGaiRadAve_flow,flo.att.fmuZon.TRooLast,$PRE.flo.att.fmuZon.TRooLast,flo.att.fmuZon.TAveInlet,$PRE.flo.att.fmuZon.TAveInlet,flo.att.fmuZon.mInlet_flow,$PRE.flo.att.fmuZon.mInlet_flow,flo.att.fmuZon.tNext,$PRE.flo.att.fmuZon.tNext,flo.att.fmuZon.yEP[5],$PRE.flo.att.fmuZon.yEP[5],flo.att.fmuZon.yEP[4],$PRE.flo.att.fmuZon.yEP[4],flo.att.fmuZon.yEP[3],$PRE.flo.att.fmuZon.yEP[3],flo.att.fmuZon.yEP[2],$PRE.flo.att.fmuZon.yEP[2],flo.att.fmuZon.yEP[1],$PRE.flo.att.fmuZon.yEP[1],flo.att.fmuZon.QPeo_flow,$PRE.flo.att.fmuZon.QPeo_flow,flo.att.fmuZon.QLat_flow,$PRE.flo.att.fmuZon.QLat_flow,flo.att.fmuZon.TRad,$PRE.flo.att.fmuZon.TRad,$whenCondition6,flo.cor.fmuZon.QConLast_flow,$PRE.flo.cor.fmuZon.QConLast_flow,flo.cor.fmuZon.QGaiRadAve_flow,$PRE.flo.cor.fmuZon.QGaiRadAve_flow,flo.cor.fmuZon.TRooLast,$PRE.flo.cor.fmuZon.TRooLast,flo.cor.fmuZon.TAveInlet,$PRE.flo.cor.fmuZon.TAveInlet,flo.cor.fmuZon.mInlet_flow,$PRE.flo.cor.fmuZon.mInlet_flow,flo.cor.fmuZon.tNext,$PRE.flo.cor.fmuZon.tNext,flo.cor.fmuZon.yEP[5],$PRE.flo.cor.fmuZon.yEP[5],flo.cor.fmuZon.yEP[4],$PRE.flo.cor.fmuZon.yEP[4],flo.cor.fmuZon.yEP[3],$PRE.flo.cor.fmuZon.yEP[3],flo.cor.fmuZon.yEP[2],$PRE.flo.cor.fmuZon.yEP[2],flo.cor.fmuZon.yEP[1],$PRE.flo.cor.fmuZon.yEP[1],flo.cor.fmuZon.QPeo_flow,$PRE.flo.cor.fmuZon.QPeo_flow,flo.cor.fmuZon.QLat_flow,$PRE.flo.cor.fmuZon.QLat_flow,flo.cor.fmuZon.TRad,$PRE.flo.cor.fmuZon.TRad,flo.wes.fmuZon.QConLast_flow,$PRE.flo.wes.fmuZon.QConLast_flow,flo.wes.fmuZon.QGaiRadAve_flow,$PRE.flo.wes.fmuZon.QGaiRadAve_flow,flo.wes.fmuZon.TRooLast,$PRE.flo.wes.fmuZon.TRooLast,flo.wes.fmuZon.TAveInlet,$PRE.flo.wes.fmuZon.TAveInlet,flo.wes.fmuZon.mInlet_flow,$PRE.flo.wes.fmuZon.mInlet_flow,flo.wes.fmuZon.tNext,$PRE.flo.wes.fmuZon.tNext,flo.wes.fmuZon.yEP[5],$PRE.flo.wes.fmuZon.yEP[5],flo.wes.fmuZon.yEP[4],$PRE.flo.wes.fmuZon.yEP[4],flo.wes.fmuZon.yEP[3],$PRE.flo.wes.fmuZon.yEP[3],flo.wes.fmuZon.yEP[2],$PRE.flo.wes.fmuZon.yEP[2],flo.wes.fmuZon.yEP[1],$PRE.flo.wes.fmuZon.yEP[1],flo.wes.fmuZon.QPeo_flow,$PRE.flo.wes.fmuZon.QPeo_flow,flo.wes.fmuZon.QLat_flow,$PRE.flo.wes.fmuZon.QLat_flow,flo.wes.fmuZon.TRad,$PRE.flo.wes.fmuZon.TRad,flo.nor.fmuZon.QConLast_flow,$PRE.flo.nor.fmuZon.QConLast_flow,flo.nor.fmuZon.QGaiRadAve_flow,$PRE.flo.nor.fmuZon.QGaiRadAve_flow,flo.nor.fmuZon.TRooLast,$PRE.flo.nor.fmuZon.TRooLast,flo.nor.fmuZon.TAveInlet,$PRE.flo.nor.fmuZon.TAveInlet,flo.nor.fmuZon.mInlet_flow,$PRE.flo.nor.fmuZon.mInlet_flow,flo.nor.fmuZon.tNext,$PRE.flo.nor.fmuZon.tNext,flo.nor.fmuZon.yEP[5],$PRE.flo.nor.fmuZon.yEP[5],flo.nor.fmuZon.yEP[4],$PRE.flo.nor.fmuZon.yEP[4],flo.nor.fmuZon.yEP[3],$PRE.flo.nor.fmuZon.yEP[3],flo.nor.fmuZon.yEP[2],$PRE.flo.nor.fmuZon.yEP[2],flo.nor.fmuZon.yEP[1],$PRE.flo.nor.fmuZon.yEP[1],flo.nor.fmuZon.QPeo_flow,$PRE.flo.nor.fmuZon.QPeo_flow,flo.nor.fmuZon.QLat_flow,$PRE.flo.nor.fmuZon.QLat_flow,flo.nor.fmuZon.TRad,$PRE.flo.nor.fmuZon.TRad,flo.eas.fmuZon.QConLast_flow,$PRE.flo.eas.fmuZon.QConLast_flow,flo.eas.fmuZon.QGaiRadAve_flow,$PRE.flo.eas.fmuZon.QGaiRadAve_flow,flo.eas.fmuZon.TRooLast,$PRE.flo.eas.fmuZon.TRooLast,flo.eas.fmuZon.TAveInlet,$PRE.flo.eas.fmuZon.TAveInlet,flo.eas.fmuZon.mInlet_flow,$PRE.flo.eas.fmuZon.mInlet_flow,flo.eas.fmuZon.tNext,$PRE.flo.eas.fmuZon.tNext,flo.eas.fmuZon.yEP[5],$PRE.flo.eas.fmuZon.yEP[5],flo.eas.fmuZon.yEP[4],$PRE.flo.eas.fmuZon.yEP[4],flo.eas.fmuZon.yEP[3],$PRE.flo.eas.fmuZon.yEP[3],flo.eas.fmuZon.yEP[2],$PRE.flo.eas.fmuZon.yEP[2],flo.eas.fmuZon.yEP[1],$PRE.flo.eas.fmuZon.yEP[1],flo.eas.fmuZon.QPeo_flow,$PRE.flo.eas.fmuZon.QPeo_flow,flo.eas.fmuZon.QLat_flow,$PRE.flo.eas.fmuZon.QLat_flow,flo.eas.fmuZon.TRad,$PRE.flo.eas.fmuZon.TRad,flo.sou.fmuZon.QConLast_flow,$PRE.flo.sou.fmuZon.QConLast_flow,flo.sou.fmuZon.QGaiRadAve_flow,$PRE.flo.sou.fmuZon.QGaiRadAve_flow,flo.sou.fmuZon.TRooLast,$PRE.flo.sou.fmuZon.TRooLast,flo.sou.fmuZon.TAveInlet,$PRE.flo.sou.fmuZon.TAveInlet,flo.sou.fmuZon.mInlet_flow,$PRE.flo.sou.fmuZon.mInlet_flow,flo.sou.fmuZon.tNext,$PRE.flo.sou.fmuZon.tNext,flo.sou.fmuZon.yEP[5],$PRE.flo.sou.fmuZon.yEP[5],flo.sou.fmuZon.yEP[4],$PRE.flo.sou.fmuZon.yEP[4],flo.sou.fmuZon.yEP[3],$PRE.flo.sou.fmuZon.yEP[3],flo.sou.fmuZon.yEP[2],$PRE.flo.sou.fmuZon.yEP[2],flo.sou.fmuZon.yEP[1],$PRE.flo.sou.fmuZon.yEP[1],flo.sou.fmuZon.QPeo_flow,$PRE.flo.sou.fmuZon.QPeo_flow,flo.sou.fmuZon.QLat_flow,$PRE.flo.sou.fmuZon.QLat_flow,flo.sou.fmuZon.TRad,$PRE.flo.sou.fmuZon.TRad,weaDat.decAng.k,$PRE.weaDat.decAng.k,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.k,$PRE.weaDat.eqnTim.k,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.k,$PRE.weaDat.conTim.k,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.k,$PRE.weaDat.conTimMin.k,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.k,$PRE.weaDat.locTim.k,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (1310): * Single equations (assignments): 1289 * Array equations: 12 * Algorithm blocks: 0 * Record equations: 0 * When equations: 0 * If-equations: 0 * Equation systems (not torn): 4 * Torn equation systems: 5 * 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): 4 systems {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): 4 systems {(1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%)} * Non-linear torn systems (#iteration vars, #inner vars): 1 system {(1,13)} Notification: Performance of prepare postOptimizeDAE: time 0.002465/1.532, allocations: 1.094 MB / 1.35 GB, free: 8.246 MB / 1.12 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.003155/1.535, allocations: 1.189 MB / 1.351 GB, free: 7.051 MB / 1.12 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01184/1.547, allocations: 7.3 MB / 1.358 GB, free: 15.66 MB / 1.136 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.363e-05/1.547, allocations: 84 kB / 1.358 GB, free: 15.58 MB / 1.136 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.836e-05/1.547, allocations: 4 kB / 1.358 GB, free: 15.57 MB / 1.136 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.007144/1.554, allocations: 4.946 MB / 1.363 GB, free: 10.5 MB / 1.136 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03527/1.589, allocations: 18.44 MB / 1.381 GB, free: 8.133 MB / 1.151 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.831e-05/1.589, allocations: 4 kB / 1.381 GB, free: 8.129 MB / 1.151 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004106/1.594, allocations: 1.585 MB / 1.382 GB, free: 6.617 MB / 1.151 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.001141/1.595, allocations: 495.7 kB / 1.383 GB, free: 6.094 MB / 1.151 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005895/1.595, allocations: 247.8 kB / 1.383 GB, free: 5.852 MB / 1.151 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01023/1.606, allocations: 23.03 MB / 1.406 GB, free: 13.59 MB / 1.182 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.398e-06/1.606, allocations: 11.98 kB / 1.406 GB, free: 13.58 MB / 1.182 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01687/1.622, allocations: 9.811 MB / 1.415 GB, free: 3.609 MB / 1.182 GB Notification: Performance of postOpt removeConstants (simulation): time 0.004755/1.627, allocations: 2.333 MB / 1.417 GB, free: 1.262 MB / 1.182 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001327/1.628, allocations: 211.8 kB / 1.418 GB, free: 1.055 MB / 1.182 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003514/1.632, allocations: 426.9 kB / 1.418 GB, free: 0.6445 MB / 1.182 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007883/1.633, allocations: 0.5713 MB / 1.419 GB, free: 76 kB / 1.182 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0006072/1.633, allocations: 371.7 kB / 1.419 GB, free: 15.71 MB / 1.198 GB Notification: Performance of sorting global known variables: time 0.004789/1.638, allocations: 3.199 MB / 1.422 GB, free: 12.55 MB / 1.198 GB Notification: Performance of sort global known variables: time 8.1e-08/1.638, allocations: 0 / 1.422 GB, free: 12.55 MB / 1.198 GB Notification: Performance of remove unused functions: time 0.01532/1.653, allocations: 7.463 MB / 1.429 GB, free: 5.074 MB / 1.198 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 16 * Number of states: 36 (flo.sou.fmuZon.EGaiRad,flo.sou.vol.medium.Xi[1],flo.sou.vol.U,flo.sou.vol.m,flo.eas.fmuZon.EGaiRad,flo.eas.vol.medium.Xi[1],flo.eas.vol.U,flo.eas.vol.m,flo.nor.fmuZon.EGaiRad,flo.nor.vol.medium.Xi[1],flo.nor.vol.U,flo.nor.vol.m,flo.wes.fmuZon.EGaiRad,flo.wes.vol.medium.Xi[1],flo.wes.vol.U,flo.wes.vol.m,flo.cor.fmuZon.EGaiRad,flo.cor.vol.medium.Xi[1],flo.cor.vol.U,flo.cor.vol.m,hea[1].conPID.I.y,hea[2].conPID.I.y,hea[3].conPID.I.y,hea[4].conPID.I.y,hea[5].conPID.I.y,coo[1].conPID.I.y,coo[2].conPID.I.y,coo[3].conPID.I.y,coo[4].conPID.I.y,coo[5].conPID.I.y,EHea.y,ECoo.y,flo.att.fmuZon.EGaiRad,flo.att.vol.medium.Xi[1],flo.att.vol.U,flo.att.vol.m) * Number of discrete variables: 138 ($whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,flo.building.weaDat.locTim.tNext,flo.building.weaDat.locTim.k,flo.building.weaDat.conTimMin.tNext,flo.building.weaDat.conTimMin.k,flo.building.weaDat.conTim.tNext,flo.building.weaDat.conTim.k,flo.building.weaDat.eqnTim.tNext,flo.building.weaDat.eqnTim.k,flo.building.weaDat.decAng.tNext,flo.building.weaDat.decAng.k,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,weaDat.locTim.tNext,weaDat.locTim.k,weaDat.conTimMin.tNext,weaDat.conTimMin.k,weaDat.conTim.tNext,weaDat.conTim.k,weaDat.eqnTim.tNext,weaDat.eqnTim.k,weaDat.decAng.tNext,weaDat.decAng.k,flo.sou.fmuZon.TRad,flo.sou.fmuZon.QLat_flow,flo.sou.fmuZon.QPeo_flow,flo.sou.fmuZon.yEP[1],flo.sou.fmuZon.yEP[2],flo.sou.fmuZon.yEP[3],flo.sou.fmuZon.yEP[4],flo.sou.fmuZon.yEP[5],flo.sou.fmuZon.tNext,flo.sou.fmuZon.tLast,flo.sou.fmuZon.dtLast,flo.sou.fmuZon.mInlet_flow,flo.sou.fmuZon.TAveInlet,flo.sou.fmuZon.TRooLast,flo.sou.fmuZon.QGaiRadAve_flow,flo.sou.fmuZon.EGaiRadLast,flo.sou.fmuZon.QConLast_flow,flo.eas.fmuZon.TRad,flo.eas.fmuZon.QLat_flow,flo.eas.fmuZon.QPeo_flow,flo.eas.fmuZon.yEP[1],flo.eas.fmuZon.yEP[2],flo.eas.fmuZon.yEP[3],flo.eas.fmuZon.yEP[4],flo.eas.fmuZon.yEP[5],flo.eas.fmuZon.tNext,flo.eas.fmuZon.tLast,flo.eas.fmuZon.dtLast,flo.eas.fmuZon.mInlet_flow,flo.eas.fmuZon.TAveInlet,flo.eas.fmuZon.TRooLast,flo.eas.fmuZon.QGaiRadAve_flow,flo.eas.fmuZon.EGaiRadLast,flo.eas.fmuZon.QConLast_flow,flo.nor.fmuZon.TRad,flo.nor.fmuZon.QLat_flow,flo.nor.fmuZon.QPeo_flow,flo.nor.fmuZon.yEP[1],flo.nor.fmuZon.yEP[2],flo.nor.fmuZon.yEP[3],flo.nor.fmuZon.yEP[4],flo.nor.fmuZon.yEP[5],flo.nor.fmuZon.tNext,flo.nor.fmuZon.tLast,flo.nor.fmuZon.dtLast,flo.nor.fmuZon.mInlet_flow,flo.nor.fmuZon.TAveInlet,flo.nor.fmuZon.TRooLast,flo.nor.fmuZon.QGaiRadAve_flow,flo.nor.fmuZon.EGaiRadLast,flo.nor.fmuZon.QConLast_flow,flo.wes.fmuZon.TRad,flo.wes.fmuZon.QLat_flow,flo.wes.fmuZon.QPeo_flow,flo.wes.fmuZon.yEP[1],flo.wes.fmuZon.yEP[2],flo.wes.fmuZon.yEP[3],flo.wes.fmuZon.yEP[4],flo.wes.fmuZon.yEP[5],flo.wes.fmuZon.tNext,flo.wes.fmuZon.tLast,flo.wes.fmuZon.dtLast,flo.wes.fmuZon.mInlet_flow,flo.wes.fmuZon.TAveInlet,flo.wes.fmuZon.TRooLast,flo.wes.fmuZon.QGaiRadAve_flow,flo.wes.fmuZon.EGaiRadLast,flo.wes.fmuZon.QConLast_flow,flo.cor.fmuZon.TRad,flo.cor.fmuZon.QLat_flow,flo.cor.fmuZon.QPeo_flow,flo.cor.fmuZon.yEP[1],flo.cor.fmuZon.yEP[2],flo.cor.fmuZon.yEP[3],flo.cor.fmuZon.yEP[4],flo.cor.fmuZon.yEP[5],flo.cor.fmuZon.tNext,flo.cor.fmuZon.tLast,flo.cor.fmuZon.dtLast,flo.cor.fmuZon.mInlet_flow,flo.cor.fmuZon.TAveInlet,flo.cor.fmuZon.TRooLast,flo.cor.fmuZon.QGaiRadAve_flow,flo.cor.fmuZon.EGaiRadLast,flo.cor.fmuZon.QConLast_flow,$whenCondition6,flo.att.fmuZon.TRad,flo.att.fmuZon.QLat_flow,flo.att.fmuZon.QPeo_flow,flo.att.fmuZon.yEP[1],flo.att.fmuZon.yEP[2],flo.att.fmuZon.yEP[3],flo.att.fmuZon.yEP[4],flo.att.fmuZon.yEP[5],flo.att.fmuZon.tNext,flo.att.fmuZon.tLast,flo.att.fmuZon.dtLast,flo.att.fmuZon.mInlet_flow,flo.att.fmuZon.TAveInlet,flo.att.fmuZon.TRooLast,flo.att.fmuZon.QGaiRadAve_flow,flo.att.fmuZon.EGaiRadLast,flo.att.fmuZon.QConLast_flow) * Number of discrete states: 50 (flo.att.fmuZon.tNext,flo.att.fmuZon.TAveInlet,flo.att.fmuZon.tLast,flo.att.fmuZon.EGaiRadLast,flo.att.fmuZon.mInlet_flow,flo.sou.fmuZon.tNext,flo.eas.fmuZon.tNext,flo.nor.fmuZon.tNext,flo.wes.fmuZon.tNext,flo.cor.fmuZon.tNext,flo.sou.fmuZon.tLast,flo.sou.fmuZon.EGaiRadLast,flo.eas.fmuZon.tLast,flo.eas.fmuZon.EGaiRadLast,flo.nor.fmuZon.tLast,flo.nor.fmuZon.EGaiRadLast,flo.wes.fmuZon.tLast,flo.wes.fmuZon.EGaiRadLast,flo.cor.fmuZon.tLast,flo.cor.fmuZon.EGaiRadLast,weaDat.conTimMin.tNext,weaDat.conTimMin.k,weaDat.locTim.tNext,weaDat.locTim.k,weaDat.conTim.tNext,weaDat.conTim.k,weaDat.eqnTim.tNext,weaDat.eqnTim.k,weaDat.decAng.tNext,weaDat.decAng.k,flo.sou.fmuZon.TAveInlet,flo.sou.fmuZon.mInlet_flow,flo.eas.fmuZon.TAveInlet,flo.eas.fmuZon.mInlet_flow,flo.nor.fmuZon.TAveInlet,flo.nor.fmuZon.mInlet_flow,flo.wes.fmuZon.TAveInlet,flo.wes.fmuZon.mInlet_flow,flo.cor.fmuZon.TAveInlet,flo.cor.fmuZon.mInlet_flow,flo.building.weaDat.conTim.tNext,flo.building.weaDat.eqnTim.tNext,flo.building.weaDat.locTim.tNext,flo.building.weaDat.decAng.tNext,flo.building.weaDat.conTimMin.tNext,flo.building.weaDat.decAng.k,flo.building.weaDat.eqnTim.k,flo.building.weaDat.conTim.k,flo.building.weaDat.conTimMin.k,flo.building.weaDat.locTim.k) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (883): * Single equations (assignments): 770 * Array equations: 0 * Algorithm blocks: 1 * Record equations: 0 * When equations: 98 * If-equations: 0 * Equation systems (not torn): 4 * Torn equation systems: 10 * 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): 4 systems {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): 10 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%)} * Non-linear torn systems (#iteration vars, #inner vars): 0 systems Notification: Performance of Backend phase and start with SimCode phase: time 0.003073/1.657, allocations: 1.373 MB / 1.431 GB, free: 4.055 MB / 1.198 GB Notification: Performance of simCode: created initialization part: time 0.02116/1.678, allocations: 11.14 MB / 1.442 GB, free: 9.086 MB / 1.214 GB Notification: Performance of simCode: created event and clocks part: time 2.115e-05/1.678, allocations: 13.12 kB / 1.442 GB, free: 9.07 MB / 1.214 GB Notification: Performance of simCode: created simulation system equations: time 0.006772/1.685, allocations: 4.68 MB / 1.446 GB, free: 4.355 MB / 1.214 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01334/1.698, allocations: 3.858 MB / 1.45 GB, free: 0.5703 MB / 1.214 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03586/1.734, allocations: 22.41 MB / 1.472 GB, free: 9.969 MB / 1.245 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003965/1.738, allocations: 3.045 MB / 1.475 GB, free: 6.898 MB / 1.245 GB Notification: Performance of simCode: alias equations: time 0.01045/1.748, allocations: 3.599 MB / 1.478 GB, free: 3.301 MB / 1.245 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003194/1.751, allocations: 0.9561 MB / 1.479 GB, free: 2.348 MB / 1.245 GB Notification: Performance of SimCode: time 1.673e-06/1.751, allocations: 0 / 1.479 GB, free: 2.348 MB / 1.245 GB Notification: Performance of Templates: time 0.5392/2.29, allocations: 170 MB / 1.645 GB, free: 312.9 MB / 1.261 GB " [Timeout remaining time 298] make -j1 -f Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.makefile [Timeout 300] (rm -f Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.pipe ; mkfifo Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.pipe ; head -c 1048576 < Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.pipe >> ../files/Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.sim & ./Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer -abortSlowSimulation -alarm=240 -emit_protected -lv LOG_STATS > Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer.pipe 2>&1) [Timeout 240] diffSimulationResults("Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.IdealHeatingCoolingSummer_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_11.x/Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SmallOffice_IdealHeatingCoolingSummer.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300] "" [Timeout remaining time 300] Reference file matches [Calling sys.exit(0), Time elapsed: 33.29600624926388]