Can anyone describe why it appears multiple requests are made for files that have already been downloaded successfully?
For example, I see 16 instances below for /dns/www/000/index.html
I’ve seen this many times before and wondered about it; this time I caught it and could see the whole of that index file had downloaded and yet still the requests kept coming.
Every time I see this it appears to move on after a while but I wonder some of those requests are not needed. Perhaps it’s a deliberate part of the test or perhaps there’s a good reason for it but if the file is downloaded to a point that I can open it, then I wondered there is nothing more that truly needed downloading.
DEBUG 19:19:4.973 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:4.980 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:4.981 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:4.983 - Sending message to FFI - 15be6bd6-69a6-4bb1-8648-dba74da1b750 - dns - get-file-metadata
DEBUG 19:19:5.193 - FFI response code 0 for id - 15be6bd6-69a6-4bb1-8648-dba74da1b750
VERBOSE 19:19:5.194 - Invoking response callback :: callback id - 15be6bd6-69a6-4bb1-8648-dba74da1b750
DEBUG 19:19:5.196 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:5.201 - DNS - Ready to stream file for range0-627/627
DEBUG 19:19:5.217 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:5.218 - Sending message to FFI - ec1537db-b58c-4be4-a72e-5ac9f83a5dad - dns - get-file
DEBUG 19:19:5.492 - FFI response code 0 for id - ec1537db-b58c-4be4-a72e-5ac9f83a5dad
VERBOSE 19:19:5.498 - Invoking response callback :: callback id - ec1537db-b58c-4be4-a72e-5ac9f83a5dad
DEBUG 19:19:6.551 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:6.553 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:6.554 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:6.555 - Sending message to FFI - 7da977c3-3f92-4dda-a799-03a77860dd3b - dns - get-file-metadata
DEBUG 19:19:6.770 - FFI response code 0 for id - 7da977c3-3f92-4dda-a799-03a77860dd3b
VERBOSE 19:19:6.772 - Invoking response callback :: callback id - 7da977c3-3f92-4dda-a799-03a77860dd3b
DEBUG 19:19:6.772 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:6.774 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:8.806 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:8.808 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:8.809 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:8.810 - Sending message to FFI - 7bd8aa52-7fbe-4963-8db9-f9797d78506f - dns - get-file-metadata
DEBUG 19:19:9.51 - FFI response code 0 for id - 7bd8aa52-7fbe-4963-8db9-f9797d78506f
VERBOSE 19:19:9.55 - Invoking response callback :: callback id - 7bd8aa52-7fbe-4963-8db9-f9797d78506f
DEBUG 19:19:9.56 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:9.58 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:12.78 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:12.80 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:12.81 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:12.82 - Sending message to FFI - 89322055-2db2-470f-940f-688421b66e89 - dns - get-file-metadata
DEBUG 19:19:12.291 - FFI response code 0 for id - 89322055-2db2-470f-940f-688421b66e89
VERBOSE 19:19:12.293 - Invoking response callback :: callback id - 89322055-2db2-470f-940f-688421b66e89
DEBUG 19:19:12.295 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:12.300 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:16.321 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:16.323 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:16.324 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:16.325 - Sending message to FFI - e45825a4-198a-480f-abd0-633770c64a82 - dns - get-file-metadata
DEBUG 19:19:16.531 - FFI response code 0 for id - e45825a4-198a-480f-abd0-633770c64a82
VERBOSE 19:19:16.532 - Invoking response callback :: callback id - e45825a4-198a-480f-abd0-633770c64a82
DEBUG 19:19:16.544 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:16.545 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:21.566 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:21.569 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:21.572 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:21.574 - Sending message to FFI - 5c2cc393-2b7b-4b77-87d9-eb02ddd134c4 - dns - get-file-metadata
DEBUG 19:19:21.797 - FFI response code 0 for id - 5c2cc393-2b7b-4b77-87d9-eb02ddd134c4
VERBOSE 19:19:21.798 - Invoking response callback :: callback id - 5c2cc393-2b7b-4b77-87d9-eb02ddd134c4
DEBUG 19:19:21.800 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:21.801 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:27.823 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:27.825 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:27.826 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:27.827 - Sending message to FFI - 5fc023dd-bb7f-4cdb-b70e-aeac9e36a3ad - dns - get-file-metadata
DEBUG 19:19:28.398 - FFI response code 0 for id - 5fc023dd-bb7f-4cdb-b70e-aeac9e36a3ad
VERBOSE 19:19:28.399 - Invoking response callback :: callback id - 5fc023dd-bb7f-4cdb-b70e-aeac9e36a3ad
DEBUG 19:19:28.400 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:28.401 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:35.448 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:35.452 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:35.453 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:35.455 - Sending message to FFI - ab1a3a34-304f-489c-b366-f62de9d2c1eb - dns - get-file-metadata
DEBUG 19:19:35.667 - FFI response code 0 for id - ab1a3a34-304f-489c-b366-f62de9d2c1eb
VERBOSE 19:19:35.670 - Invoking response callback :: callback id - ab1a3a34-304f-489c-b366-f62de9d2c1eb
DEBUG 19:19:35.672 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:35.675 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:43.699 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:43.702 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:43.703 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:43.704 - Sending message to FFI - 0d68cc16-b977-4f7b-825c-7c7496b887de - dns - get-file-metadata
DEBUG 19:19:43.924 - FFI response code 0 for id - 0d68cc16-b977-4f7b-825c-7c7496b887de
VERBOSE 19:19:43.926 - Invoking response callback :: callback id - 0d68cc16-b977-4f7b-825c-7c7496b887de
DEBUG 19:19:43.928 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:43.929 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:52.945 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:19:52.947 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:19:52.948 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:19:52.949 - Sending message to FFI - b6f8f638-577b-40bb-b750-60fa170bd910 - dns - get-file-metadata
DEBUG 19:19:53.158 - FFI response code 0 for id - b6f8f638-577b-40bb-b750-60fa170bd910
VERBOSE 19:19:53.159 - Invoking response callback :: callback id - b6f8f638-577b-40bb-b750-60fa170bd910
DEBUG 19:19:53.160 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:19:53.161 - DNS - Ready to stream file for range627-627/627
DEBUG 19:19:53.298 - Invoking CLIENT STATS API:: GETs COUNT::client-stats
DEBUG 19:19:53.300 - Sending message to FFI - 2fcb85a8-63d2-4162-9067-35523405f53f - client-stats - gets
DEBUG 19:19:53.317 - FFI response code 0 for id - 2fcb85a8-63d2-4162-9067-35523405f53f
VERBOSE 19:19:53.318 - Invoking response callback :: callback id - 2fcb85a8-63d2-4162-9067-35523405f53f
DEBUG 19:20:3.181 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:20:3.183 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:20:3.186 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:20:3.187 - Sending message to FFI - 56e6ca1b-b5ab-427f-9a43-2ac356b04785 - dns - get-file-metadata
DEBUG 19:20:3.423 - FFI response code 0 for id - 56e6ca1b-b5ab-427f-9a43-2ac356b04785
VERBOSE 19:20:3.426 - Invoking response callback :: callback id - 56e6ca1b-b5ab-427f-9a43-2ac356b04785
DEBUG 19:20:3.427 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:20:3.429 - DNS - Ready to stream file for range627-627/627
DEBUG 19:20:13.450 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:20:13.454 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:20:13.455 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:20:13.458 - Sending message to FFI - 0aadbc1e-825f-40b4-8c6a-22ee043cd9fe - dns - get-file-metadata
DEBUG 19:20:13.673 - FFI response code 0 for id - 0aadbc1e-825f-40b4-8c6a-22ee043cd9fe
VERBOSE 19:20:13.674 - Invoking response callback :: callback id - 0aadbc1e-825f-40b4-8c6a-22ee043cd9fe
DEBUG 19:20:13.675 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:20:13.676 - DNS - Ready to stream file for range627-627/627
DEBUG 19:20:23.697 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:20:23.700 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:20:23.701 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:20:23.702 - Sending message to FFI - d20cd19d-f614-4b65-a3e8-678675a208ef - dns - get-file-metadata
DEBUG 19:20:23.956 - FFI response code 0 for id - d20cd19d-f614-4b65-a3e8-678675a208ef
VERBOSE 19:20:23.957 - Invoking response callback :: callback id - d20cd19d-f614-4b65-a3e8-678675a208ef
DEBUG 19:20:23.958 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:20:23.961 - DNS - Ready to stream file for range627-627/627
DEBUG 19:20:33.988 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:20:33.989 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:20:33.990 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:20:33.993 - Sending message to FFI - a9c963ea-183c-4a13-995f-f0d135c6bbf9 - dns - get-file-metadata
DEBUG 19:20:34.236 - FFI response code 0 for id - a9c963ea-183c-4a13-995f-f0d135c6bbf9
VERBOSE 19:20:34.237 - Invoking response callback :: callback id - a9c963ea-183c-4a13-995f-f0d135c6bbf9
DEBUG 19:20:34.238 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:20:34.239 - DNS - Ready to stream file for range627-627/627
DEBUG 19:20:44.260 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:20:44.261 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:20:44.262 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:20:44.263 - Sending message to FFI - 584774c5-4cc2-4780-ab49-179eeb94b812 - dns - get-file-metadata
DEBUG 19:20:44.627 - FFI response code 0 for id - 584774c5-4cc2-4780-ab49-179eeb94b812
VERBOSE 19:20:44.629 - Invoking response callback :: callback id - 584774c5-4cc2-4780-ab49-179eeb94b812
DEBUG 19:20:44.630 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:20:44.631 - DNS - Ready to stream file for range627-627/627
DEBUG 19:20:53.298 - Invoking CLIENT STATS API:: GETs COUNT::client-stats
DEBUG 19:20:53.299 - Sending message to FFI - fdaa4e19-86e3-4717-87ef-0cd29c52343f - client-stats - gets
DEBUG 19:20:53.304 - FFI response code 0 for id - fdaa4e19-86e3-4717-87ef-0cd29c52343f
VERBOSE 19:20:53.305 - Invoking response callback :: callback id - fdaa4e19-86e3-4717-87ef-0cd29c52343f
DEBUG 19:20:54.654 - Unauthorised request ::/dns/www/000/index.html
DEBUG 19:20:54.658 - DNS - Invoking getFile API for 000, www, index.html
DEBUG 19:20:54.659 - Invoking API DNS::getFile - FFI::dns::get-file
DEBUG 19:20:54.661 - Sending message to FFI - 1953035a-5e34-4835-a8cd-c1da06766488 - dns - get-file-metadata
DEBUG 19:20:54.888 - FFI response code 0 for id - 1953035a-5e34-4835-a8cd-c1da06766488
VERBOSE 19:20:54.890 - Invoking response callback :: callback id - 1953035a-5e34-4835-a8cd-c1da06766488
DEBUG 19:20:54.891 - DNS - File metadata for reading - {"name":"index.html","size":627,"created_time_sec":1469979154,"created_time_nsec":67677000,"modified_time_sec":1469979154,"modified_time_nsec":71350000,"user_metadata":[],"version":2}
DEBUG 19:20:54.892 - DNS - Ready to stream file for range627-627/627
INFO 19:21:3.257 - Stopping proxy server
DEBUG 19:21:3.261 - Closing FFI worker client
WARN 19:21:3.287 - FFI worker client closed already
INFO 19:21:3.294 - Proxy server stopped