ATS 3.0 shows a http 502 error as a forward proxy server ！
----------------------------------------------------------
Key: TS-872
URL: https://issues.apache.org/jira/browse/TS-872
Project: Traffic Server
Issue Type: Bug
Components: DNS
Affects Versions: 3.0.0
Environment: OS: Ubuntu 10.10, Traffic Server version:.3.0, Web
Browser：firefox 4.0.1，CPU: Intel core i3-2100 3.10GHz, Memory: 2G, HardDisk:
500G
Reporter: taoyunxing
Fix For: sometime
when I set up a forward proxy with ATS 3.0 and firefox 4.0.1 and start the
proxy server as a root, it shows me the following info:
root@tyx-System-Product-Name:/usr/local/bin# ./traffic_server
[TrafficServer] using root directory '/usr/local'
[Jul 6 08:56:36.765] {3077691088} STATUS: opened
/usr/local/var/log/trafficserver/diags.log
[Jul 6 08:56:36.765] {3077691088} NOTE: updated diags config
[Jul 6 08:56:36.766] Server {3077691088} DEBUG: (http_aeua)
[HttpConfig::init_aeua_filter] - Config:
"/usr/local/etc/trafficserver/ae_ua.config"
[Jul 6 08:56:36.766] Server {3077691088} DEBUG: (http_aeua)
[HttpConfig::init_aeua_filter] - Opening config
"/usr/local/etc/trafficserver/ae_ua.config"
[Jul 6 08:56:36.766] Server {3077691088} DEBUG: (http_aeua)
[HttpConfig::init_aeua_filter] - Added 0 REGEXP filters
[Jul 6 08:56:36.766] Server {3077691088} DEBUG: (http_aeua)
[init_http_aeua_filter] - Total loaded 0 REGEXP for Accept-Enconding/User-Agent
filtering
[Jul 6 08:56:36.768] Server {3077691088} NOTE: cache clustering disabled
[Jul 6 08:56:36.768] Server {3077691088} NOTE: clearing statistics
[Jul 6 08:56:36.770] Server {3077691088} DEBUG: (dns) ink_dns_init: called
with init_called = 0
[Jul 6 08:56:36.779] Server {3077691088} DEBUG: (dns)
localhost=tyx-System-Product-Name
[Jul 6 08:56:36.779] Server {3077691088} DEBUG: (dns) Round-robin nameservers
= 0
[Jul 6 08:56:36.779] Server {3077691088} DEBUG: (hostdb) Storage path is
/usr/local/var/trafficserver
[Jul 6 08:56:36.779] Server {3077691088} DEBUG: (hostdb) Opening host.db,
size=200000
[Jul 6 08:56:36.779] Server {3077691088} WARNING: configuration changed:
[hostdb.config] : reinitializing database
[Jul 6 08:56:36.779] Server {3077691088} NOTE: reconfiguring host database
[Jul 6 08:56:36.779] Server {3077691088} DEBUG: (hostdb) unable to unlink
/usr/local/etc/trafficserver/internal/hostdb.config
[Jul 6 08:56:36.779] Server {3077691088} WARNING: Configured store too small,
unable to reconfigure
[Jul 6 08:56:36.779] Server {3077691088} WARNING: unable to initialize
database (too little storage)
: [hostdb.config] : disabling database
You may need to 'reconfigure' your cache manually. Please refer to
the 'Configuration' chapter in the manual.
[Jul 6 08:56:36.779] Server {3077691088} WARNING: could not initialize host
database. Host database will be disabled
[Jul 6 08:56:36.779] Server {3077691088} WARNING: bad hostdb or storage
configuration, hostdb disabled
[Jul 6 08:56:36.780] Server {3077691088} NOTE: cache clustering disabled
[Jul 6 08:56:36.834] Server {3057408880} WARNING: disk header different for
disk /usr/local/var/trafficserver/cache.db: clearing the disk
[Jul 6 08:56:36.884] Server {3077691088} NOTE: logging initialized[7],
logging_mode = 3
[Jul 6 08:56:36.887] Server {3077691088} DEBUG: (http_init)
proxy.config.http.redirection_enabled = 0
[Jul 6 08:56:36.887] Server {3077691088} DEBUG: (http_init)
proxy.config.http.number_of_redirections = 1
[Jul 6 08:56:36.887] Server {3077691088} DEBUG: (http_init)
proxy.config.http.post_copy_size = 2048
[Jul 6 08:56:36.887] Server {3077691088} DEBUG: (http_tproxy) Primary listen
socket transparency is off
[Jul 6 08:56:36.890] Server {3077691088} NOTE: traffic server running
[Jul 6 08:56:36.890] Server {3077691088} DEBUG: (dns) DNSHandler::startEvent:
on thread 0
[Jul 6 08:56:36.890] Server {3077691088} DEBUG: (dns) open_con: opening
connection 8.8.8.8:53
[Jul 6 08:56:36.890] Server {3077691088} DEBUG: (dns) random port = 42595
[Jul 6 08:56:36.890] Server {3077691088} DEBUG: (dns) opening connection
8.8.8.8:53 SUCCEEDED for 0
[Jul 6 08:56:36.918] Server {3058461552} NOTE: Clearing Disk:
/usr/local/var/trafficserver/cache.db
[Jul 6 08:56:36.919] Server {3058461552} NOTE: clearing cache directory
'/usr/local/var/trafficserver/cache.db 16384:24575'
[Jul 6 08:56:37.056] Server {3055303536} NOTE: cache enabled
[Jul 6 08:56:45.632] Server {3002059632} DEBUG: (http_tproxy) Marking accepted
connect on b328c6e8 as not outbound transparent.
[Jul 6 08:56:45.632] Server {3077691088} DEBUG: (http_seq)
HttpAccept:mainEvent] accepted connection
[Jul 6 08:56:45.632] Server {3077691088} DEBUG: (http_cs) [0] session born,
netvc 0xa431d60
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_cs) [0] using accept
inactivity timeout [120 seconds]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_cs) [0] Starting
transaction 1 using sm [0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [0]
[HttpSM::main_handler, VC_EVENT_READ_READY]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [0]
[&HttpSM::state_read_client_request_header, VC_EVENT_READ_READY]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [0] done parsing client
request header
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_trans) START
HttpTransact::ModifyRequest
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_trans)
[ink_cluster_time] local: 1309913805, highest_delta: 0, cluster: 1309913805
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_trans) END
HttpTransact::ModifyRequest
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_trans) Next action
HTTP_API_READ_REQUEST_HDR; HttpTransact::StartRemapRequest
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [0] State Transition:
STATE_UNDEFINED -> API_READ_REQUEST_HDR
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_trans) START
HttpTransact::StartRemapRequest
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http_trans) Before Remapping:
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) HTTP_HEADER 0xB563C060:
[T: 3, L: 32, OBJFLAGS: 0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [TYPE: REQ, V: 10001,
URL: 0xB563C208, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0xB563C080]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) URL 0xB563C208: [T: 2,
L: 72, OBJFLAGS: 0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [URLTYPE: 1, SWKSIDX:
94,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) SCHEME: "http",
SCHEME_LEN: 4,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) USER: "",
USER_LEN: 0,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) HOST:
"www.baidu.com", HOST_LEN: 13,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) PORT: "",
PORT_LEN: 0, PORT_NUM: 0
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) MIME_HEADER 0xB563C080:
[T: 4, L: 392, OBJFLAGS: 0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http)
[PBITS: 0x000801004108000F, SLACC: 0xFFFF3452FF8FFFFF7FFFFFFFFFF1FFFF,
HEADBLK: 0xB563C0B8, TAILBLK: 0xB563C0B8]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) FIELD_BLOCK 0xB563C0B8:
[T: 5, L: 332, OBJFLAGS: 0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [FREETOP: 9, NEXTBLK:
0x0]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) SLOT # 0
(0xB563C0C4), LIVE
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [N: "Host", N_LEN: 4,
N_IDX: 30,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) V: "www.baidu.com",
V_LEN: 13,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 21, F: 1]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) SLOT # 1
(0xB563C0D8), LIVE
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; rv:2.0.1) Gecko/20100101 Firefox/4.0.1", V_LEN: 67,
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 81, F: 1]
[Jul 6 08:56:45.633] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) SLOT # 2
(0xB563C0EC), LIVE
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) [N: "Accept", N_LEN: 6,
N_IDX: 4,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) V:
"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8", V_LEN: 63,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 73, F: 1]
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) SLOT # 3
(0xB563C100), LIVE
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) [N: "Accept-Language",
N_LEN: 15, N_IDX: 2,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) V: "zh-cn,zh;q=0.5",
V_LEN: 14,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 33, F: 1]
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) SLOT # 4
(0xB563C114), LIVE
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) [N: "Accept-Encoding",
N_LEN: 15, N_IDX: 1,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) V: "gzip, deflate",
V_LEN: 13,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 32, F: 1]
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) SLOT # 5
(0xB563C128), LIVE
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) [N: "Accept-Charset",
N_LEN: 14, N_IDX: 0,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) V:
"GB2312,utf-8;q=0.7,*;q=0.7", V_LEN: 26,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 44, F: 1]
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) SLOT # 6
(0xB563C13C), LIVE
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) [N: "Keep-Alive",
N_LEN: 10, N_IDX: 36,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) V: "115", V_LEN: 3,
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 17, F: 1]
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) SLOT # 7
(0xB563C150), LIVE
[Jul 6 08:56:45.634] Server {3077691088} DEBUG: (http) [N: "Proxy-Connection",
N_LEN: 16, N_IDX: 50,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) V: "keep-alive", V_LEN:
10,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 30, F: 1]
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) SLOT # 8
(0xB563C164), LIVE
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) [N: "Cookie", N_LEN: 6,
N_IDX: 22,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) V:
"BAIDUID=36C3F2905F6D124039D771A6F85E124A:FG=1", V_LEN: 45,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 55, F: 1]
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) END
HttpTransact::StartRemapRequest
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) Next action
HTTP_API_PRE_REMAP; HttpTransact::PerformRemap
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) [0] State Transition:
API_READ_REQUEST_HDR -> HTTP_API_PRE_REMAP
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) Inside
PerformRemap
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) Next action
HTTP_REMAP_REQUEST; HttpTransact::EndRemapRequest
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) [0] State Transition:
HTTP_API_PRE_REMAP -> HTTP_REMAP_REQUEST
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_seq)
[HttpSM::do_remap_request] Remapping request
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) START
HttpTransact::EndRemapRequest
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) EndRemapRequest
host is www.baidu.com
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http_trans) After Remapping:
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) HTTP_HEADER 0xB563C060:
[T: 3, L: 32, OBJFLAGS: 0]
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) [TYPE: REQ, V: 10001,
URL: 0xB563C208, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0xB563C080]
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) URL 0xB563C208: [T: 2,
L: 72, OBJFLAGS: 0]
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) [URLTYPE: 1, SWKSIDX:
94,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) SCHEME: "http",
SCHEME_LEN: 4,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) USER: "",
USER_LEN: 0,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) HOST:
"www.baidu.com", HOST_LEN: 13,
[Jul 6 08:56:45.635] Server {3077691088} DEBUG: (http) PORT: "",
PORT_LEN: 0, PORT_NUM: 0
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) MIME_HEADER 0xB563C080:
[T: 4, L: 392, OBJFLAGS: 0]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[PBITS: 0x000801004108000F, SLACC: 0xFFFF3452FF8FFFFF7FFFFFFFFFF1FFFF,
HEADBLK: 0xB563C0B8, TAILBLK: 0xB563C0B8]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) FIELD_BLOCK 0xB563C0B8:
[T: 5, L: 332, OBJFLAGS: 0]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [FREETOP: 9, NEXTBLK:
0x0]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 0
(0xB563C0C4), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Host", N_LEN: 4,
N_IDX: 30,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V: "www.baidu.com",
V_LEN: 13,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 21, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 1
(0xB563C0D8), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; rv:2.0.1) Gecko/20100101 Firefox/4.0.1", V_LEN: 67,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 81, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 2
(0xB563C0EC), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Accept", N_LEN: 6,
N_IDX: 4,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V:
"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8", V_LEN: 63,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 73, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 3
(0xB563C100), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Accept-Language",
N_LEN: 15, N_IDX: 2,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V: "zh-cn,zh;q=0.5",
V_LEN: 14,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 33, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 4
(0xB563C114), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Accept-Encoding",
N_LEN: 15, N_IDX: 1,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V: "gzip, deflate",
V_LEN: 13,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 32, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 5
(0xB563C128), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Accept-Charset",
N_LEN: 14, N_IDX: 0,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V:
"GB2312,utf-8;q=0.7,*;q=0.7", V_LEN: 26,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 44, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 6
(0xB563C13C), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Keep-Alive",
N_LEN: 10, N_IDX: 36,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) V: "115", V_LEN: 3,
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 17, F: 1]
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) SLOT # 7
(0xB563C150), LIVE
[Jul 6 08:56:45.636] Server {3077691088} DEBUG: (http) [N: "Proxy-Connection",
N_LEN: 16, N_IDX: 50,
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) V: "keep-alive", V_LEN:
10,
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 30, F: 1]
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) SLOT # 8
(0xB563C164), LIVE
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) [N: "Cookie", N_LEN: 6,
N_IDX: 22,
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) V:
"BAIDUID=36C3F2905F6D124039D771A6F85E124A:FG=1", V_LEN: 45,
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 55, F: 1]
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http_trans) END
HttpTransact::EndRemapRequest
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http_trans) Next action
HTTP_API_POST_REMAP; HttpTransact::HandleRequest
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http) [0] State Transition:
HTTP_REMAP_REQUEST -> HTTP_API_POST_REMAP
[Jul 6 08:56:45.644] Server {3077691088} DEBUG: (http_trans) START
HttpTransact::HandleRequest
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http_trans)
[is_request_valid]no request header errors
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http_seq)
[HttpTransact::HandleRequest] request valid.
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) HTTP_HEADER 0xB563C060:
[T: 3, L: 32, OBJFLAGS: 0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [TYPE: REQ, V: 10001,
URL: 0xB563C208, METHOD: "GET", METHOD_LEN: 3, FIELDS: 0xB563C080]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) URL 0xB563C208: [T: 2,
L: 72, OBJFLAGS: 0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [URLTYPE: 1, SWKSIDX:
94,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SCHEME: "http",
SCHEME_LEN: 4,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) USER: "",
USER_LEN: 0,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) PASSWORD: "",
PASSWORD_LEN: 0,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) HOST:
"www.baidu.com", HOST_LEN: 13,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) PORT: "",
PORT_LEN: 0, PORT_NUM: 0
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) PATH: "",
PATH_LEN: 0,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) PARAMS: "",
PARAMS_LEN: 0,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) QUERY: "",
QUERY_LEN: 0,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) FRAGMENT: "",
FRAGMENT_LEN: 0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) MIME_HEADER 0xB563C080:
[T: 4, L: 392, OBJFLAGS: 0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[PBITS: 0x000801004108000F, SLACC: 0xFFFF3452FF8FFFFF7FFFFFFFFFF1FFFF,
HEADBLK: 0xB563C0B8, TAILBLK: 0xB563C0B8]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [CBITS:
0x00000000, T_MAXAGE: 0, T_SMAXAGE: 0, T_MAXSTALE: 0, T_MINFRESH: 0, PNO$: 0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) FIELD_BLOCK 0xB563C0B8:
[T: 5, L: 332, OBJFLAGS: 0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [FREETOP: 9, NEXTBLK:
0x0]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 0
(0xB563C0C4), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Host", N_LEN: 4,
N_IDX: 30,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V: "www.baidu.com",
V_LEN: 13,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 21, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 1
(0xB563C0D8), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "User-Agent",
N_LEN: 10, N_IDX: 64,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V: "Mozilla/5.0
(Windows NT 6.1; rv:2.0.1) Gecko/20100101 Firefox/4.0.1", V_LEN: 67,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 81, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 2
(0xB563C0EC), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Accept", N_LEN: 6,
N_IDX: 4,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V:
"text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8", V_LEN: 63,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 73, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 3
(0xB563C100), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Accept-Language",
N_LEN: 15, N_IDX: 2,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V: "zh-cn,zh;q=0.5",
V_LEN: 14,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 33, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 4
(0xB563C114), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Accept-Encoding",
N_LEN: 15, N_IDX: 1,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V: "gzip, deflate",
V_LEN: 13,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 32, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 5
(0xB563C128), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Accept-Charset",
N_LEN: 14, N_IDX: 0,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V:
"GB2312,utf-8;q=0.7,*;q=0.7", V_LEN: 26,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 44, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 6
(0xB563C13C), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Keep-Alive",
N_LEN: 10, N_IDX: 36,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V: "115", V_LEN: 3,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 17, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 7
(0xB563C150), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Proxy-Connection",
N_LEN: 16, N_IDX: 50,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) V: "keep-alive", V_LEN:
10,
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 30, F: 1]
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http)
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) SLOT # 8
(0xB563C164), LIVE
[Jul 6 08:56:45.645] Server {3077691088} DEBUG: (http) [N: "Cookie", N_LEN: 6,
N_IDX: 22,
[Jul 6 08:56:45.653] Server {3077691088} DEBUG: (http) V:
"BAIDUID=36C3F2905F6D124039D771A6F85E124A:FG=1", V_LEN: 45,
[Jul 6 08:56:45.653] Server {3077691088} DEBUG: (http) NEXTDUP: 0x0, RAW: 1,
RAWLEN: 55, F: 1]
[Jul 6 08:56:45.653] Server {3077691088} DEBUG: (http)
+++++++++ Incoming Request +++++++++
-- State Machine Id: 0
GET http://www.baidu.com/ HTTP/1.1
Host: www.baidu.com
User-Agent: Mozilla/5.0 (Windows NT 6.1; rv:2.0.1) Gecko/20100101 Firefox/4.0.1
Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
Accept-Language: zh-cn,zh;q=0.5
Accept-Encoding: gzip, deflate
Accept-Charset: GB2312,utf-8;q=0.7,*;q=0.7
Keep-Alive: 115
Proxy-Connection: keep-alive
Cookie: BAIDUID=36C3F2905F6D124039D771A6F85E124A:FG=1
[Jul 6 08:56:45.653] Server {3077691088} DEBUG: (http_trans)
[DecideCacheLookup] Will do cache lookup.
[Jul 6 08:56:45.653] Server {3077691088} DEBUG: (http_seq) [DecideCacheLookup]
Will do cache lookup
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans) Next action
CACHE_LOOKUP; NULL
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0] State Transition:
HTTP_API_POST_REMAP -> CACHE_LOOKUP
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_seq)
[HttpSM::do_cache_lookup_and_read] [0] Issuing cache lookup for URL
http://www.baidu.com/
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_cache) [0]
[&HttpCacheSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0]
[HttpSM::main_handler, CACHE_EVENT_OPEN_READ_FAILED]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0]
[&HttpSM::state_cache_open_read, CACHE_EVENT_OPEN_READ_FAILED]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0] cache_open_read -
CACHE_EVENT_OPEN_READ_FAILED
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [state_cache_open_read]
open read failed.
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans)
[HttpTransact::HandleCacheOpenRead]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans) CacheOpenRead --
miss
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans) Next action
DNS_LOOKUP; OSDNSLookup
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0] State Transition:
CACHE_LOOKUP -> DNS_LOOKUP
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_seq)
[HttpStateMachineGet::do_hostdb_lookup] Doing DNS Lookup
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0] DNS lookup failed
for 'www.baidu.com'
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans)
[HttpTransact::OSDNSLookup] This was attempt 1
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_seq)
[HttpTransact::OSDNSLookup] DNS Lookup unsuccessful
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans) [WUTS code
generation] Hit/Miss: 49, Log: 117, Hier: 49, Status: 805
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans) Adding Server:
ATS/3.0.0
+++++++++ Proxy's Response 2 +++++++++
-- State Machine Id: 0
HTTP/1.1 502 Cannot find server.
Date: Wed, 06 Jul 2011 00:56:45 GMT
Proxy-Connection: keep-alive
Server: ATS/3.0.0
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_trans) Next action
PROXY_SEND_ERROR_CACHE_NOOP; NULL
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0] State Transition:
DNS_LOOKUP -> PROXY_SEND_ERROR_CACHE_NOOP
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_tunnel) [0] adding
producer 'internal msg'
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_tunnel) [0] adding
consumer 'user agent'
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_tunnel) tunnel_run
started, p_arg is NULL
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_tunnel) [0]
consumer_handler [user agent VC_EVENT_WRITE_COMPLETE]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0]
[&HttpSM::tunnel_handler_ua, VC_EVENT_WRITE_COMPLETE]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_cs) [0] session released
by sm [0]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_cs) [0] initiating io
for next header
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0]
[HttpSM::main_handler, HTTP_TUNNEL_EVENT_DONE]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0]
[&HttpSM::tunnel_handler, HTTP_TUNNEL_EVENT_DONE]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_redirect)
[HttpTunnel::deallocate_postdata_copy_buffers]
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http_seq)
[HttpStateMachineGet::update_stats] Logging transaction
[Jul 6 08:56:45.654] Server {3077691088} DEBUG: (http) [0] dellocating sm
ps: ATS 2.1.4 works perfect on the above evironment!
--
This message is automatically generated by JIRA.
For more information on JIRA, see: http://www.atlassian.com/software/jira
