Re: [Dbmail] dbmail-imapd 3.1.4 crash(spin-lock probably) [Was: Re: dbmail-imapd (3.1.3) using 100% cpu and not working]

[Harald Leithner]

does this mean I have 50 user connections running?

l-wx------ 1 root root 64 Sep  9 15:38 0 -> /var/log/dbmail/dbmail.log
l-wx------ 1 root root 64 Sep  9 15:38 1 -> /var/log/dbmail/dbmail-imap.log
lrwx------ 1 root root 64 Sep  9 15:38 10 -> anon_inode:[eventfd]
lr-x------ 1 root root 64 Sep  9 15:38 11 -> pipe:[9397067]
l-wx------ 1 root root 64 Sep  9 15:38 12 -> pipe:[9397067]
lrwx------ 1 root root 64 Sep  9 15:38 13 -> socket:[9397069]
lrwx------ 1 root root 64 Sep  9 15:38 14 -> socket:[9397071]
l-wx------ 1 root root 64 Sep  9 15:38 15 -> /run/dbmail/dbmail-imapd.pid
lrwx------ 1 root root 64 Sep  9 15:38 16 -> socket:[9403274]
lrwx------ 1 root root 64 Sep  9 15:38 17 -> socket:[9403355]
lrwx------ 1 root root 64 Sep  9 15:38 18 -> socket:[9403234]
lrwx------ 1 root root 64 Sep  9 15:38 19 -> socket:[9403235]
lr-x------ 1 root root 64 Sep  9 15:38 2 -> /dev/null
lrwx------ 1 root root 64 Sep  9 15:38 20 -> socket:[9404478]
lrwx------ 1 root root 64 Sep  9 15:38 21 -> socket:[9403388]
lrwx------ 1 root root 64 Sep  9 15:38 22 -> socket:[9404473]
lrwx------ 1 root root 64 Sep  9 15:38 23 -> socket:[9403417]
lrwx------ 1 root root 64 Sep  9 15:38 24 -> socket:[9403393]
lrwx------ 1 root root 64 Sep  9 15:38 25 -> socket:[9384865]
lrwx------ 1 root root 64 Sep  9 15:38 26 -> socket:[9400114]
lrwx------ 1 root root 64 Sep  9 15:38 27 -> socket:[9403256]
lrwx------ 1 root root 64 Sep  9 15:38 28 -> socket:[9403242]
lrwx------ 1 root root 64 Sep  9 15:38 29 -> socket:[9397077]
lrwx------ 1 root root 64 Sep  9 15:38 3 -> socket:[9401393]
lrwx------ 1 root root 64 Sep  9 15:38 30 -> socket:[9398235]
lrwx------ 1 root root 64 Sep  9 15:38 31 -> socket:[9397079]
lrwx------ 1 root root 64 Sep  9 15:38 32 -> socket:[9403257]
lrwx------ 1 root root 64 Sep  9 15:38 33 -> socket:[9398237]
lrwx------ 1 root root 64 Sep  9 15:38 34 -> socket:[9403275]
lrwx------ 1 root root 64 Sep  9 15:38 35 -> socket:[9403276]
lrwx------ 1 root root 64 Sep  9 15:38 36 -> socket:[9403277]
lrwx------ 1 root root 64 Sep  9 15:38 37 -> socket:[9403278]
lrwx------ 1 root root 64 Sep  9 15:38 38 -> socket:[9403279]
lrwx------ 1 root root 64 Sep  9 15:38 39 -> socket:[9403280]
lrwx------ 1 root root 64 Sep  9 15:38 4 -> socket:[9397062]
lrwx------ 1 root root 64 Sep  9 15:38 40 -> socket:[9398247]
lrwx------ 1 root root 64 Sep  9 15:38 41 -> socket:[9403285]
lrwx------ 1 root root 64 Sep  9 15:38 42 -> socket:[9398252]
lrwx------ 1 root root 64 Sep  9 15:38 43 -> socket:[9403308]
lrwx------ 1 root root 64 Sep  9 15:38 44 -> socket:[9403309]
lrwx------ 1 root root 64 Sep  9 15:38 45 -> socket:[9403310]
lrwx------ 1 root root 64 Sep  9 15:38 46 -> socket:[9403311]
lrwx------ 1 root root 64 Sep  9 15:38 47 -> socket:[9403336]
lrwx------ 1 root root 64 Sep  9 15:38 48 -> socket:[9403337]
lrwx------ 1 root root 64 Sep  9 15:38 49 -> socket:[9403379]
lrwx------ 1 root root 64 Sep  9 15:38 5 -> socket:[9400112]
lrwx------ 1 root root 64 Sep  9 15:38 50 -> socket:[9404455]
lrwx------ 1 root root 64 Sep  9 15:38 51 -> socket:[9404443]
lrwx------ 1 root root 64 Sep  9 15:38 52 -> socket:[9404435]
lrwx------ 1 root root 64 Sep  9 15:38 53 -> socket:[9404439]
lrwx------ 1 root root 64 Sep  9 15:38 54 -> socket:[9404441]
lrwx------ 1 root root 64 Sep  9 15:38 55 -> socket:[9404442]
lrwx------ 1 root root 64 Sep  9 15:38 56 -> socket:[9395667]
lrwx------ 1 root root 64 Sep  9 15:38 57 -> socket:[9404471]
lrwx------ 1 root root 64 Sep  9 15:38 58 -> socket:[9404475]
lrwx------ 1 root root 64 Sep  9 15:38 59 -> socket:[9404479]
lrwx------ 1 root root 64 Sep  9 15:38 6 -> socket:[9398230]
lrwx------ 1 root root 64 Sep  9 15:38 61 -> socket:[9400178]
lrwx------ 1 root root 64 Sep  9 15:38 62 -> socket:[9397149]
lrwx------ 1 root root 64 Sep  9 15:39 63 -> socket:[9384945]
lrwx------ 1 root root 64 Sep  9 15:38 7 -> socket:[9397064]
lrwx------ 1 root root 64 Sep  9 15:38 8 -> socket:[9397065]
lrwx------ 1 root root 64 Sep  9 15:38 9 -> socket:[9397066]




Am 09.09.2013, 14:07 Uhr, schrieb Thomas Raschbacher <lord...@lordvan.com>:

you could have a look at what those file handles are (pipe, file, socket,..)

e.g. I get this for imapd:

ls -l /proc/<pid>/fd
insgesamt 0
lr-x------ 1 root root 64  9. Sep 14:06 0 -> /dev/null
l-wx------ 1 root root 64  9. Sep 14:06 1 -> /var/log/dbmail.log
lrwx------ 1 root root 64  9. Sep 14:06 10 -> socket:[34695666]
lrwx------ 1 root root 64  9. Sep 14:06 11 -> anon_inode:[eventfd]
lr-x------ 1 root root 64  9. Sep 14:06 12 -> pipe:[34695667]
l-wx------ 1 root root 64  9. Sep 14:06 13 -> pipe:[34695667]
lrwx------ 1 root root 64  9. Sep 14:06 14 -> socket:[34695668]
lrwx------ 1 root root 64  9. Sep 14:06 15 -> socket:[34695669]

l-wx------ 1 root root 64 9. Sep 14:06 16 -> /run/dbmail/dbmail-imapd.pid

lrwx------ 1 root root 64  9. Sep 14:06 18 -> socket:[34704743]
l-wx------ 1 root root 64  9. Sep 14:06 2 -> /var/log/dbmail.err
lrwx------ 1 root root 64  9. Sep 14:06 3 -> socket:[34643747]
lrwx------ 1 root root 64  9. Sep 14:06 4 -> socket:[34693753]
lrwx------ 1 root root 64  9. Sep 14:06 5 -> socket:[34694979]
lrwx------ 1 root root 64  9. Sep 14:06 6 -> socket:[34692909]
lrwx------ 1 root root 64  9. Sep 14:06 7 -> socket:[34692913]
lrwx------ 1 root root 64  9. Sep 14:06 8 -> anon_inode:[eventpoll]
lrwx------ 1 root root 64  9. Sep 14:06 9 -> socket:[34695665]


On 2013-09-09 13:43, Harald Leithner wrote:

I'm not sure but it seam to need much more cpu time now.
 also pop3d at 100% with:
write(16, "gkJCTwvZGljdD4KCQk8L2Fy\r\ncmF5Pgo"..., 262143) = -1 EAGAIN
 (Resource temporarily unavailable)
 imapd at 200% with:
write(13, "Q", 1)                       = 1
write(34, "\27\3\1\0 \247\310\373\364\202\221\207\270\2\217X6
\244\260\306i\17\363\274\302ca\376\257\356\227"..., 106) = 106
read(12, "QQ", 128)                     = 2
read(12, 0x7fffdc37eff0, 128)           = -1 EAGAIN (Resource
temporarily  unavailable)
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 53, 1174) = 1
([...])
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 53, 1174) = 1
([...])
read(34, "\27\3\1\1 ", 5)               = 5
read(34,

"\274i\214\3\37\372\351\222\311\304c\323X\362}\243\7\245]\364\v(\207EK\314\21<\341c[\31"..., 288) = 288

read(34, 0x7f2688378003, 5)             = -1 EAGAIN (Resource
temporarily  unavailable)
poll([{fd=4, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(4, "\1\0\0\0\16", 5)              = 5
read(4, "\7\0\0\1\0\0\0\2\0\0\0", 16384) = 11
poll([{fd=4, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(4, "\1\0\0\0\16", 5)              = 5
read(4, "\7\0\0\1\0\0\0\2\0\0\0", 16384) = 11
futex(0x7f26a740eca4, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x7f26a740eca0,
{FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
futex(0x7f26a740ec70, FUTEX_WAKE_PRIVATE, 1) = 1
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 52, 1165) = 1
([...])
write(13, "Q", 1)                       = 1
write(34, "\27\3\1\0

\302\302\244yj\252\3368\272\267\302\252\324\261\344L\256D\4\347\230\226m\316\252\337\31"..., 106) = 106

read(12, "QQ", 128)                     = 2
read(12, 0x7fffdc37eff0, 128)           = -1 EAGAIN (Resource
temporarily  unavailable)
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 53, 1164) = 1
([...])
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 53, 1163) = 1
([...])
read(34, "\27\3\1\1 ", 5)               = 5
read(34,

"\6n\210\230*\357\312w\242\315\327\367+`\274\322Zf\377\336\302\21Zl\237?\34\226\213\220-Y"..., 288) = 288

read(34, 0x7f2688378003, 5)             = -1 EAGAIN (Resource
temporarily  unavailable)
poll([{fd=4, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(4, "\1\0\0\0\16", 5)              = 5
read(4, "\7\0\0\1\0\0\0\2\0\0\0", 16384) = 11
poll([{fd=4, events=POLLIN|POLLPRI}], 1, 0) = 0 (Timeout)
write(4, "\1\0\0\0\16", 5)              = 5
read(4, "\7\0\0\1\0\0\0\2\0\0\0", 16384) = 11
futex(0x7f26a740eca4, FUTEX_WAKE_OP_PRIVATE, 1, 1, 0x7f26a740eca0,
{FUTEX_OP_SET, 0, FUTEX_OP_CMP_GT, 1}) = 1
futex(0x7f26a740ec70, FUTEX_WAKE_PRIVATE, 1) = 1
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 52, 794) = 1
([...])
write(13, "Q", 1)                       = 1
write(34, "\27\3\1\0

I2eW\305\301\223\233;\237\30H+\357\264\230g\342\325|\"\372\315\322\265\352A"..., 106) = 106

read(12, "QQ", 128)                     = 2
read(12, 0x7fffdc37eff0, 128)           = -1 EAGAIN (Resource
temporarily  unavailable)
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 53, 793) = 1
([...])
poll([{fd=11, events=POLLIN}, {fd=10, events=POLLIN}, {fd=57,
events=POLLIN}, {fd=22, events=POLLIN}, {fd=82, events=POLLIN},
{fd=61,  events=POLLIN}, {fd=30, events=POLLIN}, {fd=45,
events=POLLIN}, {fd=48,  events=POLLIN}, {fd=56, events=POLLIN},
{fd=69, events=POLLIN|POLLOUT},  {fd=68, events=POLLIN}, {fd=53,
events=POLLIN}, {fd=55, events=POLLIN},  {fd=165, events=POLLIN},
{fd=42, events=POLLIN}, {fd=15, events=POLLIN},  {fd=85,
events=POLLIN}, {fd=43, events=POLLIN}, {fd=20, events=POLLIN},
{fd=70, events=POLLIN}, {fd=83, events=POLLIN}, {fd=166,
events=POLLIN},  {fd=35, events=POLLIN}, {fd=40, events=POLLIN},
{fd=18, events=POLLIN},  {fd=148, events=POLLIN}, {fd=23,
events=POLLIN}, {fd=97, events=POLLIN},  {fd=81, events=POLLIN},
{fd=36, events=POLLIN}, {fd=60, events=POLLIN},  ....], 53, 792^C
<unfinished ...>
  this could be normal, but strange normal this high cpu usage is for a
short time. Now its much longer.
     Am 09.09.2013, 10:08 Uhr, schrieb Paul J Stevens <p...@nfg.nl>:

 The only scenario I could come up

_______________________________________________
DBmail mailing list
DBmail@dbmail.org
http://mailman.fastxs.nl/cgi-bin/mailman/listinfo/dbmail

--
Harald Leithner

ITronic
Vogelweidplatz 12, 1150 Wien, Austria
Tel: +43-1-786 23 88
Fax: +43-1-98 52 077
Mobil: +43-699-123 78 4 78
Mail: leith...@itronic.at | itronic.at
_______________________________________________
DBmail mailing list
DBmail@dbmail.org
http://mailman.fastxs.nl/cgi-bin/mailman/listinfo/dbmail