Daily Usenet report for paganini.linuxd.org

May 4 00:00:01 -- May 5 00:00:01

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

May 4 00:00:02 paganini nnrpd[17031]: feeder.eternal-september.org rejected Flushing log and syslog files
May 4 00:00:02 paganini nnrpd[17028]: feeder.eternal-september.org noperm ihave without permission
May 4 00:00:27 paganini nnrpd[17338]: feeder.eternal-september.org noperm ihave without permission
May 4 00:00:27 paganini nnrpd[17339]: feeder.eternal-september.org noperm ihave without permission
May 4 00:00:32 paganini nnrpd[17340]: feeder.eternal-september.org noperm ihave without permission
May 4 00:00:57 paganini nnrpd[17406]: feeder.eternal-september.org noperm ihave without permission
May 4 00:00:57 paganini nnrpd[17407]: feeder.eternal-september.org noperm ihave without permission
May 4 11:49:30 paganini pgpverify[27890]: pgpverify: verification failed
May 4 14:45:38 paganini overchan[3895]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 44324 70.0%10.2 MB 81.4%
innd 16545 26.1%2.0 MB 15.9%
innfeed 2331 3.7%334.6 KB 2.6%
nocem 121 0.2%15.6 KB 0.1%
nnrpd 28 0.0%3.1 KB 0.0%
controlchan 3 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
overchan 1 0.0%0.2 KB 0.0%
TOTAL: 8 63354 100.0%12.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 324546 59.9%
Negative hits 173428 32.0%
Do not exist 43858 8.1%
Cache misses 88 0.0%
TOTAL: 4 541920 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.310 0.0%227 0.000 1.366 17.875
article cleanup00:00:00.260 0.0%44126 0.000 0.006 0.020
article logging00:00:00.803 0.0%44184 0.000 0.018 0.434
article parse00:00:01.220 0.0%128239 0.000 0.010 0.027
article write00:00:40.016 0.0%26870 0.099 1.489 8.467
artlog/artcncl00:00:00.001 0.0%174 0.000 0.006 0.500
data move00:00:00.233 0.0%590086 0.000 0.000 0.002
hisgrep/artcncl00:00:00.032 0.0%226 0.000 0.142 15.000
hishave/artcncl00:00:00.004 0.0%227 0.000 0.018 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:32.803 0.0%570475 0.014 0.058 0.486
history sync00:00:00.045 0.0%300 0.000 0.150 17.000
history write00:01:01.800 0.1%44123 0.053 1.401 5.800
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:53:49.119 99.5%567652 30.418 151.553 654.842
nntp read00:00:03.002 0.0%582930 0.002 0.005 0.010
overview write00:00:30.760 0.0%26870 0.012 1.145 4.969
perl filter00:02:39.130 0.2%44123 1.296 3.607 78.759
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.578 0.0%80834 0.000 0.020 0.051
TOTAL: 24:00:19.12423:59:21.116 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.098 0.0%29563 0.000 0.003 0.020
article prepare00:00:00.012 0.0%109944 0.000 0.000 0.004
article read00:00:17.430 0.0%21019 0.000 0.829 12.909
backlog stats00:00:00.256 0.0%580898 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.723 0.0%389272 0.002 0.007 0.011
data write00:00:04.348 0.0%366638 0.006 0.012 0.021
idle23:51:48.136 99.9%580898 82.365 147.889 504.691
status file00:00:06.274 0.0%186 0.000 33.731 475.000
TOTAL: 23:52:36.42523:52:19.277100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:00.688 55.3%18 37.333 38.222 40.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.001 0.1%37 0.000 0.027 0.167
TOTAL: 00:00:01.24500:00:00.689 55.3%----

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
admin@openwatcom.news-admin.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 2 63119 7874 52352 2893 12%48:52:44
2feeder.eternal-september.org 8 66157 6494 55303 4360 9%25:01:04
3vc89.vc.panix.com 1 7435 4166 2536 733 56%23:44:19
4newsfeed.neva.ru 146 96378 2613 87012 6753 2%72:02:05
5news.albasani.net 4 33985 2547 29614 1824 7%24:19:42
6etla.org 1 28593 1636 26957 0 5%25:52:45
7news.fcku.it 9 3437 812 2617 8 23%23:31:41
8news-out.mixmin.net 3 29789 699 28462 628 2%22:38:40
9news.netfront.net 2 30005 60 29886 59 0%22:12:36
10nntp.pasdenom.info 1 18147 55 18091 1 0%19:10:59
11localhost 25 0 0 0 0 0%00:00:00
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 203 377045 26956 332830 17259 7%307:36:35
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net25.2 MB1.6 KB14.5 MB39.6 MB 63%3.8 KB
2vc89.vc.panix.com22.2 MB0.0 KB5.6 MB27.8 MB 79%5.8 KB
3feeder.eternal-september.org18.6 MB1.6 KB44.6 MB63.2 MB 29%6.0 KB
4news.albasani.net10.6 MB0.0 KB95.6 MB106.2 MB 9%24.9 KB
5newsfeed.neva.ru8.1 MB13.6 KB43.2 MB51.2 MB 15%5.6 KB
6etla.org5.4 MB0.0 KB0.0 KB5.4 MB100%3.4 KB
7news.fcku.it2.7 MB0.0 KB13.5 KB2.7 MB 99%3.3 KB
8news-out.mixmin.net2.2 MB0.0 KB1.4 MB3.6 MB 61%2.8 KB
9news.netfront.net176.3 KB0.0 KB5.1 MB5.3 MB 3%45.7 KB
10nntp.pasdenom.info112.2 KB0.0 KB0.9 KB113.1 KB 99%2.0 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: 1295.1 MB16.8 KB210.0 MB305.1 MB 31%7.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 4 00:00:01 - 00:59:59 1037 3.9% 0.295.3 MB 5.5% 1.50
May 4 01:00:00 - 01:59:59 636 2.4% 0.182.1 MB 2.3% 0.61
May 4 02:00:00 - 02:59:59 574 2.1% 0.161.8 MB 1.9% 0.52
May 4 03:00:00 - 03:59:59 604 2.2% 0.172.3 MB 2.4% 0.64
May 4 04:00:00 - 04:59:59 444 1.7% 0.121.4 MB 1.5% 0.40
May 4 05:00:00 - 05:59:59 404 1.5% 0.112.9 MB 3.0% 0.82
May 4 06:00:00 - 06:59:59 583 2.2% 0.164.4 MB 4.6% 1.25
May 4 07:00:00 - 07:59:59 549 2.0% 0.154.3 MB 4.6% 1.23
May 4 08:00:00 - 08:59:59 803 3.0% 0.222.6 MB 2.8% 0.75
May 4 09:00:00 - 09:59:59 1003 3.7% 0.282.6 MB 2.8% 0.75
May 4 10:00:00 - 10:59:59 1206 4.5% 0.344.2 MB 4.4% 1.20
May 4 11:00:00 - 11:59:59 1243 4.6% 0.353.9 MB 4.1% 1.10
May 4 12:00:00 - 12:59:59 1253 4.7% 0.353.6 MB 3.8% 1.04
May 4 13:00:00 - 13:59:59 1194 4.4% 0.333.8 MB 4.0% 1.07
May 4 14:00:00 - 14:59:59 1266 4.7% 0.353.9 MB 4.1% 1.12
May 4 15:00:00 - 15:59:59 1432 5.3% 0.404.8 MB 5.1% 1.38
May 4 16:00:00 - 16:59:59 1640 6.1% 0.467.1 MB 7.5% 2.03
May 4 17:00:00 - 17:59:59 1651 6.1% 0.465.5 MB 5.8% 1.56
May 4 18:00:00 - 18:59:59 1519 5.7% 0.424.9 MB 5.1% 1.39
May 4 19:00:00 - 19:59:59 1826 6.8% 0.515.7 MB 6.0% 1.62
May 4 20:00:00 - 20:59:59 1573 5.9% 0.444.6 MB 4.8% 1.30
May 4 21:00:00 - 21:59:59 1531 5.7% 0.434.9 MB 5.1% 1.39
May 4 22:00:00 - 22:59:59 1467 5.5% 0.414.3 MB 4.5% 1.22
May 4 23:00:00 - 23:59:59 1421 5.3% 0.394.0 MB 4.2% 1.14
May 5 00:00:00 - 00:00:01 2 0.0% 2.007.2 KB 0.0% 7.25
TOTAL: 24:00:00 26861 100.0% 0.3195.0 MB 100.0% 1.13
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 6695 1987 0 1 0 0 0 0 4707
2feeder.eternal-september.org 4418 425 0 1 0 0 0 0 3992
3linuxd-out.news.weretis.net 2886 1162 0 1 0 0 0 0 1723
4news.albasani.net 1821 104 0 0 0 0 0 0 1717
5vc89.vc.panix.com 742 13 0 0 0 0 0 0 729
6news-out.mixmin.net 686 4 0 0 0 0 0 0 682
7news.fcku.it 24 0 0 0 0 0 0 0 24
8news.netfront.net 18 0 0 0 0 0 0 0 18
9nntp.pasdenom.info 2 2 0 0 0 0 0 0 0
10etla.org 1 0 0 0 0 0 0 0 1
TOTAL: 10 17293 3697 0 3 0 0 0 013593

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 847
alt.politics.scorched-earth 175
mailing.freebsd.ports-bugs 116
ut.test 114
it-alt.sport.calcio.juventus 87
lada.talk 84
mailing.freebsd.svn-src-all 81
fa.freebsd.cvs-all 81
mailing.freebsd.svn-ports-all 76
mailing.freebsd.svn-ports-head 76
mailing.freebsd.svn-src-head 73
lada.auto 60
lada.commerce 59
pld.cvs.commit 48
24hoursupport.helpdesk 47
alt.politics 46
gmane.linux.alsa.devel 44
mailing.freebsd.wireless 43
mpc.lists.freebsd.wireless 41
tin.it.sport.calcio 40
TOTAL: 277 3697

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 7809
EMP (phn path) 3928
EMP (phl) 1093
EMP (phn nph) 401
Too many newsgroups 180
User-issued cancel 42
Binary Image: misplaced jpg 19
Binary Payload 18
Binary: non-binary distribution 17
Binary: misplaced sfv 6
Too many newsgroups (meow) 6
HTML post 6
Binary: misplaced nzb 6
Binary: misplaced pdf 2
Too many newsgroups (low_xpost) 1
Binary: misplaced com 1
TOTAL: 16 13535

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 30781 2137 10461 14349 1 315 6%24:05:19
2news.netfront.net 48158 1246 25338 87 1 634 2%23:56:33
3etla.org 32364 502 26273 3 0 501 1%23:57:58
4news.albasani.net 28400 244 22578 1543 0 169 0%24:02:14
5weretis.net 19658 221 18394 0 0 14 1%23:56:35
6news.killfile.org 44262 190 23943 2816 0 344 0%24:04:52
7neva.ru 28514 170 23594 273 0 19 0%23:59:30
8nntp.pasdenom.info 32922 123 25566 1064 0 206 0%24:01:15
9news.fcku.it 28196 50 23510 2599 0 9 0%24:04:46
10news.stack.nl 33925 20 26624 46 0 774 0%24:00:12
11eternal-september 21213 13 20002 8 0 0 0%23:57:02
TOTAL: 11 348393 4916 246283 22788 2 2985 1%264:06:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org16.1 MB32.8 KB16.1 MB0.2 KB/s32.7 KB23:57:58
2news.netfront.net10.9 MB5.7 MB16.6 MB0.2 KB/s12.8 KB23:56:33
3vc89.vc.panix.com7.5 MB40.0 MB47.5 MB0.6 KB/s3.0 KB24:05:19
4neva.ru813.6 KB774.2 KB1.6 MB0.0 KB/s3.6 KB23:59:30
5news.killfile.org781.6 KB9.4 MB10.2 MB0.1 KB/s3.5 KB24:04:52
6eternal-september637.6 KB13.0 KB650.7 KB0.0 KB/s31.0 KB23:57:02
7news.albasani.net542.2 KB10.9 MB11.5 MB0.1 KB/s6.6 KB24:02:14
8weretis.net455.5 KB0.0 KB455.5 KB0.0 KB/s2.1 KB23:56:35
9nntp.pasdenom.info358.2 KB2.0 MB2.4 MB0.0 KB/s2.1 KB24:01:15
10news.fcku.it117.8 KB7.9 MB8.0 MB0.1 KB/s3.1 KB24:04:46
11news.stack.nl100.0 KB99.9 KB199.9 KB0.0 KB/s3.0 KB24:00:12
TOTAL: 1138.2 MB76.9 MB115.1 MB0.1 KB/s4.3 KB264:06:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1feeder.eternal-september.org 7 00.0 KB 0 0 000:00:01
TOTAL: 1 7 00.0 KB 0 0 000:00:01

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.eternal-september.org 7 00.0 KB 0 0 000:00:01
TOTAL: 1 7 00.0 KB 0 0 000:00:01

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
feeder.eternal-september.org 0.372 0.040 0.00000:00:01
TOTAL: 1 0.372 0.040 0.00000:00:01