[ 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