You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@trafficserver.apache.org by "Leif Hedstrom (Updated) (JIRA)" <ji...@apache.org> on 2012/03/07 05:25:58 UTC
[jira] [Updated] (TS-872) ATS 3.0 shows a http 502 error as a forward proxy server ！
[ 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