Daily Usenet report for paganini.linuxd.org

Oct 15 00:00:02 -- Oct 16 00:00:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 15 10:06:04 paganini overchan[12358]: input line too long, skipping
Oct 15 21:30:52 paganini overchan[12358]: input line too long, skipping
Oct 16 00:00:02 paganini overchan[952]: 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 101289 71.5%12.5 MB 58.4%
inn 29486 20.8%7.1 MB 33.2%
innd 10387 7.3%1.7 MB 8.1%
nnrpd 437 0.3%61.1 KB 0.3%
nocem 23 0.0%3.7 KB 0.0%
overchan 4 0.0%0.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 141627 100.0%21.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 118657 59.6%
Negative hits 51727 26.0%
Do not exist 28800 14.5%
Cache misses 62 0.0%
TOTAL: 4 199246 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.413 0.0%125 0.000 3.304 77.000
article cleanup00:00:00.190 0.0%28949 0.000 0.007 0.026
article logging00:00:00.275 0.0%29317 0.000 0.009 0.034
article parse00:00:00.598 0.0%43896 0.000 0.014 0.055
article write00:00:52.061 0.1%19839 0.100 2.624 65.759
artlog/artcncl00:00:00.000 0.0%125 0.000 0.000 0.000
data move00:00:00.101 0.0%212206 0.000 0.000 0.002
hisgrep/artcncl00:00:00.002 0.0%125 0.000 0.016 0.500
hishave/artcncl00:00:00.001 0.0%125 0.000 0.008 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:12.020 0.0%200338 0.013 0.060 0.739
history sync00:00:00.039 0.0%310 0.000 0.126 9.500
history write00:00:27.010 0.0%28927 0.025 0.934 5.532
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:57:07.235 99.8%206290 210.152 417.990 1372.032
nntp read00:00:01.571 0.0%213115 0.000 0.007 0.016
overview write00:00:19.223 0.0%19839 0.023 0.969 3.310
perl filter00:00:51.344 0.1%28927 0.866 1.775 5.252
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.201 0.0%59737 0.004 0.020 0.052
TOTAL: 24:00:28.09623:59:53.284100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.092 0.0%34175 0.000 0.003 0.015
article prepare00:00:00.003 0.0%19942 0.000 0.000 0.005
article read00:00:01.492 0.0%3825 0.000 0.390 13.404
backlog stats00:00:00.220 0.0%328543 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.421 0.0%177045 0.002 0.008 0.016
data write00:00:02.777 0.0%157320 0.010 0.018 0.031
idle23:43:20.932 99.3%328543 150.171 259.938 470.234
status file00:09:31.489 0.7%42325 8.401 13.502 30.095
TOTAL: 23:53:15.93223:52:58.426100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:27:08.223 99.9%408 28.333 39284.860600089.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.006 0.0%1113 0.000 0.005 0.125
readart00:00:00.056 0.0%34 0.125 1.647 17.000
TOTAL: 04:27:25.60604:27:08.285 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 5 44560 16154 25543 2863 36%48:17:30
2newsfeed.neva.ru 139 66769 2197 59038 5534 3%71:34:04
3news.fcku.it 2 8052 1634 6133 285 20%25:19:25
4nntp.pasdenom.info 1 19019 6 18656 357 0%17:43:41
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 172 138400 19991 109370 9039 14%162:54:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net55.1 MB12.1 KB7.0 MB62.1 MB 88%3.3 KB
2newsfeed.neva.ru6.5 MB38.9 KB15.0 MB21.5 MB 30%2.9 KB
3news.fcku.it5.0 MB0.0 KB4.8 MB9.7 MB 51%5.2 KB
4nntp.pasdenom.info9.4 KB25.1 KB1.6 MB1.7 MB 0%4.7 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 566.6 MB76.1 KB28.3 MB95.0 MB 70%3.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 15 00:00:02 - 00:59:59 960 4.8% 0.272.8 MB 4.3% 0.81
Oct 15 01:00:00 - 01:59:59 671 3.4% 0.192.3 MB 3.6% 0.66
Oct 15 02:00:00 - 02:59:59 477 2.4% 0.131.6 MB 2.4% 0.45
Oct 15 03:00:00 - 03:59:59 446 2.3% 0.121.5 MB 2.3% 0.42
Oct 15 04:00:00 - 04:59:59 449 2.3% 0.121.2 MB 1.9% 0.35
Oct 15 05:00:00 - 05:59:59 379 1.9% 0.111.2 MB 1.9% 0.35
Oct 15 06:00:00 - 06:59:59 382 1.9% 0.111.2 MB 1.9% 0.35
Oct 15 07:00:00 - 07:59:59 512 2.6% 0.143.1 MB 4.8% 0.89
Oct 15 08:00:00 - 08:59:59 546 2.8% 0.151.4 MB 2.1% 0.40
Oct 15 09:00:00 - 09:59:59 928 4.7% 0.262.4 MB 3.6% 0.68
Oct 15 10:00:00 - 10:59:59 805 4.1% 0.222.2 MB 3.3% 0.62
Oct 15 11:00:00 - 11:59:59 916 4.6% 0.252.8 MB 4.2% 0.79
Oct 15 12:00:00 - 12:59:59 1041 5.3% 0.296.0 MB 9.1% 1.70
Oct 15 13:00:00 - 13:59:59 912 4.6% 0.252.4 MB 3.7% 0.68
Oct 15 14:00:00 - 14:59:59 1003 5.1% 0.282.6 MB 4.0% 0.75
Oct 15 15:00:00 - 15:59:59 1060 5.4% 0.292.7 MB 4.2% 0.78
Oct 15 16:00:00 - 16:59:59 1170 5.9% 0.333.2 MB 4.9% 0.91
Oct 15 17:00:00 - 17:59:59 1065 5.4% 0.303.2 MB 4.9% 0.91
Oct 15 18:00:00 - 18:59:59 1026 5.2% 0.283.2 MB 4.9% 0.91
Oct 15 19:00:00 - 19:59:59 1155 5.8% 0.322.7 MB 4.2% 0.78
Oct 15 20:00:00 - 20:59:59 1005 5.1% 0.284.5 MB 6.9% 1.29
Oct 15 21:00:00 - 21:59:59 1001 5.1% 0.282.7 MB 4.1% 0.77
Oct 15 22:00:00 - 22:59:59 1006 5.1% 0.283.8 MB 5.8% 1.08
Oct 15 23:00:00 - 23:59:59 880 4.4% 0.244.7 MB 7.2% 1.34
Oct 16 00:00:00 - 00:00:02 8 0.0% 4.0032.1 KB 0.0% 16.07
TOTAL: 24:00:00 19803 100.0% 0.2365.7 MB 100.0% 0.78
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 5622 388 0 13 0 0 0 0 5221
2linuxd-out.news.weretis.net 3207 365 0 2 0 0 0 0 2840
3nntp.pasdenom.info 446 0 0 7 0 0 0 0 439
4news.fcku.it 283 1 0 0 0 0 0 0 282
TOTAL: 4 9558 754 0 22 0 0 0 0 8782

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 153
lada.talk 105
it-alt.sport.calcio.juventus 73
lada.auto 62
alt.windows7.general 33
spline.eisfair 25
alt.survival 19
gmane.linux.alsa.devel 18
gmane.comp.gis.openstreetmap 13
lada.commerce 13
mozilla.support.thunderbird 12
mozilla.support.seamonkey 12
alt.politics 11
mozilla.dev.security.policy 11
it.comp.os.win.windows10 11
lada.photo 10
cakewalk.audio 9
mpc.lists.freebsd.gnome 8
lada.movies 7
spline.eisfair.dev 7
TOTAL: 93 754

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 4913
EMP (phn path) 2474
EMP (phl) 717
EMP (phn nph) 155
Too many newsgroups 91
User-issued cancel 31
Binary Payload 14
Binary: non-binary distribution 11
Too many newsgroups (low_xpost) 4
EMP (fsl) 2
HTML post 2
Too many newsgroups (meow) 1
TOTAL: 12 8415

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 340
newsfeed.neva.ru 19
news.fcku.it 8
TOTAL: 3 367
TOTAL: 1 367

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 35343 1935 17800 7 1 274 5%23:57:44
2news.fcku.it 23377 895 16084 1299 0 16 3%23:59:42
3weretis.net 4208 355 3370 0 0 6 8%23:57:58
4neva.ru 26321 134 17082 365 0 55 0%23:59:51
5eternal-september 22332 7 16535 0 0 11 0%23:59:50
6news.mixmin.net 0 0 0 0 0 0 0%00:10:00
7news.killfile.org 2849 0 0 0 0 22651 0%23:59:51
8news.stack.nl 2848 0 0 0 0 22531 0%23:59:39
9news.netfront.net 2759 0 0 0 0 22511 0%23:59:50
10news.albasani.net 2860 0 0 0 0 21832 0%23:59:51
11etla.org 0 0 0 0 0 19803 0%23:59:51
12vc89.vc.panix.com 2841 0 0 0 0 22643 0%23:59:51
TOTAL: 12 125738 3326 70871 1671 1 132333 2%264:03:58
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info6.8 MB11.4 KB6.8 MB0.1 KB/s3.6 KB23:57:44
2news.fcku.it4.6 MB4.0 MB8.6 MB0.1 KB/s4.0 KB23:59:42
3weretis.net1.3 MB0.0 KB1.3 MB0.0 KB/s3.6 KB23:57:58
4neva.ru786.9 KB1.4 MB2.2 MB0.0 KB/s4.5 KB23:59:51
5eternal-september30.3 KB0.0 KB30.3 KB0.0 KB/s4.3 KB23:59:50
6news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
7news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
8news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:39
9news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:50
10news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
11etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
12vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:51
TOTAL: 1213.5 MB5.4 MB18.8 MB0.0 KB/s3.9 KB264:03:58
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 4 1737.5 KB 3 0 001:30:32
2c-73-195-247-24.hsd1.nj.comcast.net 17 1737.5 KB 3 0 002:56:30
TOTAL: 2 21 3475.1 KB 6 0 004:27:25

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 4 1737.5 KB 3 0 001:30:32
2*.hsd1.nj.comcast.net 17 1737.5 KB 3 0 002:56:30
3*.188.110.pools.vodafone-ip.de 106 00.0 KB 0 0 000:00:22
TOTAL: 3 127 3475.1 KB 6 0 004:27:25

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
c-73-195-247-24.hsd1.nj.comcast.net 1.372 0.088 0.00002:56:30
challenger.nielsenmedia.com 0.412 0.036 0.00001:30:32
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 5.688 0.384 0.00000:00:22
TOTAL: 3 7.472 0.508 0.00004:27:25

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 106
TOTAL: 1 106

NNRP client timeouts [Top 20]:

SystemConnPeer
c-73-195-247-24.hsd1.nj.comcast.net 5 1
challenger.nielsenmedia.com 3 0
TOTAL: 2 8 1

Newsgroup request counts (by category):

CategoryCountPct
1comp 34100.0%
TOTAL: 1 34100.0%

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

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