Daily Usenet report for paganini.linuxd.org

Jan 16 00:00:01 -- Jan 17 00:00:11

Unknown entries from news log file:

First 32 / 32 lines (100.0%)

Jan 16 00:00:02 paganini nnrpd[2831]: news.neva.ru noperm ihave without permission
Jan 16 00:00:02 paganini nnrpd[2832]: aquila.v.weretis.net noperm ihave without permission
Jan 16 00:00:03 paganini nnrpd[2834]: news.mixmin.net noperm ihave without permission
Jan 16 00:00:06 paganini nnrpd[2872]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:06 paganini nnrpd[2871]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:06 paganini nnrpd[2873]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:08 paganini nnrpd[2880]: newsfeed.neva.ru noperm ihave without permission
Jan 16 00:00:08 paganini nnrpd[2881]: ceres.etla.org noperm ihave without permission
Jan 16 00:00:10 paganini nnrpd[3005]: news.netfront.net noperm ihave without permission
Jan 16 00:00:12 paganini nnrpd[3006]: capricornus.v.weretis.net noperm ihave without permission
Jan 16 00:00:14 paganini nnrpd[3008]: vc89.vc.panix.com noperm ihave without permission
Jan 16 00:00:19 paganini nnrpd[3009]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:27 paganini nnrpd[3012]: newsfeed.neva.ru noperm ihave without permission
Jan 16 00:00:33 paganini nnrpd[3049]: aquila.v.weretis.net noperm ihave without permission
Jan 16 00:00:33 paganini nnrpd[3050]: news.mixmin.net noperm ihave without permission
Jan 16 00:00:34 paganini nnrpd[3048]: news.neva.ru noperm ihave without permission
Jan 16 00:00:36 paganini nnrpd[3053]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:36 paganini nnrpd[3051]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:36 paganini nnrpd[3052]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:39 paganini nnrpd[3056]: ceres.etla.org noperm ihave without permission
Jan 16 00:00:39 paganini nnrpd[3055]: newsfeed.neva.ru noperm ihave without permission
Jan 16 00:00:41 paganini nnrpd[3082]: news.netfront.net noperm ihave without permission
Jan 16 00:00:42 paganini nnrpd[3086]: capricornus.v.weretis.net noperm ihave without permission
Jan 16 00:00:44 paganini nnrpd[3087]: vc89.vc.panix.com noperm ihave without permission
Jan 16 00:00:49 paganini nnrpd[3088]: feeder.eternal-september.org noperm ihave without permission
Jan 16 00:00:58 paganini nnrpd[3089]: newsfeed.neva.ru noperm ihave without permission
Jan 16 00:06:40 paganini pgpverify[9623]: pgpverify: verification failed
Jan 16 00:07:14 paganini overchan[11051]: input line too long, skipping
Jan 16 08:42:50 paganini innd: filter: EMP database could not be dumped: File o directory non esistente
Jan 16 08:42:50 paganini innd: SERVER execv /usr/lib/news/bin/innd
Jan 16 08:42:50 paganini innd: logging hist stats to /var/spool/news/www/inn.status
Jan 16 08:43:00 paganini overchan[1923]: 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 149195 78.6%28.8 MB 86.6%
innd 38138 20.1%4.1 MB 12.2%
innfeed 1944 1.0%325.2 KB 1.0%
nocem 391 0.2%46.1 KB 0.1%
nnrpd 164 0.1%16.2 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
overchan 3 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 8 189840 100.0%33.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 395108 36.5%
Negative hits 306210 28.3%
Cache misses 239202 22.1%
Do not exist 143194 13.2%
TOTAL: 4 1083714 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.119 0.0%698 0.000 1.603 20.250
article cleanup00:00:00.626 0.0%148058 0.000 0.004 0.021
article logging00:00:01.031 0.0%148246 0.000 0.007 0.083
article parse00:00:02.941 0.0%417310 0.003 0.007 0.026
article write00:02:03.961 0.1%80066 0.089 1.548 9.873
artlog/artcncl00:00:00.005 0.0%668 0.000 0.007 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.462 0.0%987997 0.000 0.000 0.001
hisgrep/artcncl00:00:00.145 0.0%325 0.000 0.446 9.333
hishave/artcncl00:00:00.008 0.0%698 0.000 0.011 0.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:47.472 0.1%1099074 0.017 0.043 0.237
history sync00:00:00.017 0.0%340 0.000 0.050 1.000
history write00:02:30.646 0.2%148029 0.285 1.018 6.068
hiswrite/artcncl00:00:00.005 0.0%373 0.000 0.013 0.250
idle23:35:37.953 98.9%861231 1.933 98.624 325.816
nntp read00:00:05.341 0.0%957901 0.003 0.006 0.010
overview write00:01:56.687 0.1%80066 0.029 1.457 10.331
perl filter00:05:54.034 0.4%148029 1.096 2.392 8.424
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:21.377 0.0%321102 0.007 0.067 1.044
TOTAL: 23:50:50.38923:49:23.830 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.427 0.0%398384 0.000 0.001 0.012
article prepare00:00:00.032 0.0%219196 0.000 0.000 0.002
article read00:01:04.904 0.1%54139 0.000 1.199 19.156
backlog stats00:00:00.341 0.0%899517 0.000 0.000 0.002
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:03.936 0.0%557097 0.003 0.007 0.015
data write00:00:05.544 0.0%481981 0.006 0.012 0.031
idle23:51:11.642 99.9%899517 4.434 95.464 555.488
status file00:00:08.650 0.0%225 0.000 38.444 156.000
TOTAL: 23:53:01.91623:52:35.476100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:20:39.695 99.7%213 30.667 5820.164 20183.830
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.086 0.0%486 0.000 0.177 0.389
readart00:00:00.003 0.0%14 0.000 0.214 0.250
TOTAL: 00:20:43.14900:20:39.784 99.7%----

Control commands to INND:

CommandNumber
L 1
flush 1
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
throttle 2
xexec 1
TOTAL: 12 167

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1feeder.eternal-september.org 15 83311 24079 42828 16404 28%25:35:52
2linuxd-out.news.weretis.net 10 175893 10257 159806 5830 5%48:22:45
3news-out.mixmin.net 8 89992 5277 81510 3205 5%23:50:36
4vc89.vc.panix.com 3 25115 4290 20335 490 17%23:28:30
5newsfeed.neva.ru 139 104370 3976 84009 16385 3%70:51:54
6etla.org 4 76667 2064 74584 19 2%22:55:32
7news.netfront.net 7 110163 201 109355 607 0%11:57:14
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 211 665511 50144 572427 42940 7%227:02:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feeder.eternal-september.org76.4 MB17.0 KB134.0 MB210.5 MB 36%5.3 KB
2linuxd-out.news.weretis.net53.3 MB14.2 KB50.0 MB103.3 MB 51%6.6 KB
3vc89.vc.panix.com17.1 MB328.7 KB2.7 MB20.1 MB 85%4.3 KB
4news-out.mixmin.net13.6 MB1.7 KB7.0 MB20.6 MB 66%2.5 KB
5newsfeed.neva.ru12.0 MB17.1 KB122.6 MB134.6 MB 8%6.8 KB
6etla.org5.5 MB1.9 KB185.0 KB5.6 MB 96%2.8 KB
7news.netfront.net568.6 KB17.1 KB20.9 MB21.5 MB 2%27.2 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 8178.4 MB397.7 KB337.3 MB516.1 MB 34%5.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 16 00:00:01 - 00:59:59 51485 64.1% 14.31168.9 MB 60.5% 48.07
Jan 16 01:00:00 - 01:59:59 890 1.1% 0.252.9 MB 1.0% 0.81
Jan 16 02:00:00 - 02:59:59 706 0.9% 0.204.0 MB 1.4% 1.13
Jan 16 03:00:00 - 03:59:59 734 0.9% 0.202.8 MB 1.0% 0.81
Jan 16 04:00:00 - 04:59:59 542 0.7% 0.153.9 MB 1.4% 1.11
Jan 16 05:00:00 - 05:59:59 567 0.7% 0.162.8 MB 1.0% 0.79
Jan 16 06:00:00 - 06:59:59 610 0.8% 0.173.2 MB 1.1% 0.90
Jan 16 07:00:00 - 07:59:59 601 0.7% 0.172.1 MB 0.7% 0.59
Jan 16 08:00:00 - 08:59:59 864 1.1% 0.242.6 MB 0.9% 0.74
Jan 16 09:00:00 - 09:59:59 1510 1.9% 0.425.7 MB 2.1% 1.64
Jan 16 10:00:00 - 10:59:59 1552 1.9% 0.435.0 MB 1.8% 1.43
Jan 16 11:00:00 - 11:59:59 1635 2.0% 0.454.6 MB 1.7% 1.32
Jan 16 12:00:00 - 12:59:59 1510 1.9% 0.425.5 MB 2.0% 1.55
Jan 16 13:00:00 - 13:59:59 1413 1.8% 0.393.7 MB 1.3% 1.06
Jan 16 14:00:00 - 14:59:59 1401 1.7% 0.393.5 MB 1.2% 0.99
Jan 16 15:00:00 - 15:59:59 1602 2.0% 0.459.6 MB 3.5% 2.74
Jan 16 16:00:00 - 16:59:59 1710 2.1% 0.477.7 MB 2.7% 2.18
Jan 16 17:00:00 - 17:59:59 1821 2.3% 0.5111.0 MB 3.9% 3.12
Jan 16 18:00:00 - 18:59:59 1736 2.2% 0.487.7 MB 2.8% 2.19
Jan 16 19:00:00 - 19:59:59 1720 2.1% 0.485.0 MB 1.8% 1.43
Jan 16 20:00:00 - 20:59:59 1513 1.9% 0.424.8 MB 1.7% 1.37
Jan 16 21:00:00 - 21:59:59 1511 1.9% 0.424.3 MB 1.5% 1.22
Jan 16 22:00:00 - 22:59:59 1272 1.6% 0.353.7 MB 1.3% 1.06
Jan 16 23:00:00 - 23:59:59 1352 1.7% 0.384.1 MB 1.5% 1.18
Jan 17 00:00:00 - 00:00:11 17 0.0% 1.5577.0 KB 0.0% 7.00
TOTAL: 24:00:10 80274 100.0% 0.93279.2 MB 100.0% 3.31
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1feeder.eternal-september.org 39996 4452 0 6 0 0 0 035538
2newsfeed.neva.ru 16457 1983 0 3 0 0 0 014471
3linuxd-out.news.weretis.net 6097 4334 0 6 0 0 0 0 1757
4news-out.mixmin.net 4348 29 0 1 0 0 0 0 4318
5news.netfront.net 818 31 0 4 0 0 0 0 783
6vc89.vc.panix.com 506 5 0 7 0 0 0 0 494
7etla.org 29 1 0 2 0 0 0 0 26
TOTAL: 7 68251 10835 0 29 0 0 0 057387

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 2047
mailing.freebsd.pkg-fallout 453
it-alt.sport.calcio.juventus 385
lada.talk 361
alt.politics 352
alt.survival 251
mailing.freebsd.svn-ports-all 219
mailing.freebsd.svn-ports-head 205
lada.auto 203
mailing.freebsd.ports-bugs 195
mailing.gcc.gcc-cvs 190
mailing.openbsd.source-changes 163
ut.test 143
mailing.freebsd.svn-src-all 131
gmane.comp.gis.openstreetmap.tagging 130
gmane.linux.alsa.devel 129
fa.openbsd.source-changes 127
fa.freebsd.cvs-all 124
mailing.freebsd.svn-src-head 117
lada.commerce 108
TOTAL: 431 10835

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 19181
EMP (phn path) 13472
EMP (phl) 1598
EMP (phn nph) 350
Binary: misplaced mp3 170
Too many newsgroups 168
Binary: non-binary distribution 59
User-issued cancel 54
PFM bot misc 47
Binary Image: misplaced jpg 42
Too many newsgroups (meow) 35
Binary Payload 35
Binary: misplaced rar 33
HTML post 27
Excessive Supersedes (host-81-190-236-52.wroclaw.mm.pl) 11
Too many newsgroups (low_xpost) 3
Excessive Supersedes (78.194.32.7) 3
Binary: misplaced par2 2
Binary: misplaced pdf 1
Excessive Supersedes (80.161.147.62) 1
TOTAL: 21 35293

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 87364 642 33604 45858 15 46285 0%23:51:43
2etla.org 84939 527 79564 28 0 54517 0%23:56:40
3news.netfront.net 102417 346 78905 180 0 47746 0%23:51:41
4weretis.net 67620 151 66916 1 0 50136 0%23:56:38
5news.killfile.org 102921 79 73095 6929 15 50093 0%23:51:43
6news.stack.nl 80665 19 78945 51 0 33639 0%23:51:43
7eternal-september 28922 14 27723 4 0 4788 0%23:56:40
8neva.ru 74841 9 70850 10 0 31155 0%23:51:43
9news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 9 629689 1787 509602 53061 30 318359 0%191:18:31
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org40.1 MB107.8 KB40.2 MB0.5 KB/s74.2 KB23:56:40
2news.netfront.net19.1 MB3.9 MB23.0 MB0.3 KB/s44.8 KB23:51:41
3vc89.vc.panix.com2.6 MB155.6 MB158.2 MB1.9 KB/s3.5 KB23:51:43
4weretis.net369.3 KB1.3 KB370.6 KB0.0 KB/s2.4 KB23:56:38
5news.killfile.org293.0 KB27.8 MB28.1 MB0.3 KB/s4.1 KB23:51:43
6neva.ru140.8 KB195.0 KB335.9 KB0.0 KB/s17.7 KB23:51:43
7news.stack.nl88.7 KB141.1 KB229.9 KB0.0 KB/s3.3 KB23:51:43
8eternal-september49.3 KB6.0 KB55.2 KB0.0 KB/s3.1 KB23:56:40
9news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 962.7 MB187.7 MB250.4 MB0.4 KB/s4.7 KB191:18:31
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1ppp-158-66.21-151.libero.it 2 1420.8 KB 6 0 000:20:30
TOTAL: 1 2 1420.8 KB 6 0 000:20:43

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.21-151.libero.it 2 1420.8 KB 6 0 000:20:30
2*.v.weretis.net 4 00.0 KB 0 0 000:00:00
3*.netfront.net 2 00.0 KB 0 0 000:00:01
4*.vc.panix.com 2 00.0 KB 0 0 000:00:00
5*.neva.ru 6 00.0 KB 0 0 000:00:06
6*.eternal-september.org 8 00.0 KB 0 0 000:00:02
7*.etla.org 2 00.0 KB 0 0 000:00:00
8*.mixmin.net 2 00.0 KB 0 0 000:00:00
TOTAL: 8 28 1420.8 KB 6 0 000:20:43

NNRP auth users [Top 20]:

UserConn
ivo@paganini.linuxd.org 2
TOTAL: 1 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
ppp-158-66.21-151.libero.it 0.136 0.012 0.06500:20:30
newsfeed.neva.ru 0.240 0.020 0.00000:00:04
news.neva.ru 0.152 0.000 0.00000:00:02
feeder.eternal-september.org 0.556 0.036 0.00000:00:02
news.netfront.net 0.140 0.008 0.00000:00:01
vc89.vc.panix.com 0.132 0.016 0.00000:00:00
ceres.etla.org 0.128 0.004 0.00000:00:00
news.mixmin.net 0.144 0.016 0.00000:00:00
aquila.v.weretis.net 0.144 0.008 0.00000:00:00
capricornus.v.weretis.net 0.120 0.012 0.00000:00:00
TOTAL: 10 1.892 0.132 0.06500:20:43

Curious NNRP server explorers [Top 20]:

SystemConn
feeder.eternal-september.org 8
newsfeed.neva.ru 4
news.netfront.net 2
ceres.etla.org 2
news.neva.ru 2
aquila.v.weretis.net 2
news.mixmin.net 2
capricornus.v.weretis.net 2
vc89.vc.panix.com 2
TOTAL: 9 26

Newsgroup request counts (by category):

CategoryCountPct
1it 7 50.0%
2fr 5 35.7%
3news 2 14.3%
TOTAL: 3 14100.0%

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

NewsgroupCount
1fr.test 5
2it.comp.os.linux.sys 4
3it.news.net-abuse 3
4news.admin.net-abuse.usenet 1
5news.admin.peering 1
TOTAL: 5 14