[
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
