error.log

gistfile1.txt

2012/05/12 22:03:41 [debug] 4522#0: bind() 0.0.0.0:1984 #6 
2012/05/12 22:03:41 [notice] 4522#0: using the "poll" event method
2012/05/12 22:03:41 [notice] 4522#0: nginx/1.0.15
2012/05/12 22:03:41 [notice] 4522#0: built by gcc 4.4.3 (GCC) 
2012/05/12 22:03:41 [notice] 4522#0: OS: Linux 2.6.33.1
2012/05/12 22:03:41 [notice] 4522#0: sysctl(KERN_RTSIGMAX): 0
2012/05/12 22:03:41 [notice] 4522#0: getrlimit(RLIMIT_NOFILE): 119200:119200
2012/05/12 22:03:41 [debug] 4523#0: write: 7, 00007FFF65179D10, 5, 0
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072F3B0:32
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000710F50:24
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000723F80:512
2012/05/12 22:03:41 [debug] 4523#0: malloc: 00007F1410F62010:953600
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000717E90:12288
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000732EA0:6656
2012/05/12 22:03:41 [debug] 4523#0: malloc: 00000000007348B0:6656
2012/05/12 22:03:41 [debug] 4523#0: poll add event: fd:6 ev:1
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: -1
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 1
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0001
2012/05/12 22:03:41 [debug] 4523#0: post event 0000000000732EA0
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 108
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000732EA0
2012/05/12 22:03:41 [debug] 4523#0: delete posted event 0000000000732EA0
2012/05/12 22:03:41 [debug] 4523#0: accept on 0.0.0.0:1984, ready: 0
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000710F10:56
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000730060:24
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072F6B0:16
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072F360:24
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000713A10:48
2012/05/12 22:03:41 [debug] 4523#0: malloc: 0000000000713A50:24
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072F380:15
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072B4E0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 accept: 127.0.0.1 fd:3
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072B500:24
2012/05/12 22:03:41 [debug] 4523#0: malloc: 000000000072B520:24
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer add: 3: 60000:1336831481416
2012/05/12 22:03:41 [debug] 4523#0: *1 poll add event: fd:3 ev:1
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 60000
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0001 rev:0001
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000732F08
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000732F08
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000732F08
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000732F08
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000724190:48
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 00000000007241D0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000071AEA0:1272
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 00000000007241F0:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072F1D0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072F1F0:80
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072F250:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000730570:1024
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E6D0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E6F0:56
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E730:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072BBA0:960
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E750:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E130:352
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E2A0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072CCC0:96
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072CD30:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http process request line
2012/05/12 22:03:41 [debug] 4523#0: *1 recv: fd:3 106 of 1024
2012/05/12 22:03:41 [debug] 4523#0: *1 http request line: "GET / HTTP/1.1"
2012/05/12 22:03:41 [debug] 4523#0: *1 http uri: "/"
2012/05/12 22:03:41 [debug] 4523#0: *1 http args: ""
2012/05/12 22:03:41 [debug] 4523#0: *1 http exten: ""
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723A60:960
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072CD50:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723E30:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723E50:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http process request header line
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723E70:4
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723E90:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723EB0:9
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723ED0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http header: "Host: localhost"
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723EF0:10
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723F10:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http header: "Connection: Close"
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723F30:19
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000723F50:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http header: "If-Unmodified-Since: Thu, 10 May 2012 07:50:47 GMT"
2012/05/12 22:03:41 [debug] 4523#0: *1 http header done
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer del: 3: 1336831481416
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 2
2012/05/12 22:03:41 [debug] 4523#0: *1 test location: "/"
2012/05/12 22:03:41 [debug] 4523#0: *1 using configuration "/"
2012/05/12 22:03:41 [debug] 4523#0: *1 http cl:-1 max:31457280
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 4
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 5
2012/05/12 22:03:41 [debug] 4523#0: *1 post rewrite phase: 6
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 7
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 8
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 9
2012/05/12 22:03:41 [debug] 4523#0: *1 access phase: 10
2012/05/12 22:03:41 [debug] 4523#0: *1 access phase: 11
2012/05/12 22:03:41 [debug] 4523#0: *1 post access phase: 12
2012/05/12 22:03:41 [debug] 4523#0: *1 content phase: 13
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072E2C0:61
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072BF70:24
2012/05/12 22:03:41 [debug] 4523#0: *1 open index "/home/agentz/git/lua-nginx-module/t/servroot/html/index.html"
2012/05/12 22:03:41 [debug] 4523#0: *1 internal redirect: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 2
2012/05/12 22:03:41 [debug] 4523#0: *1 test location: "/"
2012/05/12 22:03:41 [debug] 4523#0: *1 test location: "lua"
2012/05/12 22:03:41 [debug] 4523#0: *1 using configuration "/"
2012/05/12 22:03:41 [debug] 4523#0: *1 http cl:-1 max:31457280
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 4
2012/05/12 22:03:41 [debug] 4523#0: *1 rewrite phase: 5
2012/05/12 22:03:41 [debug] 4523#0: *1 post rewrite phase: 6
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 7
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 8
2012/05/12 22:03:41 [debug] 4523#0: *1 generic phase: 9
2012/05/12 22:03:41 [debug] 4523#0: *1 access phase: 10
2012/05/12 22:03:41 [debug] 4523#0: *1 access phase: 11
2012/05/12 22:03:41 [debug] 4523#0: *1 post access phase: 12
2012/05/12 22:03:41 [debug] 4523#0: *1 content phase: 13
2012/05/12 22:03:41 [debug] 4523#0: *1 content phase: 14
2012/05/12 22:03:41 [debug] 4523#0: *1 content phase: 15
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C550:61
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C5A0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http filename: "/home/agentz/git/lua-nginx-module/t/servroot/html/index.html"
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072BF90:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072BFB0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C5C0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C5E0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 add cleanup: 000000000072BF90
2012/05/12 22:03:41 [debug] 4523#0: *1 http static fd: 7
2012/05/12 22:03:41 [debug] 4523#0: *1 http set discard body
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000725290:80
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 00000000007252F0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000071BB40:200
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000725310:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http iums:1336636247 lm:1336831421
2012/05/12 22:03:41 [debug] 4523#0: *1 http special response: 412, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http set discard body
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000715A90:80
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000715AF0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 00000000007298B0:193
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 0000000000715B10:24
2012/05/12 22:03:41 [debug] 4523#0: *1 HTTP/1.1 412 Precondition Failed
Server: nginx/1.0.15 (without pool)
Date: Sat, 12 May 2012 14:03:41 GMT
Content-Type: text/html
Content-Length: 204
Connection: close

2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DBF0:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DC10:24
2012/05/12 22:03:41 [debug] 4523#0: *1 write new buf t:1 f:0 00000000007298B0, pos 00000000007298B0, size: 175 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:0 f:0 s:175
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to signal EAGAIN.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev() not ready (11: Resource temporarily unavailable)
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DC30:80
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DC90:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DCB0:80
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DD10:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DD30:104
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072DDA0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D760:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D780:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D7A0:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D7C0:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 000000000072D760
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B0, size: 175 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D7E0:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D800:24
2012/05/12 22:03:41 [debug] 4523#0: *1 write new buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D820:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D840:24
2012/05/12 22:03:41 [debug] 4523#0: *1 write new buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:379
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D860:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D880:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D8A0:80
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D900:24
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C2D0:72
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072D920:24
2012/05/12 22:03:41 [debug] 4523#0: *1 read: 7, 000000000072C2D0, 72, 0
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C320:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C340:24
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 000000000072C320
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B0, size: 175 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C360:16
2012/05/12 22:03:41 [debug] 4523#0: *1 malloc: 000000000072C380:24
2012/05/12 22:03:41 [debug] 4523#0: *1 write new buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:451
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http finalize request: -2, "/index.html?" a:1, c:2
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer add: 3: 60000:1336831481417
2012/05/12 22:03:41 [debug] 4523#0: *1 poll add event: fd:3 ev:4
2012/05/12 22:03:41 [debug] 4523#0: *1 poll add index: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http finalize request: -4, "/index.html?" a:1, c:2
2012/05/12 22:03:41 [debug] 4523#0: *1 http request count:2 blk:0
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 60000
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B0, size: 175 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:451
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 451 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481418
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59999
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B1, size: 174 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:450
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 450 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481419
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59998
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B2, size: 173 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:449
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 449 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481419
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59998
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B3, size: 172 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:448
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 448 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481419
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59998
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B4, size: 171 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:447
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 447 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481419
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59998
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B5, size: 170 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:446
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 446 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481419
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59998
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B6, size: 169 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:445
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 445 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481420
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59997
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B7, size: 168 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:444
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 444 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481420
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59997
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B8, size: 167 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:443
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 443 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481420
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59997
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298B9, size: 166 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:442
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 442 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481420
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59997
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298BA, size: 165 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:441
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 441 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481421
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59996
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298BB, size: 164 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:440
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 440 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481421
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59996
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298BC, size: 163 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:439
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 439 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481421
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59996
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298BD, size: 162 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:438
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 438 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481421
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59996
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298BE, size: 161 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:437
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 437 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481421
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59996
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298BF, size: 160 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:436
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 436 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481422
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59995
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298C0, size: 159 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:435
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 435 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481422
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59995
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298C1, size: 158 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:434
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 434 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481422
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59995
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298C2, size: 157 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:433
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 433 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481422
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59995
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 1
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298C3, size: 156 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:432
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter limit 0
mockeagain: mocking "writev" on fd 3 to emit 1 of 432 bytes.
2012/05/12 22:03:41 [debug] 4523#0: *1 writev: 1
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter 000000000072DBF0
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: -2 "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer output filter: -2, "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 event timer: 3, old: 1336831481417, new: 1336831481423
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: worker cycle
2012/05/12 22:03:41 [debug] 4523#0: poll timer: 59994
2012/05/12 22:03:41 [debug] 4523#0: poll ready 1 of 2
2012/05/12 22:03:41 [debug] 4523#0: poll: 0: fd:6 ev:0001 rev:0000
2012/05/12 22:03:41 [debug] 4523#0: poll: 1: fd:3 ev:0005 rev:0004
2012/05/12 22:03:41 [debug] 4523#0: *1 post event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: timer delta: 0
2012/05/12 22:03:41 [debug] 4523#0: posted events 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 delete posted event 0000000000734918
2012/05/12 22:03:41 [debug] 4523#0: *1 http run request: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http writer handler: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http output filter "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http copy filter: "/index.html?"
2012/05/12 22:03:41 [debug] 4523#0: *1 http postpone filter "/index.html?" 0000000000000000
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 00000000007298B0, pos 00000000007298C4, size: 155 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DB420, size: 136 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:0 f:0 0000000000000000, pos 00000000006DA9A0, size: 68 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 write old buf t:1 f:0 000000000072C2D0, pos 000000000072C2D0, size: 72 file: 0, size: 0
2012/05/12 22:03:41 [debug] 4523#0: *1 http write filter: l:1 f:0 s:431