Daily Usenet report for paganini.linuxd.org

Apr 6 00:00:04 -- Apr 7 00:00:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Apr 6 09:22:53 paganini overchan[25389]: input line too long, skipping
Apr 6 09:25:57 paganini overchan[25389]: input line too long, skipping
Apr 6 09:35:01 paganini overchan[25389]: input line too long, skipping
Apr 7 00:00:02 paganini overchan[5989]: 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 103745 43.5%12.9 MB 39.5%
nnrpd 75324 31.6%8.7 MB 26.7%
inn 40703 17.1%8.6 MB 26.4%
innd 18810 7.9%2.4 MB 7.3%
nocem 119 0.0%19.1 KB 0.1%
overchan 5 0.0%0.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 238707 100.0%32.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 122671 50.5%
Negative hits 80252 33.0%
Do not exist 40093 16.5%
Cache misses 69 0.0%
TOTAL: 4 243085 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.228 0.0%220 0.000 5.582 37.500
article cleanup00:00:00.262 0.0%40203 0.000 0.007 0.020
article logging00:00:00.459 0.0%40530 0.000 0.011 0.220
article parse00:00:01.185 0.0%90425 0.000 0.013 0.042
article write00:00:50.652 0.1%22236 0.123 2.278 12.198
artlog/artcncl00:00:00.001 0.0%212 0.000 0.005 0.143
data move00:00:00.184 0.0%249401 0.000 0.001 0.004
hisgrep/artcncl00:00:00.292 0.0%216 0.000 1.352 16.056
hishave/artcncl00:00:00.002 0.0%220 0.000 0.009 0.143
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.841 0.0%243860 0.016 0.040 0.175
history sync00:00:00.025 0.0%395 0.000 0.063 1.000
history write00:00:41.177 0.0%40202 0.055 1.024 4.221
hiswrite/artcncl00:00:00.000 0.0%4 0.000 0.000 0.000
idle23:57:03.238 99.7%251601 191.075 342.698 1053.469
nntp read00:00:01.866 0.0%260177 0.002 0.007 0.018
overview write00:00:21.133 0.0%22236 0.027 0.950 3.325
perl filter00:01:27.754 0.1%40200 0.959 2.183 3.667
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.174 0.0%66915 0.003 0.018 0.056
TOTAL: 24:01:09.90424:00:40.473100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.107 0.0%56968 0.000 0.002 0.016
article prepare00:00:00.005 0.0%37608 0.000 0.000 0.005
article read00:00:04.059 0.0%7491 0.000 0.542 17.927
backlog stats00:00:00.236 0.0%363670 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.545 0.0%199487 0.001 0.008 0.017
data write00:00:03.123 0.0%177624 0.008 0.018 0.044
idle23:42:21.146 99.3%363670 26.378 234.666 444.866
status file00:09:54.532 0.7%43373 6.771 13.707 22.224
TOTAL: 23:52:37.01323:52:24.753100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:48:32.889 96.6%77414 36.594 223.640454968.750
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:08:42.602 2.9%2349849 0.000 0.222 482.000
readart00:00:03.613 0.0%5326 0.000 0.678 54.000
TOTAL: 04:58:41.23804:57:19.104 99.5%----

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 48816 18662 28007 2147 38%48:37:51
2newsfeed.neva.ru 139 85290 2154 67504 15632 2%72:36:17
3news.fcku.it 1 8693 1529 6884 280 17%23:56:54
4nntp.pasdenom.info 2 21017 32 20971 14 0%23:54:43
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 172 163816 22377 123366 18073 13%169:05:45
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net58.8 MB0.0 KB14.1 MB72.8 MB 80%3.6 KB
2newsfeed.neva.ru5.8 MB31.1 KB137.9 MB143.8 MB 4%8.3 KB
3news.fcku.it5.4 MB0.0 KB1.9 MB7.3 MB 74%4.1 KB
4nntp.pasdenom.info89.8 KB0.0 KB95.1 KB184.9 KB 48%4.0 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 570.1 MB31.1 KB153.9 MB224.1 MB 31%5.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 6 00:00:04 - 00:59:59 887 4.0% 0.253.9 MB 5.6% 1.11
Apr 6 01:00:00 - 01:59:59 604 2.7% 0.171.8 MB 2.6% 0.51
Apr 6 02:00:00 - 02:59:59 526 2.4% 0.152.0 MB 2.9% 0.57
Apr 6 03:00:00 - 03:59:59 650 2.9% 0.182.5 MB 3.6% 0.72
Apr 6 04:00:00 - 04:59:59 492 2.2% 0.141.7 MB 2.4% 0.48
Apr 6 05:00:00 - 05:59:59 482 2.2% 0.131.6 MB 2.3% 0.46
Apr 6 06:00:00 - 06:59:59 441 2.0% 0.121.6 MB 2.2% 0.44
Apr 6 07:00:00 - 07:59:59 511 2.3% 0.142.1 MB 3.1% 0.61
Apr 6 08:00:00 - 08:59:59 634 2.9% 0.182.1 MB 3.0% 0.60
Apr 6 09:00:00 - 09:59:59 818 3.7% 0.232.5 MB 3.5% 0.70
Apr 6 10:00:00 - 10:59:59 978 4.4% 0.272.7 MB 3.9% 0.77
Apr 6 11:00:00 - 11:59:59 1107 5.0% 0.313.1 MB 4.5% 0.89
Apr 6 12:00:00 - 12:59:59 1070 4.8% 0.302.8 MB 4.0% 0.80
Apr 6 13:00:00 - 13:59:59 931 4.2% 0.262.5 MB 3.5% 0.70
Apr 6 14:00:00 - 14:59:59 1144 5.2% 0.323.3 MB 4.7% 0.93
Apr 6 15:00:00 - 15:59:59 1179 5.3% 0.333.3 MB 4.8% 0.94
Apr 6 16:00:00 - 16:59:59 1287 5.8% 0.363.7 MB 5.4% 1.07
Apr 6 17:00:00 - 17:59:59 1335 6.0% 0.373.8 MB 5.5% 1.08
Apr 6 18:00:00 - 18:59:59 1297 5.8% 0.364.3 MB 6.2% 1.23
Apr 6 19:00:00 - 19:59:59 1219 5.5% 0.343.5 MB 5.0% 0.99
Apr 6 20:00:00 - 20:59:59 1278 5.8% 0.353.9 MB 5.6% 1.10
Apr 6 21:00:00 - 21:59:59 1213 5.5% 0.343.8 MB 5.5% 1.09
Apr 6 22:00:00 - 22:59:59 1102 5.0% 0.313.4 MB 4.9% 0.98
Apr 6 23:00:00 - 23:59:59 1012 4.6% 0.283.6 MB 5.1% 1.02
Apr 7 00:00:00 - 00:00:02 6 0.0% 3.0019.0 KB 0.0% 9.50
TOTAL: 23:59:58 22203 100.0% 0.2669.5 MB 100.0% 0.82
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 15550 479 0 1 0 0 0 015070
2linuxd-out.news.weretis.net 2452 630 0 0 0 0 0 0 1822
3news.fcku.it 273 51 0 0 0 0 0 0 222
4nntp.pasdenom.info 13 2 0 0 0 2 0 0 9
TOTAL: 4 18288 1162 0 1 0 2 0 017123

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 216
ut.test 124
it-alt.sport.calcio.juventus 75
lada.auto 68
it.comp.os.win.windows10 58
mozilla.support.thunderbird 58
alt.windows7.general 51
alt.politics.scorched-earth 42
it.annunci.immobiliari 32
it.annunci.varie 31
alt.2600 28
it.annunci.commerciali 27
mozilla.support.seamonkey 24
gmane.comp.gis.openstreetmap.tagging 21
gmane.linux.alsa.devel 17
alt.survival 16
mozilla.general 14
mozilla.test 13
mozilla.dev.platform 11
lada.commerce 11
TOTAL: 123 1162

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15024
EMP (phn path) 1534
EMP (phn nph) 109
Too many newsgroups 54
User-issued cancel 27
EMP (phl) 14
Binary Payload 14
Binary: non-binary distribution 8
Too many newsgroups (meow) 6
User-issued spam cancel 2
Binary: misplaced par2 2
Binary Image: misplaced jpg 1
HTML post 1
Too many newsgroups (low_xpost) 1
TOTAL: 14 16797

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 307
newsfeed.neva.ru 15
news.fcku.it 4
TOTAL: 3 326
TOTAL: 1 326

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 53910 3565 35134 1013 0 3009 6%23:49:45
2news.fcku.it 25290 1786 16446 2446 0 9 7%23:57:59
3weretis.net 3986 232 3425 0 0 9 5%23:46:49
4neva.ru 29324 117 19620 215 0 89 0%23:51:34
5eternal-september 24080 44 19009 1 0 4 0%23:59:52
6etla.org 0 0 0 0 0 22066 0%23:50:02
7news.stack.nl 2858 0 0 0 0 25040 0%23:59:44
8news.netfront.net 2762 0 0 0 0 24901 0%23:59:41
9news.mixmin.net 0 0 0 0 0 0 0%00:10:00
10news.killfile.org 2834 0 0 0 0 25029 0%23:59:41
11news.albasani.net 2863 0 0 0 0 24248 0%23:59:39
12vc89.vc.panix.com 2838 0 0 0 0 25033 0%23:59:39
TOTAL: 12 150745 5744 93634 3675 0 149437 3%263:24:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info12.0 MB2.1 MB14.1 MB0.2 KB/s3.2 KB23:49:45
2news.fcku.it4.9 MB7.0 MB11.9 MB0.1 KB/s2.9 KB23:57:59
3weretis.net704.5 KB0.0 KB704.5 KB0.0 KB/s3.0 KB23:46:49
4neva.ru276.5 KB740.5 KB1017.0 KB0.0 KB/s3.1 KB23:51:34
5eternal-september187.0 KB1.7 KB188.7 KB0.0 KB/s4.2 KB23:59:52
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
7news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
8news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
9news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:41
11news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:39
12vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:39
TOTAL: 1218.1 MB9.8 MB27.9 MB0.0 KB/s3.0 KB263:24:25
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-092-073-008-096.092.073.pools.vodafone-ip.de 129 23.4 KB 2 0 001:01:27
2challenger.nielsenmedia.com 8 25.2 KB 2 0 002:06:05
TOTAL: 2 137 48.6 KB 4 0 004:58:39

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 8 25.2 KB 2 0 002:06:05
2*.092.073.pools.vodafone-ip.de 129 23.4 KB 2 0 001:01:27
3*.drr02.clwr.fl.frontiernet.net 9 00.0 KB 0 0 001:05:57
4unresolved 1 00.0 KB 0 0 000:00:16
5*.dsl.bell.ca 522 00.0 KB 0 0 000:44:52
TOTAL: 5 669 48.6 KB 4 0 004:58:39

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.608 0.048 0.00002:06:05
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.476 0.048 0.00001:05:57
dslb-092-073-008-096.092.073.pools.vodafone-ip.de 13.656 3.500 0.00001:01:27
mtrlpq2314w-lp130-04-70-54-157-241.dsl.bell.ca 47.360 13.136 0.00000:44:52
196.52.20.209 0.228 0.108 0.00000:00:16
TOTAL: 5 62.328 16.840 0.00004:58:39

Curious NNRP server explorers [Top 20]:

SystemConn
mtrlpq2314w-lp130-04-70-54-157-241.dsl.bell.ca 522
47-192-187-101.drr02.clwr.fl.frontiernet.net 9
196.52.20.209 1
TOTAL: 3 532

NNRP client timeouts [Top 20]:

SystemConnPeer
mtrlpq2314w-lp130-04-70-54-157-241.dsl.bell.ca 115 115
challenger.nielsenmedia.com 4 0
47-192-187-101.drr02.clwr.fl.frontiernet.net 2 0
TOTAL: 3 121 115

Newsgroup request counts (by category):

CategoryCountPct
1comp 2 50.0%
2de 1 25.0%
3alt 1 25.0%
TOTAL: 3 4100.0%

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

NewsgroupCount
1comp.lang.awk 2
2de.rec.fotografie 1
3alt.de.test 1
TOTAL: 3 4