Daily Usenet report for paganini.linuxd.org

Jul 15 00:00:06 -- Jul 16 00:00:04

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Jul 15 02:01:30 paganini overchan[17066]: input line too long, skipping
Jul 15 09:07:53 paganini overchan[17066]: input line too long, skipping
Jul 15 09:16:08 paganini overchan[17066]: input line too long, skipping
Jul 15 14:08:12 paganini overchan[17066]: input line too long, skipping
Jul 15 15:30:14 paganini overchan[17066]: input line too long, skipping
Jul 15 15:46:23 paganini overchan[17066]: input line too long, skipping
Jul 15 17:00:06 paganini pgpverify[26684]: pgpverify: verification failed
Jul 15 17:40:11 paganini overchan[17066]: input line too long, skipping
Jul 15 18:05:01 paganini overchan[17066]: input line too long, skipping
Jul 15 18:55:53 paganini overchan[17066]: input line too long, skipping
Jul 15 19:17:34 paganini overchan[17066]: input line too long, skipping
Jul 15 19:59:02 paganini overchan[17066]: input line too long, skipping
Jul 15 20:31:41 paganini overchan[17066]: input line too long, skipping
Jul 15 21:41:48 paganini overchan[17066]: input line too long, skipping
Jul 15 22:03:10 paganini overchan[17066]: input line too long, skipping
Jul 16 00:00:04 paganini overchan[3199]: 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
innfeed 112712 67.9%14.0 MB 55.6%
inn 34827 21.0%8.6 MB 34.2%
nnrpd 9593 5.8%1.0 MB 4.2%
innd 8705 5.2%1.5 MB 6.0%
nocem 70 0.0%12.7 KB 0.0%
overchan 16 0.0%2.8 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 165928 100.0%25.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 111118 54.5%
Negative hits 58904 28.9%
Do not exist 33895 16.6%
Cache misses 96 0.0%
TOTAL: 4 204013 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.508 0.0%196 0.000 12.796 144.000
article cleanup00:00:00.230 0.0%34229 0.000 0.007 0.023
article logging00:00:00.509 0.0%34683 0.000 0.015 0.553
article parse00:00:00.763 0.0%54069 0.000 0.014 0.035
article write00:01:06.964 0.1%23861 0.105 2.806 12.843
artlog/artcncl00:00:00.004 0.0%196 0.000 0.020 0.500
data move00:00:00.118 0.0%223596 0.000 0.001 0.002
hisgrep/artcncl00:00:00.174 0.0%193 0.000 0.902 39.000
hishave/artcncl00:00:00.004 0.0%196 0.000 0.020 0.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.637 0.0%205752 0.008 0.047 0.308
history sync00:00:00.138 0.0%395 0.000 0.349 20.333
history write00:03:45.157 0.3%34229 0.037 6.578 25.320
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:53:11.093 99.4%214231 227.027 401.394 1298.169
nntp read00:00:01.642 0.0%221171 0.002 0.007 0.017
overview write00:01:05.590 0.1%23861 0.038 2.749 12.970
perl filter00:01:02.517 0.1%34229 0.885 1.826 6.304
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.351 0.0%71731 0.000 0.019 0.054
TOTAL: 24:01:06.88324:00:28.399100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.127 0.0%39794 0.000 0.003 0.013
article prepare00:00:00.001 0.0%11560 0.000 0.000 0.016
article read00:00:00.941 0.0%2635 0.000 0.357 13.357
backlog stats00:00:00.181 0.0%284892 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.197 0.0%149489 0.002 0.008 0.018
data write00:00:02.088 0.0%125757 0.007 0.017 0.030
idle23:41:45.489 99.2%284892 231.403 299.431 470.720
status file00:11:24.126 0.8%47228 10.140 14.486 33.882
TOTAL: 23:53:30.84823:53:14.150100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:07:40.909 99.7%20828 28.000 713.506450266.250
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:04.428 0.0%42471 0.000 0.104 4.585
readart00:00:07.901 0.1%9164 0.000 0.862 33.000
TOTAL: 04:08:30.45104:07:53.238 99.8%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 55921 19908 32850 3163 35%48:19:59
2newsfeed.neva.ru 145 81214 3051 71256 6907 3%73:34:16
3news.fcku.it 1 10040 1005 8680 355 10%24:07:00
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 3 0 0 0 0 0%00:30:01
TOTAL: 5 178 147175 23964 112786 10425 16%146:31:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net67.4 MB0.0 KB21.7 MB89.1 MB 75%4.0 KB
2newsfeed.neva.ru9.1 MB0.0 KB25.8 MB34.8 MB 26%3.6 KB
3news.fcku.it3.4 MB0.0 KB3.4 MB6.8 MB 50%5.1 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 579.9 MB0.0 KB50.8 MB130.7 MB 61%3.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 15 00:00:06 - 00:59:59 974 4.1% 0.273.3 MB 4.1% 0.94
Jul 15 01:00:00 - 01:59:59 729 3.1% 0.202.0 MB 2.5% 0.57
Jul 15 02:00:00 - 02:59:59 696 2.9% 0.192.4 MB 3.0% 0.69
Jul 15 03:00:00 - 03:59:59 478 2.0% 0.132.5 MB 3.2% 0.72
Jul 15 04:00:00 - 04:59:59 495 2.1% 0.141.8 MB 2.3% 0.51
Jul 15 05:00:00 - 05:59:59 488 2.0% 0.142.0 MB 2.5% 0.57
Jul 15 06:00:00 - 06:59:59 590 2.5% 0.162.3 MB 2.9% 0.67
Jul 15 07:00:00 - 07:59:59 551 2.3% 0.152.0 MB 2.5% 0.57
Jul 15 08:00:00 - 08:59:59 746 3.1% 0.214.5 MB 5.7% 1.28
Jul 15 09:00:00 - 09:59:59 977 4.1% 0.273.5 MB 4.4% 1.01
Jul 15 10:00:00 - 10:59:59 1044 4.4% 0.293.0 MB 3.7% 0.84
Jul 15 11:00:00 - 11:59:59 1180 5.0% 0.333.2 MB 4.0% 0.91
Jul 15 12:00:00 - 12:59:59 1197 5.0% 0.333.2 MB 4.1% 0.92
Jul 15 13:00:00 - 13:59:59 1114 4.7% 0.312.7 MB 3.4% 0.77
Jul 15 14:00:00 - 14:59:59 1166 4.9% 0.323.4 MB 4.2% 0.95
Jul 15 15:00:00 - 15:59:59 1264 5.3% 0.353.8 MB 4.8% 1.08
Jul 15 16:00:00 - 16:59:59 1272 5.3% 0.354.0 MB 5.0% 1.14
Jul 15 17:00:00 - 17:59:59 1254 5.3% 0.354.7 MB 6.0% 1.35
Jul 15 18:00:00 - 18:59:59 1276 5.4% 0.355.3 MB 6.7% 1.50
Jul 15 19:00:00 - 19:59:59 1411 5.9% 0.396.0 MB 7.5% 1.70
Jul 15 20:00:00 - 20:59:59 1291 5.4% 0.363.6 MB 4.6% 1.03
Jul 15 21:00:00 - 21:59:59 1210 5.1% 0.343.2 MB 4.0% 0.91
Jul 15 22:00:00 - 22:59:59 1265 5.3% 0.353.5 MB 4.4% 1.00
Jul 15 23:00:00 - 23:59:59 1159 4.9% 0.323.4 MB 4.3% 0.97
Jul 16 00:00:00 - 00:00:04 11 0.0% 2.7549.9 KB 0.1% 12.48
TOTAL: 23:59:58 23838 100.0% 0.2879.5 MB 100.0% 0.94
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 6851 1478 0 0 0 0 0 0 5373
2linuxd-out.news.weretis.net 3580 2045 0 0 0 0 0 0 1535
3news.fcku.it 363 0 0 0 0 0 0 0 363
TOTAL: 3 10794 3523 0 0 0 0 0 0 7271

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 805
mailing.freebsd.pkg-fallout 282
lada.talk 239
it-alt.sport.calcio.juventus 164
mailing.freebsd.ports-bugs 94
mailing.freebsd.svn-src-all 73
fa.freebsd.cvs-all 72
alt.sex.breasts 66
mailing.freebsd.svn-src-head 64
alt.windows7.general 63
lada.auto 60
ut.test 57
mailing.freebsd.svn-ports-all 51
mailing.gcc.gcc-cvs 49
mailing.freebsd.svn-ports-head 49
gmane.linux.alsa.devel 49
mailing.database.pgsql-general 44
mailing.freebsd.bugs 35
fa.freebsd.bugs 35
mailing.freebsd.arm 31
TOTAL: 244 3523

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4684
EMP (phn path) 1023
EMP (phl) 586
PFM bot misc 223
EMP (phn nph) 131
Too many newsgroups 114
User-issued cancel 20
Binary Payload 17
Binary: non-binary distribution 10
Too many newsgroups (meow) 3
MID-Bot 2
Too many newsgroups (low_xpost) 1
HTML Multipart without Text/Plain. 1
TOTAL: 13 6815

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 427
newsfeed.neva.ru 19
news.fcku.it 10
TOTAL: 3 456
TOTAL: 1 456

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 29770 831 20670 1343 0 83 2%23:59:51
2nntp.pasdenom.info 13340 178 6335 84 0 18976 1%23:57:45
3weretis.net 4435 156 3847 0 0 17 3%23:59:46
4neva.ru 31055 138 20459 175 0 94 0%23:59:52
5news.mixmin.net 0 0 0 0 0 0 0%00:10:00
6news.albasani.net 2859 0 0 0 0 25901 0%23:59:52
7news.killfile.org 2843 0 0 0 0 26677 0%23:59:52
8news.netfront.net 2747 0 0 0 0 26498 0%23:59:51
9news.stack.nl 2862 0 0 0 0 26599 0%23:59:52
10vc89.vc.panix.com 2833 0 0 0 0 26667 0%23:59:51
11eternal-september 0 0 0 0 0 19728 0%23:59:52
12etla.org 0 0 0 0 0 23835 0%23:59:52
TOTAL: 12 92744 1303 51311 1602 0 195075 1%264:06:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it2.1 MB4.2 MB6.3 MB0.1 KB/s3.0 KB23:59:51
2nntp.pasdenom.info807.2 KB287.8 KB1.1 MB0.0 KB/s4.2 KB23:57:45
3weretis.net745.9 KB0.0 KB745.9 KB0.0 KB/s4.8 KB23:59:46
4neva.ru604.2 KB1.1 MB1.7 MB0.0 KB/s5.5 KB23:59:52
5news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
6news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
7news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
8news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
9news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
10vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
11eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
12etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
TOTAL: 124.2 MB5.6 MB9.8 MB0.0 KB/s3.5 KB264:06:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-05-1178027065.dsl.bell.ca 105 2762.2 MB 139 0 000:36:31
2challenger.nielsenmedia.com 13 825.7 KB 3 0 002:07:20
371.98.160.36 12 27.2 KB 2 0 001:24:15
TOTAL: 3 130 2862.3 MB 144 0 004:08:29

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 105 2762.2 MB 139 0 000:36:31
2*.nielsenmedia.com 13 825.7 KB 3 0 002:07:20
3unresolved 12 27.2 KB 2 0 001:24:15
4*.188.099.pools.vodafone-ip.de 113 00.0 KB 0 0 000:00:22
TOTAL: 4 243 2862.3 MB 144 0 004:08:29

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.972 0.060 0.00002:07:20
71.98.160.36 0.792 0.052 0.00001:24:15
mtrlpq2314w-lp130-05-1178027065.dsl.bell.ca 9.736 1.552 0.00000:36:31
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 5.824 0.412 0.00000:00:22
TOTAL: 4 17.324 2.076 0.00004:08:29

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 113
TOTAL: 1 113

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 4 0
71.98.160.36 2 0
TOTAL: 2 6 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 114 39.9%
2rec 46 16.1%
3uk 45 15.7%
4fr 28 9.8%
5comp 23 8.0%
6misc 9 3.1%
7talk 8 2.8%
8linux 4 1.4%
9can 3 1.0%
10aus 3 1.0%
11soc 2 0.7%
12sci 1 0.3%
TOTAL: 12 286100.0%

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

NewsgroupCount
1rec.arts.tv 37
2alt.christnet.christianlife 32
3alt.fan.rush-limbaugh 29
4fr.soc.politique 26
5alt.atheism 21
6alt.california 17
7uk.legal 15
8uk.rec.sheds 13
9comp.lang.awk 10
10misc.survivalism 9
11comp.mobile.android 6
12talk.politics.guns 6
13uk.d-i-y 6
14alt.home.repair 5
15linux.kernel 4
16uk.railway 4
17rec.boats 3
18aus.politics 3
19alt.comp.os.windows-10 3
20rec.sport.pro-wrestling 3
21can.politics 3
22alt.os.linux.debian 2
23alt.happy.valley 2
24comp.lang.python 2
25uk.sci.weather 2
26fr.rec.photo 2
27comp.lang.fortran 2
28talk.politics.misc 2
29uk.media.radio.archers 2
30alt.comp.freeware 2
31comp.lang.lisp 1
32rec.sport.golf 1
33soc.retirement 1
34uk.games.video.misc 1
35sci.physics 1
36rec.gambling.poker 1
37alt.os.linux 1
38soc.culture.usa 1
39uk.rec.cycling 1
40comp.lang.perl.misc 1
41comp.lang.php 1
42rec.arts.sf.written 1
43uk.rec.gardening 1
TOTAL: 43 286