You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@trafficserver.apache.org by "taoyunxing (JIRA)" <ji...@apache.org> on 2011/07/08 09:37:16 UTC
[jira] [Created] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
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
[jira] [Commented] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
Posted by "Leif Hedstrom (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/TS-872?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13062276#comment-13062276 ]
Leif Hedstrom commented on TS-872:
----------------------------------
This is the relevant section from your logs:
{code}
[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.
{code}
This looks like two potential problems, one might be permissions, but the other one definitely is that you are using records.config from an old version of ATS (I'm fairly certain at least). Try that, if that fixes it, please comment here and we'll close this.
> 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
> Labels: patch
> Fix For: sometime
>
> Original Estimate: 48h
> Remaining Estimate: 48h
>
> 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
[jira] [Commented] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
Posted by "taoyunxing (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/TS-872?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13064514#comment-13064514 ]
taoyunxing commented on TS-872:
-------------------------------
I make a fresh operation including config,make and install, and a little revise in records.config as forward proxy, then open the web pages successfully. Maybe I make some mistakes last time. thanks Leif, the ATS 3.0 is good! this is not a bug!
> 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
> Labels: patch
> Fix For: sometime
>
> Original Estimate: 48h
> Remaining Estimate: 48h
>
> 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
[jira] [Commented] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
Posted by "weijin (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/TS-872?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13061847#comment-13061847 ]
weijin commented on TS-872:
---------------------------
my system, ff5.0, ats lastest version, no problem.
> 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
> Labels: patch
> Fix For: sometime
>
> Original Estimate: 48h
> Remaining Estimate: 48h
>
> 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
[jira] [Commented] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
Posted by "Leif Hedstrom (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/TS-872?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13062275#comment-13062275 ]
Leif Hedstrom commented on TS-872:
----------------------------------
You need to nuke your hostdb, and/or update your records.config to the one that ships with 3.0. See the Notes: on http://trafficserver.apache.org/downloads.html for v3.0.0
> 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
> Labels: patch
> Fix For: sometime
>
> Original Estimate: 48h
> Remaining Estimate: 48h
>
> 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
[jira] [Updated] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
Posted by "Leif Hedstrom (Updated) (JIRA)" <ji...@apache.org>.
[ https://issues.apache.org/jira/browse/TS-872?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Leif Hedstrom updated TS-872:
-----------------------------
Fix Version/s: (was: sometime)
> 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
> Labels: patch
> Original Estimate: 48h
> Remaining Estimate: 48h
>
> 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.
If you think it was sent incorrectly, please contact your JIRA administrators: https://issues.apache.org/jira/secure/ContactAdministrators!default.jspa
For more information on JIRA, see: http://www.atlassian.com/software/jira