Daily Usenet report for paganini.linuxd.org

Sep 29 00:00:03 -- Sep 30 00:00:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Sep 29 06:56:53 paganini overchan[27586]: input line too long, skipping
Sep 29 06:58:05 paganini overchan[27586]: input line too long, skipping
Sep 29 08:36:01 paganini overchan[27586]: input line too long, skipping
Sep 29 19:25:32 paganini overchan[27586]: input line too long, skipping
Sep 29 21:01:29 paganini overchan[27586]: input line too long, skipping
Sep 30 00:00:02 paganini overchan[16819]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)

Log entries by program:

Program nameLines%LinesSize%Size
inn 48919 62.4%10.7 MB 75.8%
innd 20322 25.9%2.3 MB 16.5%
nnrpd 6612 8.4%692.9 KB 4.8%
innfeed 2439 3.1%398.8 KB 2.8%
nocem 129 0.2%15.2 KB 0.1%
overchan 7 0.0%0.8 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 7 78429 100.0%14.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 343064 60.1%
Negative hits 179212 31.4%
Do not exist 48251 8.5%
Cache misses 98 0.0%
TOTAL: 4 570625 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.150 0.0%153 0.000 0.980 14.125
article cleanup00:00:00.337 0.0%48580 0.000 0.007 0.024
article logging00:00:00.414 0.0%48817 0.000 0.008 0.023
article parse00:00:01.339 0.0%138840 0.002 0.010 0.029
article write00:01:10.807 0.1%27187 0.119 2.604 57.748
artlog/artcncl00:00:00.002 0.0%142 0.000 0.014 0.083
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.331 0.0%623821 0.000 0.001 0.002
hisgrep/artcncl00:00:00.007 0.0%145 0.000 0.048 1.000
hishave/artcncl00:00:00.001 0.0%153 0.000 0.007 0.077
hishave/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:26.398 0.0%574185 0.017 0.046 0.231
history sync00:00:00.053 0.0%350 0.000 0.151 6.333
history write00:00:41.499 0.0%48554 0.038 0.855 4.862
hiswrite/artcncl00:00:00.000 0.0%8 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:55:24.217 99.6%618983 75.078 139.138 386.859
nntp read00:00:03.781 0.0%630238 0.003 0.006 0.011
overview write00:00:22.463 0.0%27187 0.013 0.826 6.042
perl filter00:01:49.825 0.1%48553 1.175 2.262 13.762
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.332 0.0%81894 0.000 0.016 0.059
TOTAL: 24:00:51.15424:00:02.956 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.059 0.0%29809 0.000 0.002 0.010
article prepare00:00:00.020 0.0%158190 0.000 0.000 0.002
article read00:00:14.234 0.0%23518 0.000 0.605 5.245
backlog stats00:00:00.321 0.0%661614 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.609 0.0%423277 0.003 0.006 0.012
data write00:00:03.654 0.0%399753 0.005 0.009 0.016
idle23:52:45.672 99.9%661614 76.062 129.933 368.978
status file00:00:06.392 0.0%204 0.000 31.333 102.000
TOTAL: 23:53:37.47923:53:12.961100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:34:40.732 98.6%16698 34.686 124.610 10053.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.220 0.0%33686 0.000 0.007 0.099
readart00:00:07.261 0.3%8847 0.158 0.821 17.500
TOTAL: 00:35:10.56800:34:48.213 98.9%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 171

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 3 61921 7501 50710 3710 12%47:09:34
2feeder.eternal-september.org 4 45268 5192 36213 3863 11%25:04:38
3vc89.vc.panix.com 1 7096 4031 2009 1056 56%23:58:35
4newsfeed.neva.ru 142 105420 2490 93601 9329 2%73:10:45
5news.albasani.net 3 34471 2449 30165 1857 7%24:21:39
6news-out.mixmin.net 4 33055 2301 29347 1407 6%24:17:11
7news.fcku.it 1 6141 1946 4143 52 31%22:43:34
8etla.org 3 24144 968 23174 2 4%22:27:54
9nntp.pasdenom.info 3 28231 109 28112 10 0%24:08:42
10news.netfront.net 2 44439 87 44337 15 0%22:26:45
11localhost 25 0 0 0 0 0%00:00:00
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 192 390186 27074 341811 21301 6%309:59:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net24.5 MB84.0 KB33.4 MB58.0 MB 42%5.3 KB
2feeder.eternal-september.org15.3 MB44.1 KB35.3 MB50.7 MB 30%5.7 KB
3vc89.vc.panix.com13.4 MB0.0 KB4.3 MB17.7 MB 75%3.6 KB
4newsfeed.neva.ru8.4 MB244.3 KB75.0 MB83.6 MB 9%7.2 KB
5news.albasani.net7.4 MB1.1 KB15.6 MB23.0 MB 32%5.5 KB
6news-out.mixmin.net6.2 MB1.4 KB2.7 MB8.9 MB 69%2.5 KB
7news.fcku.it3.5 MB0.0 KB79.6 KB3.5 MB 97%1.8 KB
8etla.org3.2 MB1.4 KB14.1 KB3.2 MB 99%3.4 KB
9nntp.pasdenom.info239.2 KB2.5 KB73.9 KB315.5 KB 75%2.7 KB
10news.netfront.net202.0 KB2.5 KB973.4 KB1.2 MB 17%11.5 KB
11localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1282.3 MB381.2 KB167.3 MB250.1 MB 32%5.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 29 00:00:03 - 00:59:59 982 3.6% 0.272.9 MB 3.5% 0.82
Sep 29 01:00:00 - 01:59:59 784 2.9% 0.222.5 MB 3.0% 0.70
Sep 29 02:00:00 - 02:59:59 743 2.7% 0.212.7 MB 3.2% 0.76
Sep 29 03:00:00 - 03:59:59 599 2.2% 0.172.1 MB 2.5% 0.59
Sep 29 04:00:00 - 04:59:59 546 2.0% 0.152.7 MB 3.2% 0.76
Sep 29 05:00:00 - 05:59:59 518 1.9% 0.141.7 MB 2.1% 0.48
Sep 29 06:00:00 - 06:59:59 519 1.9% 0.141.9 MB 2.3% 0.55
Sep 29 07:00:00 - 07:59:59 589 2.2% 0.162.2 MB 2.7% 0.64
Sep 29 08:00:00 - 08:59:59 757 2.8% 0.212.3 MB 2.7% 0.64
Sep 29 09:00:00 - 09:59:59 1026 3.8% 0.283.2 MB 3.8% 0.90
Sep 29 10:00:00 - 10:59:59 1234 4.5% 0.344.2 MB 5.0% 1.18
Sep 29 11:00:00 - 11:59:59 1323 4.9% 0.374.4 MB 5.3% 1.24
Sep 29 12:00:00 - 12:59:59 1346 5.0% 0.373.8 MB 4.6% 1.09
Sep 29 13:00:00 - 13:59:59 1222 4.5% 0.343.6 MB 4.3% 1.02
Sep 29 14:00:00 - 14:59:59 1330 4.9% 0.373.8 MB 4.6% 1.08
Sep 29 15:00:00 - 15:59:59 1253 4.6% 0.353.8 MB 4.6% 1.08
Sep 29 16:00:00 - 16:59:59 1543 5.7% 0.434.0 MB 4.9% 1.15
Sep 29 17:00:00 - 17:59:59 1804 6.6% 0.505.1 MB 6.2% 1.46
Sep 29 18:00:00 - 18:59:59 1579 5.8% 0.444.8 MB 5.9% 1.38
Sep 29 19:00:00 - 19:59:59 1604 5.9% 0.454.5 MB 5.5% 1.29
Sep 29 20:00:00 - 20:59:59 1571 5.8% 0.444.7 MB 5.6% 1.32
Sep 29 21:00:00 - 21:59:59 1539 5.7% 0.434.4 MB 5.3% 1.24
Sep 29 22:00:00 - 22:59:59 1419 5.2% 0.393.7 MB 4.5% 1.05
Sep 29 23:00:00 - 23:59:59 1328 4.9% 0.373.7 MB 4.4% 1.04
Sep 30 00:00:00 - 00:00:02 6 0.0% 3.0038.9 KB 0.0% 19.43
TOTAL: 23:59:59 27164 100.0% 0.3182.5 MB 100.0% 0.98
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9433 2128 0 13 0 0 0 0 7292
2linuxd-out.news.weretis.net 3854 1449 0 3 0 1 0 0 2401
3feeder.eternal-september.org 3794 251 0 3 0 0 0 0 3540
4news.albasani.net 1949 146 0 1 0 0 0 0 1802
5news-out.mixmin.net 1405 15 0 1 0 0 0 0 1389
6vc89.vc.panix.com 1057 1 0 0 0 0 0 0 1056
7news.fcku.it 70 50 0 0 0 0 0 0 20
8news.netfront.net 42 1 0 2 0 0 0 0 39
9nntp.pasdenom.info 7 1 0 2 0 0 0 0 4
10etla.org 2 0 0 1 0 0 0 0 1
TOTAL: 10 21613 4042 0 26 0 1 0 017544

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 1045
lada.talk 213
it-alt.sport.calcio.juventus 151
ut.test 150
lada.auto 131
alt.politics 126
mailing.freebsd.pkg-fallout 113
mailing.freebsd.ports-bugs 91
it.comp.os.win.windows10 68
mpc.lists.nanog 61
mozilla.dev.tree-alerts 58
alt.survival 56
mailing.freebsd.svn-src-all 53
fa.freebsd.cvs-all 53
alt.windows7.general 48
tin.it.sport.calcio 48
alt.politics.scorched-earth 47
mailing.freebsd.svn-ports-all 46
mailing.gcc.gcc-cvs 44
pld.cvs.commit 42
TOTAL: 261 4042

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 13697
EMP (phn path) 2021
EMP (phl) 727
EMP (phn nph) 627
PFM bot misc 51
Too many newsgroups 46
Binary: non-binary distribution 35
Binary Payload 26
Binary Image: misplaced jpg 22
User-issued cancel 16
HTML post 9
User-issued spam cancel 4
Binary: misplaced 050 2
Binary: misplaced 281 1
Binary: misplaced rar 1
Binary: misplaced 052 1
Binary: misplaced 383 1
Binary: misplaced 171 1
Binary: misplaced 416 1
Binary: misplaced 161 1
TOTAL: 39 17309

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.albasani.net 90
linuxd-out.news.weretis.net 77
news-out.mixmin.net 18
news.fcku.it 18
vc89.vc.panix.com 17
feeder.eternal-september.org 15
TOTAL: 6 235
TOTAL: 1 235

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 29632 2882 8910 15368 3 160 9%23:59:51
2news.fcku.it 26859 2613 14523 7999 0 3 9%23:59:51
3news.netfront.net 48545 2461 24601 23 1 437 5%23:59:50
4weretis.net 20581 967 18300 0 0 4 4%23:59:51
5etla.org 34027 602 26538 19 0 499 1%23:59:51
6neva.ru 31060 541 23141 910 0 159 1%23:59:51
7news.albasani.net 29729 197 22412 2096 0 83 0%23:59:51
8nntp.pasdenom.info 34997 144 26409 533 0 142 0%23:59:51
9news.stack.nl 44768 141 26850 148 0 954 0%23:59:48
10news.killfile.org 45781 140 25141 1880 1 310 0%23:59:49
11eternal-september 24371 3 21884 4 0 10 0%23:59:51
12news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 12 370350 10691 238709 28980 5 2761 2%264:08:15
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1vc89.vc.panix.com9.2 MB43.0 MB52.2 MB0.6 KB/s2.9 KB23:59:51
2news.netfront.net8.7 MB77.9 KB8.8 MB0.1 KB/s3.6 KB23:59:50
3news.fcku.it8.4 MB30.9 MB39.3 MB0.5 KB/s3.8 KB23:59:51
4etla.org5.9 MB72.5 KB6.0 MB0.1 KB/s9.9 KB23:59:51
5weretis.net1.7 MB0.0 KB1.7 MB0.0 KB/s1.8 KB23:59:51
6neva.ru1.1 MB1.7 MB2.8 MB0.0 KB/s2.0 KB23:59:51
7news.stack.nl612.5 KB370.2 KB982.7 KB0.0 KB/s3.4 KB23:59:48
8news.killfile.org564.1 KB6.8 MB7.3 MB0.1 KB/s3.7 KB23:59:49
9nntp.pasdenom.info523.7 KB1.0 MB1.6 MB0.0 KB/s2.4 KB23:59:51
10news.albasani.net484.5 KB9.8 MB10.2 MB0.1 KB/s4.6 KB23:59:51
11eternal-september9.3 KB7.1 KB16.4 KB0.0 KB/s2.3 KB23:59:51
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 1237.2 MB93.6 MB130.8 MB0.1 KB/s3.4 KB264:08:15
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1f055014082.adsl.alicedsl.de 41 75162.1 KB 55 0 000:01:00
2mtrlpq2314w-lp130-04-1177984284.dsl.bell.ca 64 1666.5 KB 9 0 000:16:45
3mtrlpq2314w-lp130-01-1177633947.dsl.bell.ca 65 844.4 KB 7 0 000:15:42
4x4db4b0c7.dyn.telefonica.de 7 826.0 KB 4 0 000:00:09
TOTAL: 4 177 107299.0 KB 75 0 000:35:09

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.adsl.alicedsl.de 41 75162.1 KB 55 0 000:01:00
2*.dsl.bell.ca 139 24110.9 KB 16 0 000:33:59
3*.dyn.telefonica.de 7 826.0 KB 4 0 000:00:09
TOTAL: 3 187 107299.0 KB 75 0 000:35:09

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mtrlpq2314w-lp130-04-1177984284.dsl.bell.ca 5.644 0.672 0.00000:16:45
mtrlpq2314w-lp130-01-1177633947.dsl.bell.ca 5.108 0.452 0.00000:15:42
mtrlpq2314w-lp140-01-845526728.dsl.bell.ca 0.828 0.084 0.00000:01:31
f055014082.adsl.alicedsl.de 3.104 0.256 0.00000:01:00
x4db4b0c7.dyn.telefonica.de 0.488 0.056 0.00000:00:09
TOTAL: 5 15.172 1.520 0.00000:35:09

Curious NNRP server explorers [Top 20]:

SystemConn
mtrlpq2314w-lp140-01-845526728.dsl.bell.ca 10
TOTAL: 1 10

Newsgroup request counts (by category):

CategoryCountPct
1de 83 77.6%
2alt 22 20.6%
3rec 2 1.9%
TOTAL: 3 107100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1de.alt.rec.digitalfotografie 30
2de.talk.jokes 20
3de.comp.security.misc 9
4alt.atheism 7
5de.comm.technik.dsl 7
6de.etc.finanz.boerse 6
7alt.bible.prophecy 6
8alt.baldspot 4
9de.comm.provider.status 4
10de.alt.sysadmin.recovery 2
11de.alt.technik.gps 2
12alt.fan.rush-limbaugh 2
13de.comp.os.unix.networking.misc 1
14alt.arts.poetry.comments 1
15alt.life.sucks 1
16rec.food.cooking 1
17de.comm.software.mailserver 1
18alt.comp.lang.borland-delphi 1
19de.markt.comp.hardware.misc 1
20rec.boats 1
TOTAL: 20 107