Daily Usenet report for paganini.linuxd.org

Oct 1 00:00:04 -- Oct 2 00:00:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Oct 1 00:10:29 paganini overchan[5274]: input line too long, skipping
Oct 1 02:05:31 paganini overchan[5274]: input line too long, skipping
Oct 1 07:04:04 paganini pgpverify[19186]: pgpverify: verification failed
Oct 1 21:46:24 paganini overchan[5274]: input line too long, skipping
Oct 1 21:46:34 paganini overchan[5274]: input line too long, skipping
Oct 2 00:00:03 paganini overchan[27770]: 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 39070 54.8%9.3 MB 69.8%
innfeed 16422 23.1%2.0 MB 15.3%
innd 10651 15.0%1.4 MB 10.9%
nnrpd 4967 7.0%532.1 KB 3.9%
nocem 117 0.2%16.5 KB 0.1%
controlchan 6 0.0%0.9 KB 0.0%
overchan 6 0.0%0.7 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 71240 100.0%13.3 MB100.0%

History cache:

ReasonCount%Count
Negative hits 54608 40.1%
Positive hits 43077 31.7%
Do not exist 38317 28.2%
Cache misses 64 0.0%
TOTAL: 4 136066 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.581 0.0%185 0.000 3.141 80.000
article cleanup00:00:00.250 0.0%38580 0.000 0.006 0.021
article logging00:00:00.487 0.0%38987 0.000 0.012 1.010
article parse00:00:00.902 0.0%75536 0.000 0.012 0.038
article write00:01:04.505 0.1%26537 0.129 2.431 28.677
artlog/artcncl00:00:00.000 0.0%180 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.114 0.0%181043 0.000 0.001 0.004
hisgrep/artcncl00:00:00.008 0.0%182 0.000 0.044 1.000
hishave/artcncl00:00:00.003 0.0%185 0.000 0.016 0.100
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:09.915 0.0%139318 0.015 0.071 0.958
history sync00:00:00.022 0.0%397 0.000 0.055 0.500
history write00:00:40.246 0.0%38580 0.027 1.043 6.072
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
hiswrite/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:56:58.407 99.7%171859 87.543 501.681 1304.026
nntp read00:00:01.301 0.0%172227 0.002 0.008 0.019
overview write00:00:29.431 0.0%26537 0.010 1.109 5.396
perl filter00:01:13.261 0.1%38580 0.953 1.899 15.096
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.546 0.0%79960 0.000 0.019 0.051
TOTAL: 24:01:09.68124:00:40.979100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.070 0.0%30604 0.000 0.002 0.020
article prepare00:00:00.015 0.0%120706 0.000 0.000 0.003
article read00:00:14.978 0.0%21915 0.016 0.683 6.480
backlog stats00:00:00.275 0.0%564218 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.362 0.0%364343 0.003 0.006 0.014
data write00:00:03.405 0.0%340559 0.003 0.010 0.021
idle23:50:42.705 99.8%564218 98.943 152.145 344.370
status file00:01:34.144 0.1%6051 0.000 15.558 304.000
TOTAL: 23:52:53.45123:52:37.954100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:43:26.529 99.1%12214 34.585 213.405 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.258 0.0%24588 0.000 0.010 0.503
readart00:00:05.517 0.2%6072 0.219 0.909 17.000
TOTAL: 00:43:50.02200:43:32.304 99.3%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
us-control@lists.killfile.org000100
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news.albasani.net 3 35190 17865 9475 7850 50%24:27:42
2vc89.vc.panix.com 1 7035 4323 1939 773 61%24:01:08
3linuxd-out.news.weretis.net 0 15645 2981 11248 1416 19%16:05:52
4feeder.eternal-september.org 3 6566 961 4114 1491 14%05:50:30
5etla.org 0 7759 257 7502 0 3%08:07:01
6news-out.mixmin.net 2 6030 211 5677 142 3%06:46:49
7news.fcku.it 0 981 199 780 2 20%03:10:22
8newsfeed.neva.ru 7 6102 163 5357 582 2%04:47:26
9nntp.pasdenom.info 1 29191 72 29075 44 0%23:59:24
10news.netfront.net 0 9356 40 9243 73 0%04:33:16
11localhost 25 0 0 0 0 0%00:00:00
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 43 123855 27072 84410 12373 21%121:59:30
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1news.albasani.net54.5 MB0.0 KB39.8 MB94.2 MB 57%3.8 KB
2vc89.vc.panix.com16.2 MB0.0 KB2.8 MB18.9 MB 85%3.8 KB
3linuxd-out.news.weretis.net11.0 MB0.0 KB14.0 MB25.0 MB 43%5.8 KB
4feeder.eternal-september.org3.2 MB0.0 KB15.0 MB18.3 MB 17%7.6 KB
5etla.org1.1 MB0.0 KB0.0 KB1.1 MB100%4.2 KB
6news-out.mixmin.net755.6 KB0.0 KB395.0 KB1.1 MB 65%3.3 KB
7newsfeed.neva.ru574.4 KB0.0 KB4.8 MB5.3 MB 10%7.3 KB
8news.fcku.it369.3 KB0.0 KB5.4 KB374.7 KB 98%1.9 KB
9nntp.pasdenom.info218.3 KB111.9 KB207.2 KB537.4 KB 40%4.6 KB
10news.netfront.net127.7 KB51.4 KB3.4 MB3.6 MB 3%32.2 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: 1287.9 MB163.3 KB80.3 MB168.4 MB 52%4.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 1 00:00:04 - 00:59:59 1059 4.0% 0.294.2 MB 4.9% 1.20
Oct 1 01:00:00 - 01:59:59 781 2.9% 0.223.2 MB 3.7% 0.90
Oct 1 02:00:00 - 02:59:59 690 2.6% 0.192.5 MB 2.9% 0.71
Oct 1 03:00:00 - 03:59:59 592 2.2% 0.161.9 MB 2.2% 0.54
Oct 1 04:00:00 - 04:59:59 501 1.9% 0.141.4 MB 1.6% 0.40
Oct 1 05:00:00 - 05:59:59 575 2.2% 0.161.8 MB 2.1% 0.51
Oct 1 06:00:00 - 06:59:59 591 2.2% 0.163.0 MB 3.5% 0.85
Oct 1 07:00:00 - 07:59:59 610 2.3% 0.172.4 MB 2.8% 0.68
Oct 1 08:00:00 - 08:59:59 962 3.6% 0.273.5 MB 4.0% 0.98
Oct 1 09:00:00 - 09:59:59 1171 4.4% 0.333.5 MB 4.1% 1.00
Oct 1 10:00:00 - 10:59:59 1148 4.3% 0.323.9 MB 4.6% 1.12
Oct 1 11:00:00 - 11:59:59 1263 4.8% 0.354.0 MB 4.6% 1.12
Oct 1 12:00:00 - 12:59:59 1191 4.5% 0.333.4 MB 3.9% 0.95
Oct 1 13:00:00 - 13:59:59 1312 5.0% 0.364.8 MB 5.6% 1.36
Oct 1 14:00:00 - 14:59:59 1387 5.2% 0.394.6 MB 5.3% 1.30
Oct 1 15:00:00 - 15:59:59 1473 5.6% 0.414.5 MB 5.2% 1.27
Oct 1 16:00:00 - 16:59:59 1478 5.6% 0.414.3 MB 5.0% 1.23
Oct 1 17:00:00 - 17:59:59 1468 5.5% 0.414.3 MB 5.0% 1.21
Oct 1 18:00:00 - 18:59:59 1456 5.5% 0.404.3 MB 5.0% 1.23
Oct 1 19:00:00 - 19:59:59 1493 5.6% 0.414.8 MB 5.5% 1.35
Oct 1 20:00:00 - 20:59:59 1413 5.3% 0.394.5 MB 5.2% 1.27
Oct 1 21:00:00 - 21:59:59 1373 5.2% 0.384.1 MB 4.8% 1.17
Oct 1 22:00:00 - 22:59:59 1299 4.9% 0.363.6 MB 4.1% 1.01
Oct 1 23:00:00 - 23:59:59 1203 4.5% 0.333.6 MB 4.2% 1.02
Oct 2 00:00:00 - 00:00:03 9 0.0% 3.0034.3 KB 0.0% 11.44
TOTAL: 23:59:59 26498 100.0% 0.3185.9 MB 100.0% 1.02
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.albasani.net 8199 1920 0 0 0 0 0 0 6279
2feeder.eternal-september.org 1492 60 0 0 0 0 0 0 1432
3linuxd-out.news.weretis.net 1337 783 0 0 0 0 0 0 554
4vc89.vc.panix.com 801 1 0 0 0 0 0 0 800
5newsfeed.neva.ru 419 70 0 0 0 0 0 0 349
6news-out.mixmin.net 129 2 0 0 0 0 0 0 127
7nntp.pasdenom.info 17 17 0 0 0 0 0 0 0
TOTAL: 7 12394 2853 0 0 0 0 0 0 9541

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 941
it-alt.sport.calcio.juventus 198
lada.auto 166
lada.talk 164
alt.politics 137
it.comp.os.win.windows10 61
free.it.calcio.juventus 49
alt.job 48
tin.it.sport.calcio 43
alt.survival 43
fa.freebsd.cvs-all 41
alt.politics.scorched-earth 39
fa.openbsd.source-changes 36
ut.test 31
mailing.freebsd.svn-ports-all 29
pld.cvs.commit 28
mailing.freebsd.svn-ports-head 28
alt.windows7.general 26
easynews.support 25
fa.openbsd.tech 24
TOTAL: 324 2853

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6226
EMP (phn path) 1415
EMP (phn nph) 682
EMP (phl) 676
Too many newsgroups 49
User-issued cancel 43
HTML post 26
Binary Payload 14
Binary: non-binary distribution 2
Binary Image: misplaced jpg 1
Binary: misplaced c1Gi 1
TOTAL: 11 9135

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news.albasani.net 353
linuxd-out.news.weretis.net 28
vc89.vc.panix.com 15
feeder.eternal-september.org 10
TOTAL: 4 406
TOTAL: 1 406

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 29095 2344 9299 14858 2 144 8%23:59:51
2news.fcku.it 27618 2010 18129 5868 0 3 7%23:59:51
3news.netfront.net 48388 1150 25207 39 0 416 2%23:59:50
4etla.org 31797 379 26104 19 0 407 1%23:59:39
5neva.ru 30378 200 24254 495 0 45 0%23:59:51
6news.killfile.org 42368 110 23598 1850 1 1330 0%23:59:50
7news.stack.nl 39837 47 26348 65 0 836 0%23:59:41
8nntp.pasdenom.info 8490 32 5050 40 0 23209 0%23:59:50
9weretis.net 23166 24 21783 0 0 264 0%23:59:49
10news.albasani.net 10535 13 7915 725 0 54 0%23:59:51
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12eternal-september 22603 0 21415 0 0 0 0%23:59:51
TOTAL: 12 314275 6309 209102 23959 3 26708 2%264:07:54
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net8.6 MB483.8 KB9.0 MB0.1 KB/s7.8 KB23:59:50
2vc89.vc.panix.com8.1 MB42.5 MB50.6 MB0.6 KB/s3.0 KB23:59:51
3etla.org8.1 MB97.2 KB8.2 MB0.1 KB/s21.0 KB23:59:39
4news.fcku.it7.6 MB27.7 MB35.3 MB0.4 KB/s4.6 KB23:59:51
5neva.ru756.4 KB1.4 MB2.1 MB0.0 KB/s3.1 KB23:59:51
6news.killfile.org391.9 KB7.6 MB8.0 MB0.1 KB/s4.2 KB23:59:50
7news.stack.nl207.2 KB161.7 KB368.9 KB0.0 KB/s3.3 KB23:59:41
8nntp.pasdenom.info81.0 KB105.1 KB186.0 KB0.0 KB/s2.6 KB23:59:50
9weretis.net60.5 KB0.0 KB60.5 KB0.0 KB/s2.5 KB23:59:49
10news.albasani.net36.3 KB4.3 MB4.3 MB0.1 KB/s6.0 KB23:59:51
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
TOTAL: 1233.9 MB84.3 MB118.2 MB0.1 KB/s4.0 KB264:07:54
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-01-1177633947.dsl.bell.ca 130 23398.3 KB 12 0 000:41:51
2f055106025.adsl.alicedsl.de 3 21362.4 KB 7 0 000:00:04
3x55b4cf95.dyn.telefonica.de 11 713.0 KB 7 0 000:00:15
TOTAL: 3 144 51773.7 KB 26 0 000:43:49

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 135 23398.3 KB 12 0 000:43:29
2*.adsl.alicedsl.de 3 21362.4 KB 7 0 000:00:04
3*.dyn.telefonica.de 11 713.0 KB 7 0 000:00:15
TOTAL: 3 149 51773.7 KB 26 0 000:43:49

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mtrlpq2314w-lp130-01-1177633947.dsl.bell.ca 10.724 1.048 0.00000:41:51
mtrlpq2314w-lp130-02-1176035140.dsl.bell.ca 0.436 0.064 0.00000:01:37
x55b4cf95.dyn.telefonica.de 0.808 0.048 0.00000:00:15
f055106025.adsl.alicedsl.de 0.228 0.028 0.00000:00:04
TOTAL: 4 12.196 1.188 0.00000:43:49

Curious NNRP server explorers [Top 20]:

SystemConn
mtrlpq2314w-lp130-02-1176035140.dsl.bell.ca 5
TOTAL: 1 5

Newsgroup request counts (by category):

CategoryCountPct
1de 28 54.9%
2alt 21 41.2%
3talk 1 2.0%
4rec 1 2.0%
TOTAL: 4 51100.0%

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

NewsgroupCount
1de.admin.infos 10
2alt.atheism 10
3de.talk.jokes 8
4alt.bible.prophecy 8
5de.alt.rec.digitalfotografie 5
6de.alt.sysadmin.recovery 3
7alt.usenet.kooks 1
8rec.sport.tennis 1
9talk.origins 1
10alt.baldspot 1
11de.comp.security.misc 1
12de.comm.provider.usenet 1
13alt.fan.rush-limbaugh 1
TOTAL: 13 51