It looks like you enabled the "http" debug tag but not the "cache" debug
tag. That said, given the "Will NOT do cache lookup", I suspect Leif is
correct. OTOH I do see
[Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:4696
(do_cache_prepare_action)> (http_cache_write) [35] writing to cache with
URL http://10.10.0.213/
<https://urldefense.proofpoint.com/v2/url?u=http-3A__10.10.0.213_&d=DwMFaQ&c=sWW_bEwW_mLyN3Kx2v57Q8e-CRbmiT9yOhqES_g_wVY&r=5nE_8e-Jc1t5vF6GVeub9BCN4FzSc_6kU7_mjSiUrDs&m=wFygucBV1pFwCVoy1_dK7uEOITb4TOm0sVYwfjePuUs&s=9xDeuoqZtYqzeZRmi6edCNHOSy3SwpbA5qVV77cjuXE&e=>
[Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <P_CacheInternal.h:566
(new_CacheVC)> (cache_new) new 0x7fa8b8284bb0
[Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <Cache.cc:3126
(key_to_vol)> (cache_hosting) Generic volume: b8008c28d for host:
10.10.0.213
[Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpCacheSM.cc:177
(state_cache_open_write)> (http_cache) [35]
[&HttpCacheSM::state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
[Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2676
(main_handler)> (http) [35] [HttpSM::main_handler, CACHE_EVENT_OPEN_WRITE]
which indicates an attempt to write to cache, but then
[Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:6423
(is_response_cacheable)> (http_trans) [35] [is_response_cacheable] NO by
default
[Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4264
(handle_cache_operation_on_forward_server_response)> (http_trans) [35]
[hcoofsr] response is not cacheable
[Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4407
(handle_cache_operation_on_forward_server_response)> (http_trans) [35]
[hcoofsr] response code: 403
which indicates it's not being cached because it's a negative response.
On Wed, Feb 17, 2021 at 9:34 AM Milos Dodic <[email protected]> wrote:
> I have enabled cache tags for debugging, here is the parsed traffic.out
> log (looking at cache lines only):
>
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1996
> (DecideCacheLookup)> (http_trans) [31] [DecideCacheLookup] Will NOT do
> cache lookup.
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1997
> (DecideCacheLookup)> (http_seq) [31] [DecideCacheLookup] Will NOT do cache
> lookup
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:2019
> (DecideCacheLookup)> (http_trans) Next action
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE; CallOSDNSLookup
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [31] State Transition:
> SM_ACTION_API_POST_REMAP -> SM_ACTION_API_CACHE_LOOKUP_COMPLETE
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [31] State Transition:
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE -> SM_ACTION_DNS_LOOKUP
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:7953
> (build_request)> (http_trans) [31] [build_request] request like cacheable
> and conditional headers removed
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4145
> (handle_forward_server_connection_open)> (http_trans) [31] [hfsco] cache
> action: CACHE_DO_NO_ACTION
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4708
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [31]
> [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4709
> (handle_no_cache_operation_on_forward_server_response)> (http_seq) [31]
> [handle_no_cache_operation_on_forward_server_response]
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4716
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [31]
> [hncoofsr] server sent back 200
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4723
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [31]
> [hncoofsr] next action will be OS_READ_CACHE_NOOP
> [Feb 17 15:31:04.433] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:5816
> (perform_cache_write_action)> (http) [31] perform_cache_write_action
> CACHE_DO_NO_ACTION
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1996
> (DecideCacheLookup)> (http_trans) [32] [DecideCacheLookup] Will NOT do
> cache lookup.
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1997
> (DecideCacheLookup)> (http_seq) [32] [DecideCacheLookup] Will NOT do cache
> lookup
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:2019
> (DecideCacheLookup)> (http_trans) Next action
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE; CallOSDNSLookup
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [32] State Transition:
> SM_ACTION_API_POST_REMAP -> SM_ACTION_API_CACHE_LOOKUP_COMPLETE
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [32] State Transition:
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE -> SM_ACTION_DNS_LOOKUP
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:7953
> (build_request)> (http_trans) [32] [build_request] request like cacheable
> and conditional headers removed
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4145
> (handle_forward_server_connection_open)> (http_trans) [32] [hfsco] cache
> action: CACHE_DO_NO_ACTION
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4708
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [32]
> [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4709
> (handle_no_cache_operation_on_forward_server_response)> (http_seq) [32]
> [handle_no_cache_operation_on_forward_server_response]
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4716
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [32]
> [hncoofsr] server sent back 200
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4723
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [32]
> [hncoofsr] next action will be OS_READ_CACHE_NOOP
> [Feb 17 15:31:14.435] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:5816
> (perform_cache_write_action)> (http) [32] perform_cache_write_action
> CACHE_DO_NO_ACTION
> [Feb 17 15:31:17.488] Server {0x7fa8bfffe700} DEBUG: <CacheDir.cc:1048
> (mainEvent)> (cache_dir_sync) sync started
> [Feb 17 15:31:17.488] Server {0x7fa8bfffe700} DEBUG: <CacheDir.cc:1056
> (mainEvent)> (cache_dir_sync) Dir /test/cache/cache.db 16384:65536 not dirty
> [Feb 17 15:31:17.488] Server {0x7fa8bfffe700} DEBUG: <CacheDir.cc:1048
> (mainEvent)> (cache_dir_sync) sync started
> [Feb 17 15:31:17.488] Server {0x7fa8bfffe700} DEBUG: <CacheDir.cc:1056
> (mainEvent)> (cache_dir_sync) Dir /test/cache/cache.db 536887296:32768 not
> dirty
> [Feb 17 15:31:17.488] Server {0x7fa8bfffe700} DEBUG: <CacheDir.cc:1006
> (mainEvent)> (cache_dir_sync) sync done
> [Feb 17 15:31:24.436] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1996
> (DecideCacheLookup)> (http_trans) [33] [DecideCacheLookup] Will NOT do
> cache lookup.
> [Feb 17 15:31:24.436] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1997
> (DecideCacheLookup)> (http_seq) [33] [DecideCacheLookup] Will NOT do cache
> lookup
> [Feb 17 15:31:24.436] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:2019
> (DecideCacheLookup)> (http_trans) Next action
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE; CallOSDNSLookup
> [Feb 17 15:31:24.436] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [33] State Transition:
> SM_ACTION_API_POST_REMAP -> SM_ACTION_API_CACHE_LOOKUP_COMPLETE
> [Feb 17 15:31:24.436] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [33] State Transition:
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE -> SM_ACTION_DNS_LOOKUP
> [Feb 17 15:31:24.436] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:7953
> (build_request)> (http_trans) [33] [build_request] request like cacheable
> and conditional headers removed
> [Feb 17 15:31:24.437] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4145
> (handle_forward_server_connection_open)> (http_trans) [33] [hfsco] cache
> action: CACHE_DO_NO_ACTION
> [Feb 17 15:31:24.437] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4708
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [33]
> [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
> [Feb 17 15:31:24.437] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4709
> (handle_no_cache_operation_on_forward_server_response)> (http_seq) [33]
> [handle_no_cache_operation_on_forward_server_response]
> [Feb 17 15:31:24.437] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4716
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [33]
> [hncoofsr] server sent back 200
> [Feb 17 15:31:24.437] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4723
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [33]
> [hncoofsr] next action will be OS_READ_CACHE_NOOP
> [Feb 17 15:31:24.437] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:5816
> (perform_cache_write_action)> (http) [33] perform_cache_write_action
> CACHE_DO_NO_ACTION
> [Feb 17 15:31:34.438] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1996
> (DecideCacheLookup)> (http_trans) [34] [DecideCacheLookup] Will NOT do
> cache lookup.
> [Feb 17 15:31:34.438] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1997
> (DecideCacheLookup)> (http_seq) [34] [DecideCacheLookup] Will NOT do cache
> lookup
> [Feb 17 15:31:34.438] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:2019
> (DecideCacheLookup)> (http_trans) Next action
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE; CallOSDNSLookup
> [Feb 17 15:31:34.438] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [34] State Transition:
> SM_ACTION_API_POST_REMAP -> SM_ACTION_API_CACHE_LOOKUP_COMPLETE
> [Feb 17 15:31:34.438] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [34] State Transition:
> SM_ACTION_API_CACHE_LOOKUP_COMPLETE -> SM_ACTION_DNS_LOOKUP
> [Feb 17 15:31:34.438] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:7953
> (build_request)> (http_trans) [34] [build_request] request like cacheable
> and conditional headers removed
> [Feb 17 15:31:34.439] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4145
> (handle_forward_server_connection_open)> (http_trans) [34] [hfsco] cache
> action: CACHE_DO_NO_ACTION
> [Feb 17 15:31:34.439] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4708
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [34]
> [handle_no_cache_operation_on_forward_server_response] (hncoofsr)
> [Feb 17 15:31:34.439] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4709
> (handle_no_cache_operation_on_forward_server_response)> (http_seq) [34]
> [handle_no_cache_operation_on_forward_server_response]
> [Feb 17 15:31:34.439] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4716
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [34]
> [hncoofsr] server sent back 200
> [Feb 17 15:31:34.439] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4723
> (handle_no_cache_operation_on_forward_server_response)> (http_trans) [34]
> [hncoofsr] next action will be OS_READ_CACHE_NOOP
> [Feb 17 15:31:34.439] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:5816
> (perform_cache_write_action)> (http) [34] perform_cache_write_action
> CACHE_DO_NO_ACTION
> [Feb 17 15:31:36.404] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1948
> (DecideCacheLookup)> (http_trans) [35] [DecideCacheLookup] Will do cache
> lookup.
> [Feb 17 15:31:36.404] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1949
> (DecideCacheLookup)> (http_seq) [35] [DecideCacheLookup] Will do cache
> lookup
> [Feb 17 15:31:36.404] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1992
> (DecideCacheLookup)> (http_trans) Next action SM_ACTION_CACHE_LOOKUP;
> nullptr
> [Feb 17 15:31:36.404] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [35] State Transition:
> SM_ACTION_API_POST_REMAP -> SM_ACTION_CACHE_LOOKUP
> [Feb 17 15:31:36.404] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:4569
> (do_cache_lookup_and_read)> (http_seq) [HttpSM::do_cache_lookup_and_read]
> [35] Issuing cache lookup for URL http://10.10.0.213/
> <https://urldefense.proofpoint.com/v2/url?u=http-3A__10.10.0.213_&d=DwMFaQ&c=sWW_bEwW_mLyN3Kx2v57Q8e-CRbmiT9yOhqES_g_wVY&r=5nE_8e-Jc1t5vF6GVeub9BCN4FzSc_6kU7_mjSiUrDs&m=wFygucBV1pFwCVoy1_dK7uEOITb4TOm0sVYwfjePuUs&s=9xDeuoqZtYqzeZRmi6edCNHOSy3SwpbA5qVV77cjuXE&e=>
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <Cache.cc:3126
> (key_to_vol)> (cache_hosting) Generic volume: b8008c28d for host:
> 10.10.0.213
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpCacheSM.cc:118
> (state_cache_open_read)> (http_cache) [35]
> [&HttpCacheSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2676
> (main_handler)> (http) [35] [HttpSM::main_handler,
> CACHE_EVENT_OPEN_READ_FAILED]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2607
> (state_cache_open_read)> (http) [35] [&HttpSM::state_cache_open_read,
> CACHE_EVENT_OPEN_READ_FAILED]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2641
> (state_cache_open_read)> (http) [35] cache_open_read -
> CACHE_EVENT_OPEN_READ_FAILED with ECACHE_NO_DOC (-20400)
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2643
> (state_cache_open_read)> (http) [state_cache_open_read] open read failed.
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:2205
> (HandleCacheOpenRead)> (http_trans) [35] [HttpTransact::HandleCacheOpenRead]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:2235
> (HandleCacheOpenRead)> (http_trans) [35] CacheOpenRead -- miss
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:3122
> (HandleCacheOpenReadMiss)> (http_trans) [35] [HandleCacheOpenReadMiss] ---
> MISS
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:3124
> (HandleCacheOpenReadMiss)> (http_seq) [35]
> [HttpTransact::HandleCacheOpenReadMiss] Miss in cache
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:3200
> (HandleCacheOpenReadMiss)> (http_trans) Next action SM_ACTION_DNS_LOOKUP;
> OSDNSLookup
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [35] State Transition:
> SM_ACTION_CACHE_LOOKUP -> SM_ACTION_DNS_LOOKUP
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:1846
> (OSDNSLookup)> (http_trans) Next action SM_ACTION_API_OS_DNS;
> HandleCacheOpenReadMiss
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:3122
> (HandleCacheOpenReadMiss)> (http_trans) [35] [HandleCacheOpenReadMiss] ---
> MISS
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:3124
> (HandleCacheOpenReadMiss)> (http_seq) [35]
> [HttpTransact::HandleCacheOpenReadMiss] Miss in cache
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:7953
> (build_request)> (http_trans) [35] [build_request] request like cacheable
> and conditional headers removed
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [35] State Transition:
> SM_ACTION_API_OS_DNS -> SM_ACTION_CACHE_ISSUE_WRITE
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:4696
> (do_cache_prepare_action)> (http_cache_write) [35] writing to cache with
> URL http://10.10.0.213/
> <https://urldefense.proofpoint.com/v2/url?u=http-3A__10.10.0.213_&d=DwMFaQ&c=sWW_bEwW_mLyN3Kx2v57Q8e-CRbmiT9yOhqES_g_wVY&r=5nE_8e-Jc1t5vF6GVeub9BCN4FzSc_6kU7_mjSiUrDs&m=wFygucBV1pFwCVoy1_dK7uEOITb4TOm0sVYwfjePuUs&s=9xDeuoqZtYqzeZRmi6edCNHOSy3SwpbA5qVV77cjuXE&e=>
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG:
> <P_CacheInternal.h:566 (new_CacheVC)> (cache_new) new 0x7fa8b8284bb0
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <Cache.cc:3126
> (key_to_vol)> (cache_hosting) Generic volume: b8008c28d for host:
> 10.10.0.213
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpCacheSM.cc:177
> (state_cache_open_write)> (http_cache) [35]
> [&HttpCacheSM::state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2676
> (main_handler)> (http) [35] [HttpSM::main_handler, CACHE_EVENT_OPEN_WRITE]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:2488
> (state_cache_open_write)> (http) [35] [&HttpSM : state_cache_open_write,
> CACHE_EVENT_OPEN_WRITE]
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:3088
> (handle_cache_write_lock)> (http_trans) Next action next; nullptr
> [Feb 17 15:31:36.405] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:7244
> (call_transact_and_set_next_state)> (http) [35] State Transition:
> SM_ACTION_CACHE_ISSUE_WRITE -> SM_ACTION_ORIGIN_SERVER_OPEN
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4124
> (handle_forward_server_connection_open)> (http_trans) [35] [hfsco] cache
> action: CACHE_DO_WRITE
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4254
> (handle_cache_operation_on_forward_server_response)> (http_trans) [35]
> [handle_cache_operation_on_forward_server_response] (hcoofsr)
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4255
> (handle_cache_operation_on_forward_server_response)> (http_seq) [35]
> [handle_cache_operation_on_forward_server_response]
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:6308
> (is_response_cacheable)> (http_trans) [35] [is_response_cacheable] client
> permits storing
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:6423
> (is_response_cacheable)> (http_trans) [35] [is_response_cacheable] NO by
> default
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4264
> (handle_cache_operation_on_forward_server_response)> (http_trans) [35]
> [hcoofsr] response is not cacheable
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpTransact.cc:4407
> (handle_cache_operation_on_forward_server_response)> (http_trans) [35]
> [hcoofsr] response code: 403
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG: <HttpSM.cc:5816
> (perform_cache_write_action)> (http) [35] perform_cache_write_action
> CACHE_DO_NO_ACTION
> [Feb 17 15:31:36.407] Server {0x7fa8bfffe700} DEBUG:
> <P_CacheInternal.h:578 (free_CacheVC)> (cache_free) free 0x7fa8b8284bb0
>
>
>
> I see CACHE_DO_NO_ACTION and response is not cacheable. I am curling an
> index page (default index) on Apache web server, fresh install.
>
> On Wed, Feb 17, 2021 at 4:17 PM Alan Carroll <
> [email protected]> wrote:
>
>> Hmmm. I would enable debug tags and add the "cache" tag. That should give
>> you information on why the cache isn't caching. You can also chat with us
>> at the-asf.slack.com
>> <https://urldefense.proofpoint.com/v2/url?u=http-3A__the-2Dasf.slack.com&d=DwMFaQ&c=sWW_bEwW_mLyN3Kx2v57Q8e-CRbmiT9yOhqES_g_wVY&r=5nE_8e-Jc1t5vF6GVeub9BCN4FzSc_6kU7_mjSiUrDs&m=wFygucBV1pFwCVoy1_dK7uEOITb4TOm0sVYwfjePuUs&s=irsYt8PCQN3eRwH3Lz4lsWSyZ4Ln4hwNog9_VgFJ8U8&e=>,
>> channel "#trafficserver".
>>
>> On Wed, Feb 17, 2021 at 9:13 AM Milos Dodic <[email protected]> wrote:
>>
>>> I already had cache defined in storage.config. As for volume.config,
>>> I've added volumes, but it didn't change much (seems like an option to
>>> segment the overall storage for cache).
>>>
>>> On Wed, Feb 17, 2021 at 3:57 PM Alan Carroll <
>>> [email protected]> wrote:
>>>
>>>> You need to set up the "storage.config" and "volume.config" files to
>>>> specify the storage used to cache the objects.
>>>>
>>>> On Tue, Feb 16, 2021 at 6:38 PM Milos Dodic <[email protected]>
>>>> wrote:
>>>>
>>>>> Basic caching setup?
>>>>>
>>>>>
>>>>> I am trying to have the most basic caching setup for a forward caching
>>>>> proxy. Initially I tried changing the following options only:
>>>>>
>>>>> CONFIG proxy.config.http.cache.http INT 1
>>>>> CONFIG proxy.config.reverse_proxy.enabled INT 0
>>>>> CONFIG proxy.config.url_remap.remap_required INT 0
>>>>>
>>>>> I had no luck. After trying out a few things, I ended with something
>>>>> like this:
>>>>>
>>>>> CONFIG proxy.config.http.cache.http INT 1
>>>>> CONFIG proxy.config.reverse_proxy.enabled INT 0
>>>>> CONFIG proxy.config.url_remap.remap_required INT 0
>>>>> CONFIG proxy.config.http.cache.ignore_client_no_cache INT 1
>>>>> CONFIG proxy.config.http.cache.ignore_server_no_cache INT 1
>>>>> CONFIG proxy.config.http.cache.ignore_authentication INT 1
>>>>> CONFIG proxy.config.http.cache.cache_urls_that_look_dynamic INT 1
>>>>> CONFIG proxy.config.http.uncacheable_requests_bypass_parent INT 1
>>>>> CONFIG proxy.config.http.cache.required_headers INT X (tried 0,1 and 2)
>>>>> CONFIG proxy.config.http.push_method_enabled INT 1
>>>>>
>>>>> Again, no luck. All I got was lots of MISS, and nothing was cached.
>>>>> I tried to cache by using the custom app with a forward proxy set to
>>>>> target the ATS, and later by simply trying to curl na object from AWS S3
>>>>> (https). In the end, I brought up an Apache web server, and tried curling
>>>>> the basic http index page. Still, nothing was cached.
>>>>>
>>>>> How can I have the most basic setup in place (at least for now), that
>>>>> will cache stuff like me pulling the index page from the apache web
>>>>> server?
>>>>> Or cache everything even?
>>>>> What am I missing?
>>>>>
>>>>>
>>>>> Thanks!
>>>>>
>>>>
