Daily Usenet report for paganini.linuxd.org

Oct 5 00:00:02 -- Oct 6 00:00:02

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Oct 5 07:27:30 paganini overchan[3769]: input line too long, skipping
Oct 6 00:00:02 paganini overchan[24648]: 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 112503 66.6%13.8 MB 55.9%
inn 39410 23.3%8.5 MB 34.5%
innd 16567 9.8%2.3 MB 9.4%
nnrpd 438 0.3%60.3 KB 0.2%
nocem 21 0.0%3.4 KB 0.0%
overchan 3 0.0%0.4 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 7 168944 100.0%24.7 MB100.0%

History cache:

ReasonCount%Count
Positive hits 103764 47.4%
Negative hits 76261 34.9%
Do not exist 38680 17.7%
Cache misses 66 0.0%
TOTAL: 4 218771 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.114 0.0%119 0.000 0.958 8.000
article cleanup00:00:00.257 0.0%38927 0.000 0.007 0.029
article logging00:00:00.597 0.0%39364 0.000 0.015 0.601
article parse00:00:00.966 0.0%82897 0.002 0.012 0.029
article write00:00:46.813 0.1%22448 0.077 2.085 8.623
artlog/artcncl00:00:00.000 0.0%114 0.000 0.000 0.000
data move00:00:00.162 0.0%241257 0.000 0.001 0.003
hisgrep/artcncl00:00:00.004 0.0%119 0.000 0.034 0.250
hishave/artcncl00:00:00.005 0.0%119 0.000 0.042 0.300
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.768 0.0%219961 0.017 0.049 0.453
history sync00:00:00.882 0.0%399 0.000 2.211 153.333
history write00:00:35.185 0.0%38927 0.023 0.904 7.824
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:56:51.123 99.7%240927 227.161 357.831 1006.760
nntp read00:00:01.936 0.0%245504 0.002 0.008 0.014
overview write00:00:24.813 0.0%22448 0.013 1.105 6.154
perl filter00:01:24.958 0.1%38927 0.989 2.182 5.379
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.373 0.0%67491 0.000 0.020 0.056
TOTAL: 24:01:08.33124:00:19.956 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.105 0.0%38229 0.000 0.003 0.013
article prepare00:00:00.002 0.0%14400 0.000 0.000 0.016
article read00:00:01.031 0.0%2861 0.000 0.360 33.059
backlog stats00:00:00.192 0.0%324405 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.380 0.0%172812 0.001 0.008 0.018
data write00:00:02.265 0.0%150499 0.007 0.015 0.028
idle23:41:58.348 99.2%324405 188.450 262.999 435.261
status file00:10:53.423 0.8%47096 7.941 13.874 38.297
TOTAL: 23:53:19.25423:52:56.746100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:59:14.893 99.8%382 28.333 47002.338600139.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:22.842 0.1%1073 0.000 21.288 169.111
readart00:00:00.176 0.0%5473 0.000 0.032 0.032
TOTAL: 04:59:52.95704:59:37.911 99.9%----

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 50651 17731 30570 2350 35%47:45:47
2newsfeed.neva.ru 142 83172 2937 66456 13779 3%72:13:50
3news.fcku.it 1 8923 1648 6917 358 18%23:28:07
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 2 0 0 0 0 0%00:20:02
TOTAL: 5 174 142746 22316 103943 16487 15%143:47:46
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net57.3 MB0.0 KB14.5 MB71.8 MB 79%3.7 KB
2newsfeed.neva.ru9.1 MB0.0 KB111.3 MB120.4 MB 7%7.4 KB
3news.fcku.it4.6 MB0.0 KB1.5 MB6.1 MB 75%3.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: 571.1 MB0.0 KB127.3 MB198.3 MB 35%5.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 5 00:00:02 - 00:59:59 827 3.7% 0.232.4 MB 3.4% 0.69
Oct 5 01:00:00 - 01:59:59 730 3.3% 0.202.3 MB 3.2% 0.65
Oct 5 02:00:00 - 02:59:59 629 2.8% 0.171.8 MB 2.5% 0.51
Oct 5 03:00:00 - 03:59:59 449 2.0% 0.121.5 MB 2.1% 0.42
Oct 5 04:00:00 - 04:59:59 448 2.0% 0.121.4 MB 1.9% 0.40
Oct 5 05:00:00 - 05:59:59 470 2.1% 0.131.7 MB 2.3% 0.48
Oct 5 06:00:00 - 06:59:59 513 2.3% 0.145.0 MB 7.0% 1.42
Oct 5 07:00:00 - 07:59:59 490 2.2% 0.142.6 MB 3.6% 0.73
Oct 5 08:00:00 - 08:59:59 687 3.1% 0.191.8 MB 2.5% 0.51
Oct 5 09:00:00 - 09:59:59 923 4.1% 0.262.8 MB 4.0% 0.81
Oct 5 10:00:00 - 10:59:59 1018 4.5% 0.282.7 MB 3.7% 0.76
Oct 5 11:00:00 - 11:59:59 1017 4.5% 0.282.7 MB 3.7% 0.75
Oct 5 12:00:00 - 12:59:59 1025 4.6% 0.282.8 MB 3.9% 0.78
Oct 5 13:00:00 - 13:59:59 894 4.0% 0.252.4 MB 3.3% 0.67
Oct 5 14:00:00 - 14:59:59 1055 4.7% 0.292.9 MB 4.1% 0.83
Oct 5 15:00:00 - 15:59:59 1122 5.0% 0.315.6 MB 7.9% 1.60
Oct 5 16:00:00 - 16:59:59 1411 6.3% 0.394.3 MB 6.1% 1.23
Oct 5 17:00:00 - 17:59:59 1390 6.2% 0.394.0 MB 5.6% 1.13
Oct 5 18:00:00 - 18:59:59 1261 5.6% 0.353.3 MB 4.6% 0.93
Oct 5 19:00:00 - 19:59:59 1346 6.0% 0.373.8 MB 5.3% 1.08
Oct 5 20:00:00 - 20:59:59 1278 5.7% 0.353.9 MB 5.4% 1.10
Oct 5 21:00:00 - 21:59:59 1142 5.1% 0.323.7 MB 5.2% 1.05
Oct 5 22:00:00 - 22:59:59 1154 5.1% 0.323.1 MB 4.3% 0.87
Oct 5 23:00:00 - 23:59:59 1143 5.1% 0.323.1 MB 4.4% 0.88
Oct 6 00:00:00 - 00:00:02 4 0.0% 2.0015.8 KB 0.0% 7.88
TOTAL: 24:00:00 22426 100.0% 0.2671.4 MB 100.0% 0.85
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 13719 1115 0 0 0 0 0 012604
2linuxd-out.news.weretis.net 2772 627 0 0 0 0 0 0 2145
3news.fcku.it 379 1 0 0 1 0 0 0 377
TOTAL: 3 16870 1743 0 0 1 0 0 015126

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 486
lada.talk 235
ut.test 148
alt.survival 60
alt.windows7.general 53
fa.freebsd.cvs-all 52
fa.openbsd.source-changes 44
lada.auto 41
it-alt.sport.calcio.juventus 39
fa.openbsd.tech 26
mozilla.support.thunderbird 19
fa.netbsd.bugs 18
gmane.linux.alsa.devel 18
fa.freebsd.current 16
mpc.lists.nanog 16
fa.philos-l 14
mozilla.support.seamonkey 14
mozilla.general 13
spline.eisfair 12
mozilla.dev.security.policy 12
TOTAL: 143 1743

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

GroupsCount
alt.culture.china 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11552
EMP (phn path) 2355
EMP (phl) 494
EMP (phn nph) 95
Binary: misplaced epub 71
Too many newsgroups 53
User-issued cancel 20
Binary Payload 19
Binary: non-binary distribution 13
HTML post 11
Too many newsgroups (low_xpost) 7
TOTAL: 11 14690

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 408
newsfeed.neva.ru 24
news.fcku.it 4
TOTAL: 3 436
TOTAL: 1 436

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 28413 704 18784 1257 0 14 2%23:59:44
2weretis.net 5085 571 4012 0 0 6 11%23:59:43
3neva.ru 30781 381 18783 353 0 47 1%23:59:41
4nntp.pasdenom.info 12507 318 5627 8 0 18194 2%23:57:38
5eternal-september 26156 23 18966 0 0 33 0%23:59:45
6etla.org 0 0 0 0 0 22426 0%23:59:45
7news.killfile.org 2849 0 0 0 0 25274 0%23:59:45
8news.netfront.net 2748 0 0 0 0 25103 0%23:59:44
9vc89.vc.panix.com 2841 0 0 0 0 25266 0%23:59:45
10news.albasani.net 2859 0 0 0 0 24497 0%23:59:45
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12news.stack.nl 2799 0 0 0 0 25104 0%23:59:45
TOTAL: 12 117038 1997 66172 1618 0 165964 1%264:05:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it2.9 MB4.4 MB7.2 MB0.1 KB/s3.8 KB23:59:44
2nntp.pasdenom.info2.2 MB20.7 KB2.3 MB0.0 KB/s7.1 KB23:57:38
3weretis.net1.4 MB0.0 KB1.4 MB0.0 KB/s2.6 KB23:59:43
4neva.ru1.1 MB1.2 MB2.3 MB0.0 KB/s3.2 KB23:59:41
5eternal-september135.3 KB0.0 KB135.3 KB0.0 KB/s5.9 KB23:59:45
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
7news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
8news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
9vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
10news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
TOTAL: 127.8 MB5.6 MB13.4 MB0.0 KB/s3.8 KB264:05:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 22 38.5 KB 3 0 004:26:07
247.192.187.101 3 13.3 KB 1 0 000:32:48
TOTAL: 2 25 411.8 KB 4 0 004:59:52

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 22 38.5 KB 3 0 004:26:07
2unresolved 4 13.3 KB 1 0 000:33:24
3*.188.110.pools.vodafone-ip.de 98 00.0 KB 0 0 000:00:20
TOTAL: 3 124 411.8 KB 4 0 004:59:52

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.476 0.136 0.00004:26:07
47.192.187.101 0.200 0.016 0.00000:32:48
196.52.16.41 0.212 0.056 0.00000:00:35
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 5.216 0.372 0.00000:00:20
TOTAL: 4 7.104 0.580 0.00004:59:52

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 98
196.52.16.41 1
TOTAL: 2 99

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 7 1
47.192.187.101 1 0
TOTAL: 2 8 1

Newsgroup request counts (by category):

CategoryCountPct
1comp 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1comp.lang.awk 4
TOTAL: 1 4