1 # -*- encoding: binary -*-
4 # Not at all optimized for performance, this was written based on
5 # the original C extension code so it's not very Ruby-ish...
8 attr_accessor :env, :status, :headers, :body
9 attr_writer :body_bytes_sent, :start
11 def initialize(app, opts = {})
12 # trigger autoload to avoid thread-safety issues later on
13 Rack::Utils::HeaderHash
16 @logger = opts[:logger]
19 raise ArgumentError, ":logger and :path are independent"
20 path and @logger = File.open(path, "ab")
22 @logger.sync = true if @logger.respond_to?(:sync=)
23 @fmt_ops = compile_format(opts[:format] || Format::Common, opts)
24 @wrap_body = need_wrap_body?(@fmt_ops)
25 @reentrant = opts[:reentrant]
26 @need_resp = need_response_headers?(@fmt_ops)
33 unless resp.instance_of?(Array) && resp.size == 3
34 log(env, 500, {}, start)
35 raise TypeError, "app response not a 3 element Array: #{resp.inspect}"
37 status, headers, body = resp
38 headers = Rack::Utils::HeaderHash.new(headers) if @need_resp
40 @reentrant = env['rack.multithread'] if @reentrant.nil?
41 wbody = @reentrant ? self.dup : self
45 wbody.headers = headers
47 return [ status, headers, wbody ]
49 log(env, status, headers, start)
50 [ status, headers, body ]
56 @body_bytes_sent += Rack::Utils.bytesize(part)
63 @body.close if @body.respond_to?(:close)
65 log(@env, @status, @headers)
77 @logger.respond_to?(:fileno) ? @logger.fileno : nil
81 :close == m.to_sym || @body.respond_to?(m)
86 @body_bytes_sent = File.size(rv)
94 s.force_encoding(Encoding::BINARY) if defined?(Encoding::BINARY)
95 s.gsub!(/(['"\x00-\x1f\x7f-\xff])/) do |x|
96 "\\x#{$1.unpack('H2').first.upcase}"
101 SPECIAL_RMAP = SPECIAL_VARS.inject([]) { |ary, (k,v)| ary[v] = k; ary }
104 ru = env['REQUEST_URI'] and return byte_xs(ru)
105 qs = env['QUERY_STRING']
106 qs.empty? or qs = "?#{byte_xs(qs)}"
107 "#{byte_xs(env['PATH_INFO'])}#{qs}"
110 def special_var(special_nr, env, status, headers)
111 case SPECIAL_RMAP[special_nr]
112 when :body_bytes_sent
113 @body_bytes_sent.to_s
116 status >= 100 && status <= 999 ? ('%03d' % status) : '-'
118 version = env['HTTP_VERSION'] and version = " #{byte_xs(version)}"
119 qs = env['QUERY_STRING']
120 qs.empty? or qs = "?#{byte_xs(qs)}"
121 "#{env['REQUEST_METHOD']} " \
122 "#{request_uri(env)}#{version}"
126 env['rack.input'].size.to_s
127 when :response_length
128 @body_bytes_sent == 0 ? '-' : @body_bytes_sent.to_s
130 xff = env['HTTP_X_FORWARDED_FOR'] and return byte_xs(xff)
131 env['REMOTE_ADDR'] || '-'
139 sign = off < 0 ? '-' : '+'
140 sprintf("%02d/%s/%d:%02d:%02d:%02d #{sign}%02d%02d",
141 t.mday, Time::RFC2822_MONTH_NAME[t.mon - 1],
142 t.year, t.hour, t.min, t.sec, *(off.abs / 60).divmod(60))
145 sprintf("%02d/%s/%d:%02d:%02d:%02d +0000",
146 t.mday, Time::RFC2822_MONTH_NAME[t.mon - 1],
147 t.year, t.hour, t.min, t.sec)
149 raise "EDOOFUS #{special_nr}"
153 def time_format(sec, usec, format, div)
154 format % [ sec, usec / div ]
157 def log(env, status, headers, start = @start)
158 str = @fmt_ops.map { |op|
160 when OP_LITERAL; op[1]
161 when OP_REQUEST; byte_xs(env[op[1]] || "-")
162 when OP_RESPONSE; byte_xs(headers[op[1]] || "-")
163 when OP_SPECIAL; special_var(op[1], env, status, headers)
164 when OP_EVAL; eval(op[1]).to_s rescue "-"
165 when OP_TIME_LOCAL; Time.now.strftime(op[1])
166 when OP_TIME_UTC; Time.now.utc.strftime(op[1])
169 time_format(t.to_i, (t - t.to_i) * 1000000, op[1], op[2])
172 time_format(t.to_i, t.usec, op[1], op[2])
174 (byte_xs(env['rack.request.cookie_hash'][op[1]]) rescue "-") || "-"
176 raise "EDOOFUS #{op.inspect}"
184 env['rack.errors'].write(str)