[
https://issues.apache.org/jira/browse/TS-4591?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15438532#comment-15438532
]
Gota Adachi commented on TS-4591:
---------------------------------
here the current 6.2.x branch's behavior:
{noformat}
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075a6813c0} DEBUG:
<HttpSM.cc:7085 (call_transact_and_set_next_state)> (http) [73] State
Transition: SM_ACTION_ICP_QUERY -> SM_ACTION_CACHE_ISSUE_WRITE
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075a6813c0} DEBUG:
<HttpSM.cc:4641 (do_cache_prepare_action)> (http_cache_write) [73] writing to
cache with URL http://test1.example.jp/test2.html
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075a6813c0} DEBUG:
<HttpCacheSM.cc:176 (state_cache_open_write)> (http_cache) [73]
[&HttpCacheSM::state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075a6813c0} DEBUG:
<HttpSM.cc:2641 (main_handler)> (http) [73] [HttpSM::main_handler,
CACHE_EVENT_OPEN_WRITE]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075a6813c0} DEBUG:
<HttpSM.cc:2455 (state_cache_open_write)> (http) [73] [&HttpSM :
state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075a6813c0} DEBUG:
<ICP.cc:1336 (ICPStateMachine)> (icp) [ICP_REQUEST_NOT_ACTIVE] Id=2
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:2641 (main_handler)> (http) [73] [HttpSM::main_handler,
CACHE_EVENT_OPEN_WRITE]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:2455 (state_cache_open_write)> (http) [73] [&HttpSM :
state_cache_open_write, CACHE_EVENT_OPEN_WRITE]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3061 (handle_cache_write_lock)> (http_trans) Next action next;
__null
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:7085 (call_transact_and_set_next_state)> (http) [73] State
Transition: SM_ACTION_CACHE_ISSUE_WRITE -> SM_ACTION_ORIGIN_SERVER_OPEN
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4666 (do_http_server_open)> (http_track) entered inside
do_http_server_open ][IPv4]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4693 (do_http_server_open)> (http) [73] open connection to
192.168.56.214: 192.168.56.214:36895
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4705 (do_http_server_open)> (http_seq) [HttpSM::do_http_server_open]
Sending request to server
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4954 (do_http_server_open)> (http) calling netProcessor.connect_re
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:2641 (main_handler)> (http) [73] [HttpSM::main_handler,
NET_EVENT_OPEN]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:1707 (state_http_server_open)> (http_track) entered inside
state_http_server_open
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:1708 (state_http_server_open)> (http) [73]
[&HttpSM::state_http_server_open, NET_EVENT_OPEN]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpServerSession.cc:91 (new_connection)> (http_ss) [72] session born, netvc
0x2b07740171a0
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:2641 (main_handler)> (http) [73] [HttpSM::main_handler,
VC_EVENT_ERROR]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:1973 (state_send_server_request_header)> (http) [73]
[&HttpSM::state_send_server_request_header, VC_EVENT_ERROR]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:5365 (handle_server_setup_error)> (http) [73]
[&HttpSM::handle_server_setup_error, VC_EVENT_ERROR]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpServerSession.cc:129 (do_io_close)> (http_ss) [72] session closing, netvc
0x2b07740171a0
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3330 (HandleResponse)> (http_trans)
[HttpTransact::HandleResponse]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3331 (HandleResponse)> (http_seq)
[HttpTransact::HandleResponse] Response received
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:8386 (ink_cluster_time)> (http_trans) [ink_cluster_time]
local: 1472183093, highest_delta: 0, cluster: 1472183093
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3338 (HandleResponse)> (http_trans) [HandleResponse]
response_received_time: 1472183093
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3354 (HandleResponse)> (http_seq)
[HttpTransact::HandleResponse] Response not valid
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3488 (handle_response_from_icp_suggested_host)> (http_trans)
[handle_response_from_icp_suggested_host] (hrfish)
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpTransact.cc:3499 (handle_response_from_icp_suggested_host)> (http_trans)
[hrfish] connection not alive
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:7085 (call_transact_and_set_next_state)> (http) [73] State
Transition: SM_ACTION_ORIGIN_SERVER_OPEN -> SM_ACTION_ORIGIN_SERVER_OPEN
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4666 (do_http_server_open)> (http_track) entered inside
do_http_server_open ][IPv4]
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4693 (do_http_server_open)> (http) [73] open connection to
test1.example.jp: 192.168.56.205:80
Aug 26 12:44:53 dev01 traffic_server[9093]: {0x2b075b0ff700} DEBUG:
<HttpSM.cc:4705 (do_http_server_open)> (http_seq) [HttpSM::do_http_server_open]
Sending request to server
{noformat}
that seems trying to open connection to wrong port where sending request to icp
suggested host.
(open connection to 192.168.56.214: 192.168.56.214:36895 <- this is not ATS
http listen port)
(open connection to test1.example.jp: 192.168.56.205:80 <- and this is correct
port where sending request to origin server)
> Does ICP still works?
> ---------------------
>
> Key: TS-4591
> URL: https://issues.apache.org/jira/browse/TS-4591
> Project: Traffic Server
> Issue Type: Bug
> Components: ICP
> Reporter: zhxiaom5
> Assignee: Bryan Call
> Labels: regresion
> Fix For: 7.0.0
>
>
> I use traffic server - 6.1.1, and configure icp below
> ICP.config
> slave|192.168.208.3|2|8088|3130|0|0.0.0.0|1|
> I debug tcp, it seems works fine
> [Jun 27 15:46:43.594] Server {0x2b0fde221700} DEBUG: (icp) [ICP_START]
> ICP_OP_QUERY for [http://xxx/x.url], Id=3573
> [Jun 27 15:46:43.594] Server {0x2b0fde221700} DEBUG: (icp)
> [ICP_QUEUE_REQUEST] Id=3573 send query to [192.168.208.2:3130]
> [Jun 27 15:46:43.594] Server {0x2b0fde221700} DEBUG: (icp)
> [ICP_QUEUE_REQUEST] Id=3573 expected replies=1
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp) Response for
> Id=3573, from [192.168.208.2:3130]
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp) Passing Reply for
> ICP Id=3573
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp)
> [ICP_AWAITING_RESPONSE] Id=3573
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp) ICP Response HIT
> for Id=3573 from [192.168.208.2:3130] return [192.168.208.2:8088]
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp)
> [ICP_DEQUEUE_REQUEST] Id=3573
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp)
> [ICP_POST_COMPLETION] Id=3573
> [Jun 27 15:46:43.612] Server {0x2b0fdea29700} DEBUG: (icp)
> [ICP_REQUEST_NOT_ACTIVE] Id=3573
> and i also configure remap.config file,but seems it still use origin
> server，not icp
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
