Daily Usenet report for paganini.linuxd.org

May 1 00:00:01 -- May 2 00:00:01

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

May 1 00:00:02 paganini nnrpd[20260]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:17 paganini nnrpd[20476]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:17 paganini nnrpd[20477]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:17 paganini nnrpd[20475]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:32 paganini nnrpd[20493]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:47 paganini nnrpd[20551]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:47 paganini nnrpd[20550]: feeder.eternal-september.org noperm ihave without permission
May 1 00:00:47 paganini nnrpd[20549]: feeder.eternal-september.org noperm ihave without permission
May 1 07:00:01 paganini pgpverify[581]: pgpverify: verification failed
May 1 07:04:03 paganini pgpverify[2196]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 36368 73.4%9.0 MB 83.4%
innd 10683 21.6%1.5 MB 13.6%
innfeed 2361 4.8%321.2 KB 2.9%
nocem 89 0.2%11.7 KB 0.1%
nnrpd 40 0.1%4.5 KB 0.0%
controlchan 8 0.0%1.3 KB 0.0%
pgpverify 2 0.0%0.3 KB 0.0%
TOTAL: 7 49551 100.0%10.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 281981 60.6%
Negative hits 146189 31.4%
Do not exist 35740 7.7%
Cache misses 1306 0.3%
TOTAL: 4 465216 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.521 0.0%196 0.000 2.658 25.556
article cleanup00:00:00.225 0.0%36077 0.000 0.006 0.038
article logging00:00:00.263 0.0%36160 0.000 0.007 0.048
article parse00:00:01.025 0.0%88331 0.000 0.012 0.028
article write00:00:58.433 0.1%24296 0.100 2.405 73.849
artlog/artcncl00:00:00.001 0.0%184 0.000 0.005 0.250
data move00:00:00.176 0.0%506505 0.000 0.000 0.001
hisgrep/artcncl00:00:00.069 0.0%179 0.000 0.385 17.250
hishave/artcncl00:00:00.002 0.0%196 0.000 0.010 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:20.952 0.0%501316 0.012 0.042 0.414
history sync00:00:00.009 0.0%295 0.000 0.031 0.500
history write00:00:56.347 0.1%36007 0.262 1.565 4.789
hiswrite/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
idle23:54:30.158 99.6%491840 34.887 174.996 518.645
nntp read00:00:02.523 0.0%502754 0.002 0.005 0.011
overview write00:00:28.407 0.0%24296 0.032 1.169 6.184
perl filter00:02:00.625 0.1%36007 1.157 3.350 75.057
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.444 0.0%73099 0.000 0.020 0.043
TOTAL: 24:00:19.63723:59:21.180 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.068 0.0%33808 0.000 0.002 0.026
article prepare00:00:00.011 0.0%100194 0.000 0.000 0.004
article read00:00:16.739 0.0%18849 0.000 0.888 23.423
backlog stats00:00:00.198 0.0%518858 0.000 0.000 0.002
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:02.422 0.0%348620 0.004 0.007 0.012
data write00:00:04.090 0.0%327516 0.007 0.012 0.022
idle23:51:27.497 99.9%518858 72.515 165.532 411.743
status file00:00:07.625 0.0%219 0.000 34.817 207.000
TOTAL: 23:52:51.05523:51:58.650 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:02.649 7.8%28 37.333 94.607 343.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.855 26.1%91394 0.000 0.097 0.167
TOTAL: 00:00:33.94200:00:11.504 33.9%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
control@usenet.org.uk000100
us-control@lists.killfile.org000100
TOTAL001201

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 1 57596 6927 47541 3128 12%48:32:19
2feeder.eternal-september.org 8 63754 5179 57244 1331 8%24:39:01
3vc89.vc.panix.com 3 7237 4075 2676 486 56%22:58:22
4news.albasani.net 4 30647 2348 26664 1635 7%23:44:25
5newsfeed.neva.ru 143 81096 2187 75024 3885 2%71:41:14
6news-out.mixmin.net 4 31611 1701 28513 1397 5%24:54:54
7etla.org 1 24308 1291 23015 2 5%24:23:58
8news.fcku.it 7 2065 618 1438 9 29%21:16:31
9news.netfront.net 1 29857 78 29752 27 0%22:02:36
10nntp.pasdenom.info 3 26320 20 26297 3 0%19:22:03
11localhost 25 0 0 0 0 0%00:00:00
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 201 354491 24424 318164 11903 6%303:45:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net25.0 MB29.7 KB24.2 MB49.3 MB 50%5.0 KB
2vc89.vc.panix.com18.8 MB224.4 KB2.3 MB21.3 MB 88%4.8 KB
3feeder.eternal-september.org15.3 MB0.0 KB9.5 MB24.9 MB 61%3.9 KB
4news.albasani.net8.3 MB20.6 KB96.7 MB105.1 MB 7%27.0 KB
5newsfeed.neva.ru6.4 MB206.0 KB11.9 MB18.5 MB 34%3.1 KB
6news-out.mixmin.net4.9 MB9.3 KB2.7 MB7.6 MB 64%2.5 KB
7etla.org4.6 MB41.1 KB41.1 KB4.7 MB 98%3.7 KB
8news.fcku.it2.8 MB0.0 KB31.7 KB2.8 MB 98%4.6 KB
9news.netfront.net166.5 KB6.8 KB1.9 MB2.1 MB 7%20.3 KB
10nntp.pasdenom.info46.4 KB0.0 KB11.3 KB57.7 KB 80%2.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: 1286.2 MB537.9 KB149.4 MB236.1 MB 36%6.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 1 00:00:01 - 00:59:59 1110 4.6% 0.314.1 MB 4.8% 1.16
May 1 01:00:00 - 01:59:59 823 3.4% 0.233.3 MB 3.9% 0.95
May 1 02:00:00 - 02:59:59 768 3.2% 0.212.7 MB 3.1% 0.77
May 1 03:00:00 - 03:59:59 662 2.7% 0.182.2 MB 2.5% 0.62
May 1 04:00:00 - 04:59:59 606 2.5% 0.171.9 MB 2.2% 0.54
May 1 05:00:00 - 05:59:59 560 2.3% 0.161.9 MB 2.3% 0.55
May 1 06:00:00 - 06:59:59 650 2.7% 0.183.6 MB 4.2% 1.02
May 1 07:00:00 - 07:59:59 595 2.4% 0.172.9 MB 3.4% 0.83
May 1 08:00:00 - 08:59:59 632 2.6% 0.182.5 MB 2.9% 0.71
May 1 09:00:00 - 09:59:59 870 3.6% 0.243.8 MB 4.4% 1.07
May 1 10:00:00 - 10:59:59 919 3.8% 0.263.5 MB 4.0% 0.98
May 1 11:00:00 - 11:59:59 1113 4.6% 0.313.8 MB 4.4% 1.08
May 1 12:00:00 - 12:59:59 1050 4.3% 0.293.0 MB 3.5% 0.86
May 1 13:00:00 - 13:59:59 1065 4.4% 0.304.0 MB 4.6% 1.13
May 1 14:00:00 - 14:59:59 1067 4.4% 0.303.0 MB 3.5% 0.85
May 1 15:00:00 - 15:59:59 1128 4.6% 0.313.9 MB 4.6% 1.12
May 1 16:00:00 - 16:59:59 1408 5.8% 0.394.7 MB 5.5% 1.34
May 1 17:00:00 - 17:59:59 1344 5.5% 0.374.3 MB 5.0% 1.21
May 1 18:00:00 - 18:59:59 1478 6.1% 0.415.2 MB 6.1% 1.49
May 1 19:00:00 - 19:59:59 1466 6.0% 0.414.9 MB 5.7% 1.40
May 1 20:00:00 - 20:59:59 1316 5.4% 0.374.6 MB 5.3% 1.30
May 1 21:00:00 - 21:59:59 1405 5.8% 0.395.5 MB 6.4% 1.56
May 1 22:00:00 - 22:59:59 1165 4.8% 0.323.6 MB 4.2% 1.02
May 1 23:00:00 - 00:00:01 1092 4.5% 0.303.0 MB 3.5% 0.85
TOTAL: 24:00:00 24292 100.0% 0.2885.8 MB 100.0% 1.02
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 3869 1630 0 36 1 0 0 0 2202
2linuxd-out.news.weretis.net 3149 1749 0 11 0 0 0 0 1389
3news.albasani.net 1632 116 0 6 0 0 0 0 1510
4feeder.eternal-september.org 1424 468 0 0 0 0 0 0 956
5news-out.mixmin.net 1303 20 0 4 0 0 0 0 1279
6vc89.vc.panix.com 484 13 0 6 0 0 0 0 465
7news.netfront.net 13 0 0 5 0 0 0 0 8
8news.fcku.it 13 0 0 0 0 0 0 0 13
9etla.org 3 0 0 2 0 0 0 0 1
10nntp.pasdenom.info 2 1 0 0 0 0 0 0 1
TOTAL: 10 11892 3997 0 70 1 0 0 0 7824

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 658
alt.politics.scorched-earth 316
mailing.freebsd.pkg-fallout 272
ut.test 173
alt.politics 128
mailing.freebsd.svn-ports-all 101
mailing.freebsd.svn-ports-head 97
lada.talk 77
mailing.freebsd.ports-bugs 77
mailing.openbsd.ports-changes 72
mailing.freebsd.svn-src-all 65
mozilla.dev.platform 62
alt.survival 60
fa.freebsd.cvs-all 60
mozilla.support.seamonkey 57
mailing.freebsd.svn-src-head 56
mozilla.general 51
mailing.gcc.gcc-cvs 49
it-alt.sport.calcio.juventus 44
mailing.openbsd.misc 43
TOTAL: 400 3997

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
linux.kernel 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4668
EMP (phn path) 1366
EMP (phl) 1058
EMP (phn nph) 304
Too many newsgroups 135
PFM bot misc 111
User-issued cancel 30
Too many newsgroups (low_xpost) 21
Binary Image: misplaced jpg 15
Binary: non-binary distribution 15
Binary Payload 13
Too many newsgroups (meow) 4
HTML post 1
TOTAL: 13 7741

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 27210 2339 9535 12005 5 1954 8%23:58:01
2news.netfront.net 43687 1330 22715 67 1 377 3%23:56:33
3etla.org 29732 512 23930 8 0 370 1%24:07:59
4weretis.net 18420 200 16951 0 0 0 1%23:58:18
5neva.ru 26814 184 21574 222 0 92 0%23:59:30
6news.killfile.org 38574 181 21500 2313 5 1918 0%24:03:29
7news.albasani.net 25097 94 20537 1261 0 61 0%23:58:22
8nntp.pasdenom.info 28608 90 23021 983 0 4962 0%23:58:17
9news.fcku.it 25646 41 20596 3206 0 0 0%24:04:45
10news.stack.nl 30636 27 24095 43 0 637 0%24:00:05
11eternal-september 19939 1 18850 1 0 3 0%23:57:06
TOTAL: 11 314363 4999 223304 20109 11 10374 1%264:02:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org13.8 MB129.2 KB13.9 MB0.2 KB/s27.5 KB24:07:59
2vc89.vc.panix.com9.0 MB36.3 MB45.3 MB0.5 KB/s3.2 KB23:58:01
3news.netfront.net6.7 MB4.1 MB10.8 MB0.1 KB/s7.9 KB23:56:33
4weretis.net742.8 KB0.0 KB742.8 KB0.0 KB/s3.7 KB23:58:18
5news.killfile.org621.4 KB8.1 MB8.7 MB0.1 KB/s3.6 KB24:03:29
6neva.ru607.9 KB553.7 KB1.1 MB0.0 KB/s2.9 KB23:59:30
7nntp.pasdenom.info236.6 KB1.7 MB1.9 MB0.0 KB/s1.8 KB23:58:17
8news.albasani.net224.9 KB6.5 MB6.7 MB0.1 KB/s5.1 KB23:58:22
9news.fcku.it122.6 KB9.3 MB9.4 MB0.1 KB/s3.0 KB24:04:45
10news.stack.nl54.3 KB80.0 KB134.3 KB0.0 KB/s1.9 KB24:00:05
11eternal-september1.3 KB1.8 KB3.2 KB0.0 KB/s1.6 KB23:57:06
TOTAL: 1132.1 MB66.6 MB98.7 MB0.1 KB/s4.0 KB264:02:25
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
12607:f350:1:1225::1:4 2 00.0 KB 0 0 000:00:31
2feeder.eternal-september.org 8 00.0 KB 0 0 000:00:02
TOTAL: 2 10 00.0 KB 0 0 000:00:33

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1? 2 00.0 KB 0 0 000:00:31
2*.eternal-september.org 8 00.0 KB 0 0 000:00:02
TOTAL: 2 10 00.0 KB 0 0 000:00:33

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
2607:f350:1:1225::1:4 0.168 0.052 0.00000:00:31
feeder.eternal-september.org 0.436 0.036 0.00000:00:02
TOTAL: 2 0.604 0.088 0.00000:00:33