Perlbal reproxied downloads stalling

Sam G ceptorial at comcast.net
Fri Sep 15 01:39:54 UTC 2006


This is long, but you may want to scroll past the strace output onto the  
Syscall stuff.

On Thu, 14 Sep 2006 12:13:19 -0700, Brad Fitzpatrick <brad at danga.com>  
wrote:

> Sam,
>
> When the download stalls, is the Perlbal consuming near 100% CPU as well?

Nope; it doesn't appear to be taking any.

>
> It looks like it could be.  The ClientProxy is in RW state (waiting for
> readability and writability), and it should be writable non-stop when
> it's stalled.
>
> In ClientProxy.pm, find this part:
>
> sub as_string {
>     my Perlbal::ClientProxy $self = shift;
>
>     my $ret = $self->SUPER::as_string;
>     if ($self->{backend}) {
>         my $ipport = $self->{backend}->{ipport};
>         $ret .= "; backend=$ipport";
>     } else {
>         $ret .= "; write_buf_size=$self->{write_buf_size}"
>             if $self->{write_buf_size} > 0;
>     }
>
>
> And take the write_buf_size lines out of the else block. (not sure why we
> only showed it mutually exclusive with having a backend....)
>
> Also add something like:
>
>     $ret .= "; stalled=$self->{backend_stalled}";

Here is what I have changed it to:

sub as_string {
     my Perlbal::ClientProxy $self = shift;

     my $ret = $self->SUPER::as_string;
     if ($self->{backend}) {
         my $ipport = $self->{backend}->{ipport};
         $ret .= "; backend=$ipport";
     }

     $ret .= "; write_buf_size=$self->{write_buf_size}"
         if $self->{write_buf_size} > 0;
     $ret .= "; stalled=$self->{backend_stalled}";

     $ret .= "; highpri" if $self->{high_priority};
     $ret .= "; lowpri" if $self->{low_priority};
     ...

>
> And can you get me an strace of the Perlbal while it's stalled?

Okay, this is a big chunk, but I'm not too sure which part you might want:

read(14,  
"\306\341=\7#(\320\274\354\37r\226\37\221\352l\376!\347\220\210\265\374\364\0279-\223\234\277\310qT\340\26396E\0264B{%\32\217\356z{C\256_6\241\n\t\215^\'\ffn\366\3423\202/\315?:\2602\363\277\27\240\374\205\261`\363\246\357\313~u\264  
\222\323[M4\3ig\203\361\5\352f0\251\0L\307I\312\261\212\t\23\361\24\30\374\360ej\2545lCJJ\300\30\241\211\257\363A\264|)\202(V\353\3373&\260E\347\313\207\371\332\370\270\344\370\356z\257g\233\271\26\177\243B\32.\240\f\220\226\216\276\212\327\364P\360\376\275o]\314\223\211@\260\255\345\256\354\r\376\225W\371\235\263\232\324\343>\240e\223\366\375P\250\315\"\263\322\237\236n$\205\21\205\353I\22:\326\241\262\343\3748s\264\260\221\244\27\307CM\326\217\31V\345\271\332w\335\0029\237\21\210\341\232\303\216?\301\10\4\342\220\315\261\201\362\260\213\265cM\211\f\321u\211\360\317F\373\222\335\1\300\313u\27\2T\213L\26\205\227l\323_\207\3277\363zB\344\254L\302\215\4\212\347\26\300\314)\345\30\303\27\313>U\3124\230\245\270\324S\7\263\227\335\261\362~\373\33\350\305"...,  
61449) = 7165
time(NULL)                              = 1158283301
gettimeofday({1158283301, 60893}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\31&\20\250a\206H/_l\377\177)\20Q`y5\370\206\353\323XB\261\262\372\333\360\315~\224\34\355}\244\363\360\7\22\374Y\202C\321\324\"E;\326E\253:\223\364S\315\363\307!*\27&\n\274rE\214p\212\351o6\372\251\214\375\364x\201\241\364\252\364\350t\264\r.\364Q\v\375c\221\271\366\256)\372\235E\332`\312(\264\36\343$\317\316\"\205\215+\342\5\224v%\337\34U\305\372oh\360\201\2A\205\303\345\316\323\346\331\210\272\277\244zb\321\302k\256\177\223\376\325rR\1)\331\5\215\344\2370p\366z\16\261\3750\32\23\210x\335\204\rK\370`\303\243\"M\273\246w\253\346f\373@\3260\305o\1\3067\vQ\331q\267V\'\32F2\233:>\337\360\274B\341\215$\307R\300\317~\367\f\310\346\\z\333\254>^\'f\267%\353W_\222\216\vo|\345\332<m\20z\25\366\265\335\334>\354\311\237\343Z\'C\317OI\17#KO;\314\305)\347\310\255\25\325\370O\312\325\177R\2161#\0249\327\25\250\231>\346\254@}RXkt\343Hym\331\365\314\346\354x\4  
\214\36\232\365\374\2513\35\335\330\305\256-\n\211\1\33\213\245\234\233\337\275\177\373\337\tMS\275\2451dq\233H\2445\2c\v\220Zz\240y"...,  
61449) = 5792
time(NULL)                              = 1158283301
gettimeofday({1158283301, 61201}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\363o\266p\23\355\7\201\340u\17v\345\365\27=6\327\t\225v,\224P\271\364V\237l,\255h\215\217\24.\231T\350.Pe\0\312\03392r\321CK\213\334\241\30\21R\345e\223\325wPA\320\27\362A\215\251\222\237>\"\336\305\227d\302AY\231)#\275~\305W\10Y\300\n\"\32^\277*W\177\257y\330\362\203!\265\256\371\305\315\246mc\373$.!\255\'\7z\2304\\o\34\354vvS\17\226\34\250\24\203\237\300e\204\303;a\244\320epaz\370\207\\$\24\f\207\261\226\34\277\267Ux\24!\275\215\fH\35!\320T\376\370\34LRsf\235\226\337\4\201\214R_\251@\276{\336\205y\322AS\16\370\267\227+i\220\20\31SsVp\327Q\227$\244}fO\367\314\213p~\233\373\204\327\341\313&\342\27=\250U.\3228\27\233\221f\343g\231Rv=\204\334\24\232\247\306=Z\354n\235\342e_\233\271\262\341\316\267\365\317\217i\345\262\32ly\2032u\33|\314\376(\273-X\2079\343\211cJ\352]s8,\276\307e\26\314^&i\371{\304l\355\262\2701\'x\6\225\361(\336\343Y\365\177\327\267\201|{O\203\274\372\325\344B\6\357\224r\365go\27\3179\23z\274\277>  
\304\337 \343\310.\334\245\362\324\326\266\300\n\3260F\200JJ`~\5"...,  
61449) = 7165
time(NULL)                              = 1158283301
gettimeofday({1158283301, 61511}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"hv?\21\375\5`\360\25\\\25\\\f\362?\177\350\371\275\352M\343\341\26\205<\334Y9\314\376\326\f\340\235\217\353\'\36\33G\324\352\244/(\340\326\37w\345\205B?/-\276\253\252\227vOJ\270aG\253J\365~\262\323\310s+=frM\367\364\310l\v\314\20\267\337k\320*|&\217\236\204Ed\323G\211\315\302\35\0270\236\241\310Q\374=\262\36\331\"Y\0=\267N0\345)\204\376\242\32ZQ\2460l^\351\3076\25\f\r\317\270\216\33\350\216\327\34\361\\Y\261d\17\21\342\302\373\361\223\376\27\275D\271\272\205\346M\301\342YD\6\342O\332\333\337b\242\371\2754J\361&\10\255s\362fo<G\214/\213\215\3041\311\215>D\\\274X\270\243\241K\26\312\371f\350\24\211\324\260\212\223xO\201\"\31\345f\201\327N\361\3328\24\3\273\336\360\16\362x$D\301\363/Y)\220`\226b\210\222\334\f\227\354.\322A4\344\276\5j\234IG\'\333\312\366\251\216\342\277\26j/\215\n0\224\325\276\346i\366\21\211\206\v\205\217\231ms\24\321T1^\372>\236\352[\220-[\211\257l\232t\356y{\27\264\255iz\343\342,\353E-\347A\233\364\353`\214Pk]K\36\223\tA\36\324\360\205\321\270\343HP  
\311(J1;\265"..., 61449) = 5717
time(NULL)                              = 1158283301
gettimeofday({1158283301, 61843}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
epoll_ctl(5, EPOLL_CTL_MOD, 14, {EPOLLERR|EPOLLHUP|0x20, {u32=14,  
u64=14}}) = 0
time(NULL)                              = 1158283301
gettimeofday({1158283301, 62018}, NULL) = 0
epoll_wait(5, {{EPOLLOUT, {u32=11, u64=11}}}, 1000, 1000) = 1
time(NULL)                              = 1158283301
write(11,  
"En\305\212\203J\2522\2631f\320\365\31\267\354\275\24\371\337a%\226,T(2\305\355\'\246/  
\26I\334\267\37ar\305\337\314\362\312qSS7A\27\362P\233\234\207L>\30\255\35\323\262\3!\334\21\5\363\367g\234\177\247\3-\353\222\22\217\265\322\2064*\271d\276{-\26\32\202y\236\253\371\346c|\231\260\351C\355\263\324\352\360\341?\377*\267l\274xL\344d\37w\354\270\372Z\261\342\214\10\233V\275*\'\\\356\304\34Q\332|\274{I1-\326j\17\f\326qI/\336^\247\232\4F=\215+\362\343\203-\237cWNh\207\25\332\240Kd\255=\320\236\332\3267\6\324\23003T\376p\266\261\34483\213\37\205\365\220|\371\32\2229\235j_\\~\24\322\211!a\221\277\241j\22\213\217\300\27\331{\370\2=v\315\267,\347S\301\246\274wM\32\177\224G\2615\r\376\v=\212\346]\356\'Ar\26\251\30|\200(\222W\311\247ox\23\347N\336i\2521\32r\370\333D5\325\7\2431\3239\337X\235\320\354\206\360\226\256M\317T\342d\364\267<b>\274\325#\r\r\222e\254\25\355Jx\30U\376\347n\344\361\301JI\330\21\355i\21  
R\326\32n`\366\315j\f\261\361\323\263\315\217\223yMbT*\322^5\246\305\362\366WC\237"...,  
5611) = 5611
write(11,  
"\346~\325\273\2667\336}\311\2723{\251O\210sL+\343\335\35J\240\253\243\34\311=ZkJw\215\212\340\7\224$\257\305\33\323\305\v\344\212\251\231k\375\263\\\16\1A_w\0333\320\271\200e4O\374\f\224\354\256\363\232XA\35\0244,Mw\325>\322\203t\254u\17c\251\315?\274\266\"gK\224\213\325\\tM><\240\254_\'I\335q\253\264\343$[Y\310\24Ya\344\4\273\0176\2526\313$\320\353H\26I%\236{\354\211]8\211\352m\32\315\2506\331\3\3\16G0\nh\310\355\224x\270\231\345\362\320\33\317\370D\345wm&3\346u\31\202h\5\253\342\370#[88\312\255v\253\313\207\324\376T\20627\363>\267\217\220Jp\256\34\263\224\222?;\31\335\303  
0\354\304\r\257\312\313\2745\20Tr;\223\221\361\3671\326\334\336\320\324\370l.\223j\37P\367+\f\277cwA\340\16e\367\f\226!\315\24\200oX\33Y#YX\354\327\37\343\374\226~8\r\375s\325\224.\245C\241U\246!\227H\16\366Y\313\3748\373\314l\21\371\350B{\177\351\243\5\212\275\234\200\303\310\373%\326\257\223\352+V\3551G\206\306\306G\306\211J\357\372\351>\17\363\231\221\261\372\271\270\230OO\335\261\301\362\245x\0h\35\350"...,  
7718) = 7718
write(11,  
"\250\01674\340\261q\271\215\272Y+\350\253\363VYr\340V\r\334\242\217\314A\35]\260\251P\223\17\'\255\'\350\226\340\357\37\207\362|\344G\201\357j\351\337\324g\v;p=e\"\374\264\16}\371\4\224\22\t\25\211\5\340\322\377\341\237\'\17\225\201uP!\v\305M\306\362\340\262\224\231\222\354\2526\22  
\376VjX*(\222 at OK@^?\310\205\200jR\25v#\363\202\22\272|\213\0374m\rS\31\271v\225\22Cu\341\315\325\0274\340\355in[\24\\q#\324mN\235\303!\347\232\261;\310\363\7\333\357\267\246nZq\2`\30\25&\240\262!\3\n\320\20\274\23Eu6\371\364\274V\23\234\223\360\33\306P\200u%\246\25)\3633\263\n\336\3535\323sE\21\251\f\206\22\376d!\24^\327\373\201\256\245\20?q\340gJ\253\206A\1779\02106\255#\216\220X\313\2\202^\10N\257\244\332\245\275\4\231n\370@@\375  
5\4\240\265\304\315jgj\\\5l\314\30U\373\250\36\237\367\250\313w\32\312\341\303Tmn\241\203&p\366\362\267\343\271\33\363\306^\263n\35T0AN(\266u^\334\2740\370!\24J\206\246m^H\337&D\274\217\225\324\311.N\342H\333a\343|\361|\251;\346\266\224\361`\314\333-\4\20\321\352;\343\256"...,  
7718) = 7718
write(11,  
"\274\360\256]5\6a)\363\310\375c^\321\336\307\220\275tE6\325f\376\364\370\370W\371\317M\355\207\r\261\232\306\343\202\1\276\216\347\224\362\267\26\352\3725Ps\237\233:,\366tH\273\263pZ\235\224@~1\261I_\256m\330~t%\\\372\7\204\330O?\360_h\343QK\345\302\2r\331\351\317\221Z\22\376\240\30<O\217#+\301\v]e%0\343xl<e{}\243\225\225\226{\356\216\211\"\261\27\5x\220\353\227\265\t\3236\265_\207\257\270\363<\226\333  
g\307\264\3209\177\247Y\276\303#-\352S\323^\10)wQWs\301n\266\244\371\277\23+<\203\256\332\242-9\324\250\355i\306\204\21\24\16PXM\247\f\n:\242\350\264\205\3737\324\375\311\t\251\356C\vAVZ\212x\375\30\337\240s\252\313]j\245N\327\317!HJ\323wS\237\267\357\20\1\v\373Y\313\232W\357\\\227#\336\215\357~]~\36k:~\240\235Ng\310\333;\267\203\226\306\34g\371\5m&\250\2058A\312n*C\271\204\n\360\226f)\227\225H\317\344\202\211k\255\33\365\205u\26\346\307\354$\242\345\241\204u\30\n\334\2703l\247\336\207v1.\342\33\243L\212`\314p!\366`hj%\246\351v+\205b3Q\371\360\f\214AZ\272\361\221\37\t&\327\337B"...,  
7718) = 7718
write(11,  
"\277I=\330\212\355MN\232<\266\207\"\206\252[\352J\344Vrh\205\375\202\244\337\214\266\325/7>\205\234~\306\367\3\212]\314\333\364\16\220\274_\355oGbV\6wN\214J6N\37\266\356\206\345\236n\320\205\341`\330\234\242L\1\21\302\270\v\270G)^mp\16H\255  
4X\322\34\33\226\37\271\317\360\206P\251\244\237b8\376\2307)\260p\207\320\242&\324w_\6\344\236\24N\2004\23)\246\200Tl\267W_\373\372\213[\240\240]{\f\375;\5\6\30\n3\347K\213E\365\216aIE\224\334Ci1\254\212P\346e\372\fk\272\267sQX\20b\3731\305-\306\231\345$\345\37\364Tq8nT\t\27\1\360,\207*\215W\253\367O\337\237\311\224o\271\361o\331\2676\307\346f\275@\262\317>i\335\345]\37Y\203g\f\7x\3126\326\r\22\212]5\261\322\250\211\247\335Qf\26\356\260\276\200\22\2036\305\27#\0370\235{\343g\274\205L\243\320\334\31\357\357LM\323\307\256\305\316<P\226|2\30\215?-x:\371\237d\36\373>\347\35\322\205\26\363\r\231\353\254\251\26\211j\200d\1C\\\24\215\213!t\303j\356\30
3\2E\343M\215\10G\254\235\260\311\23_\242\3537\353\320\325\314\214\260\0164l\347\354\377\212\276\354"...,  
7718) = 7718
write(11,  
"n\0{\277\212\33\327]m\2434.5\225\31\373\250>\255V\306\301[nZ\234\6uRzJ\352\243A!\241j\21\311\372l\36\346!\16en\360&2\244\321\4\266\251O\v\n\365bd\221\374\201\230\377B\16=\357R@\226\\1x~\206\332\200\24$h\233\264\261\
365z\202\352\353\307\244\212\306B\317\341\321\264\3156\311\0176X\204\201\355\2211\217\fG\251o\352C\262\376.[\17\32\375\316\357b\220\266L(*\6\264S\31:\324\334\321\242\327\252\300\214\23x\331\306\260\315\322\364f\315\321\207\274
\345\337q\271I5\235#e\200C|\214\303\232=#\30OCV\367O\310q2\202\370~\33g\220W\362:Dr\273\241\207j\235V9W\304[O\271\2o\177\r\220\336\271@\374h\251\313\325+\23\265`\316\24\323\7\31)\204\20~\233\214#\260WhXA\210\211\246\365\210\36
3Fwa~\256\323Vk_\321\374\374\25Ub\\\262Kb\322\324\243G\214h\346\350\342\5\313f~\37\223\311G\250\242Z#\256\331M{H\5\v\276E\303\366\334z\325\177A\255\351\266\346\264d\10\226\254\210\234\260\256\222\204\35\271M\240\215\317\16[\23
6\255\3225\246\24\250\16\253\270_\353p!\253c=\213j\10F\306I<\2759\'DF\334.\205\16\253\17\221\3444"...,  
44715) = 44715
write(11,  
"\374\245V\270\257\301\36\"xQ9\33\2559\342<\217\203\0\10<\357}cu\212\n\365\17\272\234\254xl\26i\372?NN\264\37\301-\346\241\227\353\330J\264\376\240\23\352?7;S>\367\'\351\345\r\25\304\17\214\245\2613)\247\3H\245\277\2
41\177i\333i!\377\302g\211\n\243\206\376j(b\325\352\211\216e\34h\303e\323\2603\314TZ\21F\321\212?\5#|\33\231_\266:9\351\227  
\21\2762\2625\345\317\301\242n\263\352u)\345\314\17\22RQ\211y\216\261`\275\360L-l\7i{\366R/\363\210k\3
67\350\3108\346\277n\344z1zn\315\251D`\3558\203%O\34\335\210\t\2E\352Z\234PT\231\254(v\223&&\7<\365\224\34/\200\331\320\n\n\254X\331\250\200=\26\201\350\353\343\7\230u\240\274%h0zT\346;>\350\233O&\232V\261/I)mm\330\276\343\35\
222ye\252\2540\361\3735\3327\344\214`\340\213\3444\34\320\25N\263\220\263\225EN\1 at L\312\377\220K`<\372\373D\v\336_\310\376\266\314)S\232\336\324S>0\342\227\210\206\r\324\324\370\302\243\357\251\333\246\230(cG\316\366\31s\302\2
42\204\351e9\364\366\226\362\356\350.\364*w\366\25\265\260/\313\367\317~\n\376\\\'\337\253\265\216"...,  
9017) = 9017
write(11,  
"\327\333\374\347H\222\22\4S\"\273\360O\337\225\'\333\31\370\27r)M\244j\377E\271B\372\236\\S\17\16\246\1\354\201\241H}a\'8\4J\316\256\301\300\217y\366\306\26\361\263\10\22\253?\215\260\331\212\350\224p0\363\320\222\1
6\244Av\337\26j\2658>\23\214\252W\310jI\3659-\206\323\r\27\266\245J\352\273\20X~\221q\246e#\212t\301\371\345-,\323L\210\264(2\233\241^il\264\333\v8  
\232\341\20#$\246im#`\334\261\342!\\3\206\372\266\301\356\325\264\236\271zB\22
1\340s&\260\230\336\r\207\326\200\333\37\257\264|k\347\334T3#\273\203\3250T\264s\305z;d\227\313\26\306\31\332\212\25=\37D\300(\310)\3\326_\'DP\246\304\341\240\200b\322\204t\253\233?\377\214\227\214\364\vJ\326\276\343\332\36*\n
\244F2\370sJ\267>\345\'\207Z8\224\312\203\2456o0\202),\210\242\fq8\266\376\314#  
x\26\237\305ZY\26\317\240+\225a\336\274\'\235\306LC\213\365\211n\23\336\341\20\255K\353\334Q\3174\0\240e\361\"\311h8\343\222\223\251\337\353(\240\
222sq\265M\274\f&\16<C\342\375\363\307=\362\354\373^  
n\336ge*\16CT\305\370\254\0036Mt)O\257\250\177"..., 8688) = 8065
epoll_ctl(5, EPOLL_CTL_MOD, 14, {EPOLLIN|EPOLLERR|EPOLLHUP|0x20, {u32=14,  
u64=14}}) = 0
time(NULL)                              = 1158283301
gettimeofday({1158283301, 170607}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\332el\213\375\r:\375\346\304\366\240$\311\343\320^\22?\351wr1bS$~U\10\334\216\253\316\26\310,~\350x\352\271\274\257\302\200\307}jt7i\310\201\223:\350\34\200\247\346\r:o\21\22\25\256\364\344\250x`j\333\213\310\261M\'
\30:I\265K\261;\371\203\303\361\310\313A\264\267P\261l\276x\314Sxoo\232\225\2246\266\224EzQk\221\271\270\206\236\370\324]\260\27\354\273\207\25\347\275@\342g\312C&h\335O\\\351\377\272\265+\372\304\233u|\300y\371\305\v\352\327a
\17\3510+H\356\307\21\371n\274S\360`\337\22U>\216\277\\\303x\363\260\300^\300/\263P\347\v\205B\227\214\17Xl\323\357\336\243;\252q\365k\354iXV,\224\211X\315G\374\16P\223~\330Q\236\262l\36p\16\223\306\30=\25\325\334~\273\263l\23
4M\240XO\344e\250\353\230P\34n2Z\315\17G\22,\334\\\336t\320\31\345o\242\306p\355\252\375\203\24\226\351\362G\347`\213)\225\261\213\370\306\310\205\317n\321>\304\265\tR?\232}I\360\322\354\377\274\364\236~\273\371.\242[W\265\323
z\255\36\317\f\336\374\342\26}\254\274\337Z\37\217\213w\22t\300\244r\330\2367\fy\250\271\374\217"...,  
61449) = 43285
time(NULL)                              = 1158283301
gettimeofday({1158283301, 170955}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\363\314\3111\364\35\277\345\214Z\217\324D\203w.6\213E\207\252&&S?\214\334\354\276R\331>\37\30\255\235\270.\\\275\376\225\\\374\27\370DY\r[B\357\323l\206\314\34K6\320\34\210T\363t\353\244\335\247\230\261+m\317}\362\2
54\353d\343\325\263*?\222z\2159 at 8DU3\243\220\372\36561\364\7kd:<;\1\274(\322\260\223\370\226\311D\n\215J]\3723\311|\225\271X\323qM\221\264\233y\207\373\27\331B3\211\342\324\365\357\355HY7\362\274[#\';f<_\356\360*\250\240~\336\
325oT\333F\374\"{@Q.\315$$\246\v\37\217\'\232\21\375\366q\20\2632\226\217\307\20\352\'\31K\350\241Y\\0gMW\30\212\275\v\266iK\361\200\375\333\306\332\2067s#\345\326\272\237&\31*|<\371`\337:^M\16\322\3356/\377\210b\354\340\352\3
74[3?\2030\203\24\330\342\26(\25\242\236I\315\212\3578\213!\n\22\215\363\216\370bmnX\314\362\5[\2278\237\\\262\347\7\277\330\240\233\244\306a2\35En\317\7VU\262\34c\355\360\254\2241\332\370\6\206\356h\"\345\210\235\347`\231QM\0
\201\367Q\236\257\365\345\2\274\303~\311\311\16OH\213\233\275\364^k\27\36\264\27f0\313\5z`\311\326"...,  
61449) = 7033
time(NULL)                              = 1158283301
gettimeofday({1158283301, 171370}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\21\355\351\344\243,%\252T\7\245X0\245j\201\34\275\335\261\366\312\206\0\23\356\243\246\220\6\367\224Hb\314\34\33\303\6]BLA(~z\v\33\253\260gc\177\336,\270\275\222\314\\w)K\330\224\211\273f\366Q~\266\241c2\300u\316\30
2\331\302V\317G\371\263\301Q\36Z]\357R\201\232\222g\257\326\363(4\\\225VS\255\263\257\325\263\256\22367z\22\2230qB\231\347@\246\206+>!\2712iQ\224\361\275\265\217h\370x\270iE\302W&Fqn\3o?R\303<\347Y\227\210\311\"sJ5*\10@\245\30
1\246Z\201\231Nm\r\"Hw\251\352\367\321\vH:<\231T\256\4\245\240\247/pq<+\352)\237\265\322\rG\314n\314i\357#\311\216m\230\220\'^\25\215\310G\242)^\3\265\266\223%t\20  
rq\37\2\241\202.G\354\364Tc\342\21\23\362\251L\212\33\n\10\225
,\210C\273v\nQ\202rF\334\267T\202*:e\n\322\262W\351\263;.\213k\345\10\310\3632l\324\202~Q\231_\233L\207\354?\221-.FmV\353N\315Q\266\37\371Oh\31\254\247]\272\324/=m6\36\274\0325{\257t%\202\331\315\177:%Z\265\0250a\257\32\200}\3
36\347\240\317=?UT3v\250\324\205\253N\'Q\34\367Mh\34M\231%0\227GB4mI\346\274\352\336-\364Z\37\0\n"...,  
61449) = 5792
time(NULL)                              = 1158283301
gettimeofday({1158283301, 171699}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\300j\350\207G&\354\2100$\5\203\302\226\320`22\2\243\v\0321K\225N\260\246\321\201\347\360\347\231\357~\353\356\206Bv\2007\227\236\232/\225\312\213\32W\337\226*\370\313\223\302A\223z\260Iw\035174\374\223\335$J`>\375\3
33\210xr\344fg\r\343\206\324\371>\370\276\305\376{iU^\271\4F\204m|kzd\301\266\230SEa\36\255\367Xt\327\370|\207\327\355\310\2n\332\245dV\316\307L\324jW\331h\322\216\6\275\n5\231\265\0\27\255\357\212\262s\213\35\v\353\201\207~\3
45\302\17\262\234\237\220\312\355\261\34\264\377f\371G\230c\363;\243D\365E\'\241\306`\26~\320\271\203\35\227,\'\276\231I\346\202\303\216c_\344\7#\366\253\323\337M\277E\326\264\20\37\fH\202\362\306i\2254\17\3217\375m\346\306.m+
!\25\31\234\373E\376e\314\214\246a\ft\6\243\352\225\353\32\240\245\240,  
W\3os\16c\210\304\340\355\35\335\333\212\36112\\\374Rd=\322\205k4\231\205\252;\201\205B=\356\273\227W\241x=\233\307\215N\304\3373T\237\277\224\27\225\361\
250kp\315\302\2Zh\234e\260\1m\350X\310\357\344\257\251\210\247B\31?\354\313\303\310\315\322\205\366"...,  
61449) = 7033
time(NULL)                              = 1158283301
gettimeofday({1158283301, 172014}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"Y-\4(\367\16Z&\26)\273\211\3672\354\256\307LL\243E\251\214X\216p\205\336m\t\314a\216\6o\221\17\223Q\255_\263?M%\325Y\243*\320+\"\361\260g4\232\216k\'\355.s\230\342\347\2215XN?\2\314\212\222\261\261%\353\372\253\200\3
5\6\351\fe4\234\235\372\246\205\244\335IT,k\n\vc\0\17\307W\310{S\305\377\32\366\336s\362\336]\254R(D5nGx\22x\223c0\257\327A6\216Q\202D;\221\245Z\357,\261\237~\323\37\276\233\0\325#\234IBH%\235V\237\224K\277Q\344\340\27\331\213
\255\245f\270\31+jv9\3033\306R\342\276\243\232\375\256(1\35\301tU\0018y\31v\251H\253\10\36\37\4\221-s\300\312\246`\347\321>\315c\2348r\25\2\235\202\241\214\315\371\363\254b\277[]\374\237\372\302\337eL\233\257\357\357>\352\216\
324\1\205\324\37=\27Z\221\346\22\212g\16\240\235\325\16\311xk\213\7?\206\321J(\273\343v7\2\377\347^nTK\343\350H\3166\202\216\240L\210\16\224H\247\241QI\305T.EA\337xS\347\322\327aa;\311\36\350\260\215\276\240\366\276P\355p\212\
200a\2\246\334\266e\357sM\214\302\377\34\321:3\323\np\264\322#Sh;\32\365\263\242\223\365\23405\265"...,  
61449) = 5585
time(NULL)                              = 1158283301
gettimeofday({1158283301, 172329}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\nnmq\240h\'\250B\201\306]\27kZ&\304s8S&\273:\237\\\217\0\223~^7\370\24W\224\326\217.s\376\317\334\370k>\323\266\206\205[pz)\267\345\364\226\tP\232\251\246S\v\"\233u\321\366\334\307\237\366\363\305A\330  
a\0h\321D\37\
254,\206(?%\35\2MF\272\205.\337\230`\212\26\356\366\351\247\336\307?\373\332\332\320\344\243\202\243\213\333\335\242\206\231\23g\276\313\351\351\353\205\27\3\26k/~\347_\327\337\3741\235\364\200M\243+\37\315#\220Ta\2\262\366\31
vT\t.\221\314\f(L\272\261\363\234|\221T\237\16g\35\302\7M\311gU\226q\343D\177\256\243\252\234?\212[\330\305\217\261\333\322\355\205\240\10H\203#\0\"\332\201_\253\37n?#\344<\234\370\35U\356|\251\255\266\300\2774\377\331\6\213\3
34w\30\346<[\33\234b\6KTx\267\242\237\1\270{\277\300\246\373\177\221\203YS\322\3707\300+\336\246-[\350\24\4~\345Q\253\24\212\251\377Lr\354\32V\347\356\233b\22)\354W\337\300\235\245\377\242\327\316\223\276\226\371\356\275F\362\
305(\225\371\203\354\10\25Xj\6\300.\21x.\310\26j\31\242\256\20U\303)\252\200g6\334;\200\255\274v"...,  
61449) = 5792
time(NULL)                              = 1158283301
gettimeofday({1158283301, 172641}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\5$l\212U\364\303:!\366\27\272\10i\27U\272\240\216\204q@\3042\235`\210\245F\4\247\345\\4\36\361\3609[C\20\232\255\335q\345\21\"PQ\267,\211\220\262\323C\201\240~\"5x%\272\306\2\261Lz\20\263\211\36_\343\n-KExd\214\347\
277(\27\0\5\23o\276h\263\'g\32/\377t&X\6\304`\n0*I\216\211\244\255\255p\270*\2523\203I\352  
\243\246\215\10\223\225\334u\303\313\2472\210\224#\275a\236\270\347\236o5\254  
\35zRh\272\223\245\243\314tb\200\273\374\300R\221\10\177\
240\301\5\330\331\274\240\211\305\206\0204\0373q\232VW\237\244K\316Y\377!\246\210\205\3:[\233a\367\2770\326\237X\314W\3431w|\273\6,\35f\32\3730\274wU\3219\26\315~.v\302ln;+2\"\330\25l\327\253\341n\272NY}\275-7\377\372\355\220\
\w\"\335\10\266#n\354v\247DO\353|\200\310Q\260&\253}\341\250\373\355\232\373\275X\201\1\205\5\246zN\224\'\32\253U2\6\1Z\263\346\327\3079\206\350\241\17G,\204O\315\206\336\331a\34\\H\272m\223\317y\352qv;\333\307\\\275\326\367s7
\347M\326\232\203\360\243\251&u\362\16\16\277\352\271g\30\201\r\326\370\306N\300\265;\240\275^&\355"...,  
61449) = 7033
time(NULL)                              = 1158283301
gettimeofday({1158283301, 172965}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"FH/\306f\353\361\343\365r\244J_\316\264j[\36\276\24\263\273,\2048\333\367k3H\322~\355\271\254?\313\253\332\31\31\367\337E\262:x\274\23\366\241;\241*\25\347\345\27\352\200H2\253\346\255\344\211\377\321\375;1\232%\223<\201\237j\201\356\275d\3113)\346\240\337\21\37\3377\230\250\330\353lJ\2567\311\245\355\301\360&\350\335]\27M\236\232\v\32-!\356\207\237~\347\310c\215)\242\2546\345\314U[\373\235\331\262\240\270\332\301\315?%?\271~6Sr[3\17\227\20\265r\310\0004\365\253\311\300~}\205\224\345q\252h\vt.V\371\3552\212\267%s\213v\200V\335\16\233\240\261\205\27ag\306\3713g\214\253\351\275\f\207\373\23\217\217\177\207\367\301\226\250\262\211\306I\265\316|\241*\275R\374p\16\324^\267\326\364\326\37\302pt\217\357\'\237\233\f\32>\3217j\tZGl\207\267S\\/\267\256\361f&Z\224\215y\342\273\261\263\"\323*\251\235>~\222\214\345sP\231\314N\230\237+\373:V\371\201~\254.\363\255_\235H\322\270\336\214\3\313\\\320&\306\357\362\323\274C_F\2147\344c\263\21W|\3271\207:\217\273k\207>\27\275\240\270\305WQX<7x\337"...,  
61449) = 5585
time(NULL)                              = 1158283301
gettimeofday({1158283301, 173269}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14,  
"\204\224\263\200:w\277\251n\377b\366K\26\267\30\24\221\243[\'\203Z\0oK]v\325\354\226\340\261nM\213j\30\312\357\326\311\207\277AJ+\324\344,^C\f\370\233\230\25V\301q\r\213~/\304\364\261\271\300\232\305\1\\\'\336\272\0\5\277\362\22\225\363\245<\v\300\202\347\332\327\311\342D\316\221,m\217Y\32\337\34\311\331\340\26\33\t\254\204m\226\351\276\246\t\25\216,q\223\231\316{\271\262\275X\\)_\177V\r\f\357\346];\305\241q\33\30\266\16\307\0276\374\6\0063\377\241\211\200\264\31\32`\t\326\372\216+\265\273\214V\372m\0261\253\365\253\232lV\211\311\260\313\254\334\333\306\307\346\23\26\33\25\204  
\313\330\254n}2\350.\256\3568\327\222\v\250\264\5\321\257\237m\203l\33\213vT\v\36\6\243\320\254\336\201\212\27\25\30D\237J\242BEsnR\306\3407\27\325\31\'\362\202U\220k\271*\333E>\276\306$\375\f\351\332\243\37\236\"\226\306)U\270J:x\227\357\261S\312\3504|j\25\31Lv\305\26\35\263\2229\23T\355\276\347j}\242\341\31\362\360\35\245TiKJ\331\320  
X\17\274,\316-\376\344yT\266\345\353\215\n>Z\4\273R\\\355\v\214\223\375"...,  
61449) = 5792
time(NULL)                              = 1158283301
gettimeofday({1158283301, 173584}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
read(14, "\272\241\10\327\243\211\333\f|  
\245\354sC4`\320\4\262\366%\2+\362\367  
\231\322\360Q\226\273\7\223\311\302\31\373\25\220_\327x\355\226\3265]>\361\331\r-\351I\vC\306yX\367q\16\377\\\220\31\370J\252\225\254~\310\241^A\3
77\215\236\222\25\320rx\27\244\216y\37\251\331\350m\7V6\0108\226\242:\273u\331\n\266LhY(cPB\234WV\340hZ3\343\343\226\240\230Xo\207\266\313W\331!\220\361\360*\266\262E\334}a\247\351O\357\377\331\'\274\200\241\25*\31\251\342\306
S\244\237\3650y\305~\17\36\336{\333\234+0s\272\37\271>\20\t6\324\177\306\4t\216\\\327\370\256\3517\222\311]\7\354\266\254=\23\366\323.\313\345\220\177\260\214\1\37\367\315\351UQ\374\363\262\213\222@\262t)C\250e\366\266\360U\31
6\353\326\274\24r\1\2\26%\232k)\305\225\266K_\203\316\261n\177A)\213q\213\251\361\334\26\223\343\235;\17\226\2431\223\230\303P\333rf!\347.\341\225\221)\366\271\235\207\244\357\343\323\250\204\4H\223\3434\271\221\365\242\26.:\2
23\202\'4\232]&OO\275\273\32\253\33\227f\326\344\277\362?\371>j\336\363<\v\317\35\3774\370\232\233"...,  
61449) = 7033
time(NULL)                              = 1158283301
gettimeofday({1158283301, 173903}, NULL) = 0
epoll_wait(5, {{EPOLLIN, {u32=14, u64=14}}}, 1000, 1000) = 1
epoll_ctl(5, EPOLL_CTL_MOD, 14, {EPOLLERR|EPOLLHUP|0x20, {u32=14,  
u64=14}}) = 0
time(NULL)                              = 1158283301
gettimeofday({1158283301, 174076}, NULL) = 0
epoll_wait(5, {}, 1000, 1000)           = 0
time(NULL)                              = 1158283302
gettimeofday({1158283302, 173663}, NULL) = 0
epoll_wait(5, {}, 1000, 1000)           = 0
time(NULL)                              = 1158283303
gettimeofday({1158283303, 173662}, NULL) = 0
epoll_wait(5, {}, 1000, 1000)           = 0
time(NULL)                              = 1158283304
gettimeofday({1158283304, 173634}, NULL) = 0
epoll_wait(5, {}, 1000, 1000)           = 0
time(NULL)                              = 1158283305
gettimeofday({1158283305, 173755}, NULL) = 0
epoll_wait(5, {}, 1000, 604)            = 0
time(NULL)                              = 1158283305
gettimeofday({1158283305, 777796}, NULL) = 0
time(NULL)                              = 1158283305


>
> Is the Perl 64-bit?

Yes:

# perl -v

This is perl, v5.8.5 built for x86_64-linux-thread-multi

Copyright 1987-2004, Larry Wall

Perl may be copied only under the terms of either the Artistic License or  
the
GNU General Public License, which may be found in the Perl 5 source kit.

Complete documentation for Perl, including FAQ lists, should be found on
this system using `man perl' or `perldoc perl'.  If you have access to the
Internet, point your browser at http://www.perl.com/, the Perl Home Page.



> Which version of Sys::Syscall?  Does its make test
> succeed?

Hm.. that's interesting. I installed everything through CPAN (except  
Perlbal itself), and afterwards Perlbal seemed to work fine, so I didn't  
give it much mind. However, checking my /root/.cpan/build/ directory, I  
found Sys-Syscall-0.21, and going in there and typing make test, I got the  
following:

[root at perlbal Sys-Syscall-0.21]# make test
PERL_DL_NONLAZY=1 /usr/bin/perl "-MExtUtils::Command::MM" "-e"  
"test_harness(0, 'blib/lib', 'blib/arch')" t/*.t
t/00-use.........ok
t/01-epoll.......ok 12/20#     Failed test (t/01-epoll.t at line 61)
Can't use an undefined value as a symbol reference at t/01-epoll.t line 62.
t/01-epoll.......NOK 13# Looks like you planned 20 tests but only ran 13.
# Looks like your test died just after 13.
t/01-epoll.......dubious
         Test returned status 255 (wstat 65280, 0xff00)
DIED. FAILED tests 13-20
         Failed 8/20 tests, 60.00% okay
t/02-sendfile....couldn't start listening at t/02-sendfile.t line 64.
t/02-sendfile....NOK 2#     Failed test (t/02-sendfile.t at line 60)
#                   'ERROR: unknown command: i
# '
#     doesn't match '(?-xism:^OK)'
# Child said: ERROR: unknown command: i
# Looks like you failed 1 tests of 2.
t/02-sendfile....dubious
         Test returned status 1 (wstat 256, 0x100)
DIED. FAILED test 2
         Failed 1/2 tests, 50.00% okay
Failed Test     Stat Wstat Total Fail  Failed  List of Failed
-------------------------------------------------------------------------------
t/01-epoll.t     255 65280    20   15  75.00%  13-20
t/02-sendfile.t    1   256     2    1  50.00%  2
Failed 2/3 test scripts, 33.33% okay. 9/23 subtests failed, 60.87% okay.
make: *** [test_dynamic] Error 1
[root at perlbal Sys-Syscall-0.21]#

Now granted, I'm not positive this is the Syscall currently being used;  
but it probably is.


Hmm... I tried in the Danga-Socket-1.53 folder as well, and got the  
following results:

[root at perlbal2 Danga-Socket-1.53]# make test
PERL_DL_NONLAZY=1 /usr/bin/perl "-MExtUtils::Command::MM" "-e"  
"test_harness(0, 'blib/lib', 'blib/arch')" t/*.t
t/00-use..........ok
t/05-postloop.....ok
t/10-events.......ok 2/34couldn't create socket at t/10-events.t line 67.
# Looks like you planned 34 tests but only ran 2.
# Looks like your test died just after 2.
t/10-events.......dubious
         Test returned status 255 (wstat 65280, 0xff00)
DIED. FAILED tests 3-34
         Failed 32/34 tests, 5.88% okay
t/12-closerace....#     Failed test (t/12-closerace.t at line 24)
t/12-closerace....NOK 1Can't call method "accept" on an undefined value at  
t/12-closerace.t line 27.
# Looks like you planned 7 tests but only ran 2.
# Looks like your test died just after 2.
t/12-closerace....dubious
         Test returned status 255 (wstat 65280, 0xff00)
DIED. FAILED tests 1, 3-7
         Failed 6/7 tests, 14.29% okay
Failed Test      Stat Wstat Total Fail  Failed  List of Failed
-------------------------------------------------------------------------------
t/10-events.t     255 65280    34   64 188.24%  3-34
t/12-closerace.t  255 65280     7   11 157.14%  1 3-7
Failed 2/4 test scripts, 50.00% okay. 38/59 subtests failed, 35.59% okay.
make: *** [test_dynamic] Error 255
[root at perlbal2 Danga-Socket-1.53]#

The rest of the packages, though, seem to make test fine.

I only would figure that cpan would either tell me at end of install  
(since things scroll by so fast during actual install that it's hard to  
notice) or not install the software... it straight up didn't install  
IO::AIO.

Think this might be it? Any suggestions on how I can get these tests to  
pass?

Thanks very much,

-Sam

>
> If it's not spinning CPU, then the question is why not:  we want to know
> about writability of the clientproxy but if we're not spinning, the epoll
> support (Sys::Syscall) is broken.  Historically we had problems with
> 64-bit, but that was fixed quite some time ago.
>
> - Brad
>
>


-- 
Using Opera's revolutionary e-mail client: http://www.opera.com/mail/


More information about the perlbal mailing list