Daily Usenet report for paganini.linuxd.org

Jul 17 00:00:04 -- Jul 18 00:00:06

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jul 17 08:01:34 paganini overchan[21698]: input line too long, skipping
Jul 17 08:05:54 paganini overchan[21698]: input line too long, skipping
Jul 17 08:08:04 paganini overchan[21698]: input line too long, skipping
Jul 17 08:09:03 paganini overchan[21698]: input line too long, skipping
Jul 17 13:10:01 paganini pgpverify[7594]: pgpverify: verification failed
Jul 17 19:27:49 paganini overchan[21698]: input line too long, skipping
Jul 17 20:32:53 paganini overchan[21698]: input line too long, skipping
Jul 18 00:00:06 paganini overchan[7808]: 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 101908 71.2%12.7 MB 59.1%
inn 26828 18.7%6.6 MB 30.9%
innd 8340 5.8%1.4 MB 6.8%
nnrpd 5980 4.2%680.4 KB 3.1%
nocem 83 0.1%13.0 KB 0.1%
overchan 7 0.0%1.2 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 143151 100.0%21.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 81909 53.7%
Negative hits 44424 29.1%
Do not exist 26262 17.2%
Cache misses 44 0.0%
TOTAL: 4 152639 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.557 0.0%127 0.000 4.386 189.000
article cleanup00:00:00.190 0.0%26398 0.000 0.007 0.033
article logging00:00:00.325 0.0%26655 0.000 0.012 0.346
article parse00:00:01.031 0.0%140276 0.000 0.007 0.055
article write00:00:54.417 0.1%18118 0.142 3.003 16.137
artlog/artcncl00:00:00.000 0.0%124 0.000 0.000 0.000
data move00:00:00.124 0.0%272174 0.000 0.000 0.003
hisgrep/artcncl00:00:00.002 0.0%127 0.000 0.016 0.125
hishave/artcncl00:00:00.002 0.0%127 0.000 0.016 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:08.874 0.0%153353 0.013 0.058 1.050
history sync00:00:00.345 0.0%308 0.000 1.120 113.000
history write00:01:54.120 0.1%26398 0.025 4.323 18.360
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:55:33.859 99.7%262132 8.022 328.590 1421.640
nntp read00:00:01.754 0.0%265394 0.001 0.007 0.014
overview write00:00:16.904 0.0%18118 0.032 0.933 5.929
perl filter00:00:52.444 0.1%26398 0.971 1.987 6.645
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.720 0.0%54504 0.000 0.013 0.047
TOTAL: 24:00:23.31823:59:45.668100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:02.526 0.0%2765795 0.000 0.001 0.013
article prepare00:00:00.865 0.0%7997234 0.000 0.000 0.000
article read02:10:32.889 9.1%2736528 0.000 2.862 7.929
backlog stats00:00:01.750 0.0%4924277 0.000 0.000 0.002
callbacks00:00:02.533 0.0%819878 0.000 0.003 0.017
data read00:00:15.834 0.0%2201331 0.001 0.007 0.016
data write00:00:40.185 0.0%2196769 0.005 0.018 0.029
idle21:28:27.806 90.0%4924277 7.634 15.699 489.047
status file00:10:43.347 0.7%42544 8.512 15.122 26.970
TOTAL: 23:51:49.98123:50:47.735 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle02:06:07.237 99.4%20125 28.333 376.012600180.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.000 0.0%40864 0.000 0.024 0.167
readart00:00:07.628 0.1%12840 0.000 0.594 18.000
TOTAL: 02:06:49.44002:06:15.865 99.6%----

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
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 3 40701 15638 22931 2132 38%48:22:03
2newsfeed.neva.ru 147 61007 1848 53734 5425 3%71:44:36
3news.fcku.it 1 6401 630 5033 738 9%22:12:31
4nntp.pasdenom.info 2 0 0 0 0 0%00:20:02
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 178 108109 18116 81698 8295 16%142:39:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net53.3 MB0.0 KB20.5 MB73.8 MB 72%4.3 KB
2newsfeed.neva.ru5.3 MB0.0 KB14.5 MB19.8 MB 26%2.8 KB
3news.fcku.it2.0 MB0.0 KB138.7 MB140.7 MB 1%105.3 KB
4nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 560.6 MB0.0 KB173.7 MB234.3 MB 25%9.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 17 00:00:04 - 00:59:59 796 4.4% 0.222.5 MB 4.0% 0.70
Jul 17 01:00:00 - 01:59:59 632 3.5% 0.182.0 MB 3.3% 0.57
Jul 17 02:00:00 - 02:59:59 476 2.6% 0.131.3 MB 2.1% 0.36
Jul 17 03:00:00 - 03:59:59 443 2.4% 0.126.4 MB 10.5% 1.82
Jul 17 04:00:00 - 04:59:59 464 2.6% 0.132.6 MB 4.2% 0.73
Jul 17 05:00:00 - 05:59:59 384 2.1% 0.111.3 MB 2.1% 0.36
Jul 17 06:00:00 - 06:59:59 390 2.1% 0.112.7 MB 4.5% 0.78
Jul 17 07:00:00 - 07:59:59 393 2.2% 0.111.5 MB 2.5% 0.42
Jul 17 08:00:00 - 08:59:59 539 3.0% 0.151.5 MB 2.5% 0.44
Jul 17 09:00:00 - 09:59:59 619 3.4% 0.171.9 MB 3.1% 0.54
Jul 17 10:00:00 - 10:59:59 696 3.8% 0.192.0 MB 3.3% 0.57
Jul 17 11:00:00 - 11:59:59 777 4.3% 0.222.9 MB 4.7% 0.82
Jul 17 12:00:00 - 12:59:59 880 4.8% 0.242.6 MB 4.4% 0.75
Jul 17 13:00:00 - 13:59:59 888 4.9% 0.252.2 MB 3.7% 0.64
Jul 17 14:00:00 - 14:59:59 890 4.9% 0.252.2 MB 3.6% 0.62
Jul 17 15:00:00 - 15:59:59 890 4.9% 0.252.4 MB 4.0% 0.69
Jul 17 16:00:00 - 16:59:59 817 4.5% 0.232.3 MB 3.8% 0.65
Jul 17 17:00:00 - 17:59:59 1003 5.5% 0.282.6 MB 4.3% 0.75
Jul 17 18:00:00 - 18:59:59 1058 5.8% 0.293.4 MB 5.7% 0.98
Jul 17 19:00:00 - 19:59:59 1130 6.2% 0.313.5 MB 5.7% 0.99
Jul 17 20:00:00 - 20:59:59 1013 5.6% 0.282.8 MB 4.6% 0.80
Jul 17 21:00:00 - 21:59:59 993 5.5% 0.282.5 MB 4.2% 0.73
Jul 17 22:00:00 - 22:59:59 1009 5.6% 0.282.8 MB 4.6% 0.79
Jul 17 23:00:00 - 23:59:59 938 5.2% 0.262.6 MB 4.3% 0.75
Jul 18 00:00:00 - 00:00:06 28 0.2% 4.67111.2 KB 0.2% 18.54
TOTAL: 24:00:02 18146 100.0% 0.2160.7 MB 100.0% 0.72
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 5433 498 0 0 0 0 0 0 4935
2linuxd-out.news.weretis.net 2369 1297 0 0 0 0 0 0 1072
3news.fcku.it 742 0 0 0 0 0 0 0 742
TOTAL: 3 8544 1795 0 0 0 0 0 0 6749

Unwanted newsgroups [Top 20]:

NewsgroupCount
mailing.freebsd.pkg-fallout 533
fa.linux.kernel 181
ut.test 92
mailing.freebsd.ports-bugs 75
lada.talk 55
it-alt.sport.calcio.juventus 43
mailing.freebsd.svn-src-all 32
fa.freebsd.cvs-all 32
alt.windows7.general 30
mailing.freebsd.svn-ports-all 27
mailing.freebsd.svn-ports-head 25
alt.politics.scorched-earth 25
lada.auto 23
mailing.freebsd.ports 21
mailing.freebsd.svn-src-head 20
alt.philosophy 18
mozilla.support.firefox 17
mailing.freebsd.questions 16
cakewalk.audio 16
free.it.religioni.scientology 15
TOTAL: 163 1795

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4070
EMP (phn path) 837
EMP (phl) 657
Binary: misplaced rar 507
EMP (phn nph) 149
PFM bot misc 112
Binary: misplaced PAR2 55
Too many newsgroups 51
Binary Payload 22
User-issued cancel 14
Binary: non-binary distribution 6
User-issued spam cancel 3
Binary Image: misplaced jpg 3
HTML post 2
Binary: misplaced txt 1
Binary: misplaced par2 1
U2 violation - invalid distribution 1
Binary: misplaced nzb 1
TOTAL: 18 6492

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 234
newsfeed.neva.ru 16
news.fcku.it 7
TOTAL: 3 257
TOTAL: 1 257

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 21867 641 15415 1219 0 2 2%23:50:00
2nntp.pasdenom.info 31773 525 16589 828 0 167 1%23:54:15
3neva.ru 24177 126 15785 224 0 19 0%23:50:38
4weretis.net 2799 98 2449 0 0 4 3%23:55:11
5news.stack.nl 2873 0 0 0 0 20913 0%23:59:43
6eternal-september2751175 0 1193212629601475534 3488 0%23:53:30
7etla.org 0 0 0 0 0 17993 0%23:50:00
8news.mixmin.net 0 0 0 0 0 0 0%00:10:00
9news.killfile.org 2868 0 0 0 0 20982 0%23:59:38
10news.albasani.net 2868 0 0 0 0 20214 0%23:59:31
11news.netfront.net 2779 0 0 0 0 20836 0%23:59:44
12vc89.vc.panix.com 2849 0 0 0 0 20960 0%23:59:30
TOTAL: 122846028 1390 6217012652311475534 125578 0%263:21:40
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it1.8 MB4.9 MB6.7 MB0.1 KB/s3.7 KB23:50:00
2nntp.pasdenom.info1.8 MB1.7 MB3.5 MB0.0 KB/s2.6 KB23:54:15
3neva.ru394.7 KB1009.8 KB1.4 MB0.0 KB/s4.0 KB23:50:38
4weretis.net292.6 KB0.0 KB292.6 KB0.0 KB/s3.0 KB23:55:11
5news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:43
6eternal-september0.0 KB4.4 GB4.4 GB53.1 KB/s3.6 KB23:53:30
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:00
8news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
9news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:38
10news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:31
11news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
12vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:30
TOTAL: 124.3 MB4.4 GB4.4 GB4.8 KB/s3.6 KB263:21:40
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-06-1178028286.dsl.bell.ca 118 1841.7 MB 52 0 000:33:50
2challenger.nielsenmedia.com 3 11.5 KB 1 0 000:30:29
371.98.160.36 9 11.5 KB 1 0 001:02:05
TOTAL: 3 130 1861.7 MB 54 0 002:06:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 118 1841.7 MB 52 0 000:33:50
2*.nielsenmedia.com 3 11.5 KB 1 0 000:30:29
3unresolved 9 11.5 KB 1 0 001:02:05
4*.188.104.pools.vodafone-ip.de 119 00.0 KB 0 0 000:00:23
TOTAL: 4 249 1861.7 MB 54 0 002:06:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
71.98.160.36 0.632 0.048 0.00001:02:05
mtrlpq2314w-lp140-06-1178028286.dsl.bell.ca 7.216 1.460 0.00000:33:50
challenger.nielsenmedia.com 0.232 0.020 0.00000:30:29
dslb-188-104-033-040.188.104.pools.vodafone-ip.de 6.352 0.480 0.00000:00:23
TOTAL: 4 14.432 2.008 0.00002:06:48

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-104-033-040.188.104.pools.vodafone-ip.de 119
TOTAL: 1 119

NNRP client timeouts [Top 20]:

SystemConnPeer
71.98.160.36 2 0
challenger.nielsenmedia.com 1 1
TOTAL: 2 3 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 70 37.6%
2rec 45 24.2%
3uk 31 16.7%
4misc 19 10.2%
5soc 5 2.7%
6comp 4 2.2%
7sci 3 1.6%
8talk 3 1.6%
9fr 3 1.6%
10aus 2 1.1%
11us 1 0.5%
TOTAL: 11 186100.0%

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

NewsgroupCount
1rec.arts.tv 44
2alt.christnet.christianlife 27
3misc.survivalism 19
4uk.rec.sheds 10
5alt.home.repair 9
6alt.happy.valley 9
7alt.bumbling.idiots.the.fbi 8
8uk.sci.weather 8
9uk.legal 4
10uk.d-i-y 3
11soc.culture.usa 3
12fr.soc.politique 3
13alt.fan.rush-limbaugh 3
14alt.atheism 3
15alt.usage.english 2
16uk.rec.cycling 2
17talk.politics.guns 2
18comp.lang.awk 2
19aus.politics 2
20alt.comp.os.windows-10 2
21soc.retirement 2
22alt.politics.economics 1
23uk.games.video.misc 1
24alt.life.sucks 1
25alt.support.depression 1
26sci.physics 1
27uk.rec.motorcycles 1
28alt.california 1
29comp.sys.mac.advocacy 1
30sci.physics.relativity 1
31uk.politics.misc 1
32us.military.army 1
33comp.lang.java.programmer 1
34uk.railway 1
35alt.russian.z1 1
36alt.bible.prophecy 1
37sci.electronics.design 1
38alt.sports.football.pro.sd-chargers 1
39rec.arts.tv.uk.coronation-st 1
40talk.politics.misc 1
TOTAL: 40 186