Daily Usenet report for paganini.linuxd.org

May 11 00:00:01 -- May 12 00:00:14

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

May 11 00:00:22 paganini nnrpd[11639]: 2001:ba8:1f1:f1ef::2 noperm ihave without permission
May 11 00:00:57 paganini nnrpd[11704]: 2001:ba8:1f1:f1ef::2 noperm ihave without permission
May 11 22:30:04 paganini innd: filter: EMP database could not be dumped: File o directory non esistente
May 11 22:30:05 paganini innd: SERVER execv /usr/lib/news/bin/innd
May 11 22:30:05 paganini innd: logging hist stats to /var/spool/news/www/inn.status
May 11 22:30:15 paganini overchan[25530]: 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
inn 50157 65.1%10.6 MB 77.6%
innd 22503 29.2%2.5 MB 18.4%
innfeed 2456 3.2%359.8 KB 2.6%
nnrpd 1834 2.4%178.1 KB 1.3%
nocem 149 0.2%19.6 KB 0.1%
overchan 2 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 7 77102 100.0%13.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 328527 56.4%
Negative hits 195239 33.5%
Do not exist 49441 8.5%
Cache misses 9323 1.6%
TOTAL: 4 582530 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.637 0.0%248 0.000 2.569 94.000
article cleanup00:00:00.284 0.0%49531 0.000 0.006 0.027
article logging00:00:00.435 0.0%49588 0.000 0.009 0.027
article parse00:00:01.447 0.0%166741 0.000 0.009 0.029
article write00:00:58.622 0.1%25912 0.128 2.262 52.596
artlog/artcncl00:00:00.003 0.0%192 0.000 0.016 0.125
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:00:00.248 0.0%622473 0.000 0.000 0.002
hisgrep/artcncl00:00:00.097 0.0%241 0.000 0.402 3.065
hishave/artcncl00:00:00.003 0.0%248 0.000 0.012 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:28.182 0.0%584126 0.015 0.048 0.435
history sync00:00:00.072 0.0%296 0.000 0.243 28.500
history write00:01:10.938 0.1%49514 0.041 1.433 9.320
hiswrite/artcncl00:00:00.000 0.0%7 0.000 0.000 0.000
idle23:42:24.088 99.4%611262 27.704 139.619 433.075
nntp read00:00:03.357 0.0%627431 0.001 0.005 0.009
overview write00:00:31.964 0.0%25912 0.013 1.234 5.374
perl filter00:03:35.157 0.3%49513 1.343 4.345 56.076
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.505 0.0%77969 0.000 0.019 0.056
TOTAL: 23:50:19.39523:49:17.039 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.067 0.0%29030 0.000 0.002 0.014
article prepare00:00:00.019 0.0%190872 0.000 0.000 0.001
article read00:00:10.235 0.0%25996 0.017 0.394 5.215
backlog stats00:00:00.253 0.0%619562 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.800 0.0%402069 0.004 0.007 0.013
data write00:00:04.772 0.0%381520 0.006 0.013 0.021
idle23:52:39.838 99.9%619562 72.380 138.743 477.257
status file00:00:06.712 0.0%206 0.000 32.583 303.000
TOTAL: 23:53:30.10923:53:04.696100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:01:03.074 91.7%1768 34.860 35.675 42.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.047 0.1%3678 0.000 0.013 0.070
readart00:00:00.729 1.1%86 3.500 8.477 16.500
TOTAL: 00:01:08.82000:01:03.850 92.8%----

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
throttle 2
xexec 1
TOTAL: 13 170

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 59399 7474 49466 2459 12%46:59:38
2feeder.eternal-september.org 9 46401 5704 35409 5288 12%25:23:31
3vc89.vc.panix.com 2 6265 3901 1813 551 62%23:25:49
4news.albasani.net 4 32185 2442 28338 1405 7%23:38:52
5newsfeed.neva.ru 139 101985 2240 87218 12527 2%71:29:01
6news-out.mixmin.net 5 30997 1940 27908 1149 6%24:10:10
7etla.org 4 32788 1207 31579 2 3%22:44:05
8news.fcku.it 11 3481 630 2845 6 18%22:32:46
9nntp.pasdenom.info 4 40684 85 40596 3 0%39:02:12
10news.netfront.net 2 45558 63 45457 38 0%23:18:09
11localhost 25 0 0 0 0 0%00:00:00
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 210 399743 25686 350629 23428 6%322:54:13
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net28.2 MB48.5 KB15.7 MB43.9 MB 64%4.5 KB
2vc89.vc.panix.com24.7 MB85.4 KB3.4 MB28.2 MB 87%6.5 KB
3feeder.eternal-september.org17.0 MB2.2 KB56.8 MB73.8 MB 23%6.9 KB
4news.albasani.net13.4 MB0.0 KB83.0 MB96.4 MB 13%25.7 KB
5newsfeed.neva.ru7.3 MB79.0 KB116.3 MB123.8 MB 5%8.6 KB
6news-out.mixmin.net5.1 MB0.0 KB2.1 MB7.2 MB 70%2.4 KB
7etla.org4.3 MB0.0 KB42.0 KB4.3 MB 99%3.7 KB
8news.fcku.it2.0 MB0.0 KB12.9 KB2.0 MB 99%3.2 KB
9news.netfront.net203.3 KB7.7 KB1.4 MB1.6 MB 12%16.4 KB
10nntp.pasdenom.info183.2 KB39.2 KB40.7 KB263.1 KB 69%3.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: 12102.4 MB261.9 KB279.0 MB381.6 MB 26%8.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 11 00:00:01 - 00:59:59 770 2.9% 0.212.1 MB 2.0% 0.61
May 11 01:00:00 - 01:59:59 622 2.4% 0.171.9 MB 1.8% 0.53
May 11 02:00:00 - 02:59:59 596 2.3% 0.172.5 MB 2.3% 0.70
May 11 03:00:00 - 03:59:59 509 1.9% 0.141.7 MB 1.6% 0.49
May 11 04:00:00 - 04:59:59 565 2.2% 0.162.2 MB 2.1% 0.62
May 11 05:00:00 - 05:59:59 453 1.7% 0.134.8 MB 4.6% 1.37
May 11 06:00:00 - 06:59:59 562 2.1% 0.163.4 MB 3.3% 0.98
May 11 07:00:00 - 07:59:59 591 2.3% 0.163.4 MB 3.3% 0.97
May 11 08:00:00 - 08:59:59 793 3.0% 0.223.5 MB 3.3% 0.99
May 11 09:00:00 - 09:59:59 1107 4.2% 0.312.9 MB 2.7% 0.81
May 11 10:00:00 - 10:59:59 1109 4.2% 0.313.1 MB 3.0% 0.90
May 11 11:00:00 - 11:59:59 1257 4.8% 0.354.8 MB 4.6% 1.37
May 11 12:00:00 - 12:59:59 1193 4.6% 0.334.3 MB 4.1% 1.23
May 11 13:00:00 - 13:59:59 1228 4.7% 0.343.5 MB 3.4% 1.00
May 11 14:00:00 - 14:59:59 1290 4.9% 0.363.8 MB 3.6% 1.08
May 11 15:00:00 - 15:59:59 1389 5.3% 0.3911.1 MB 10.7% 3.17
May 11 16:00:00 - 16:59:59 1494 5.7% 0.416.8 MB 6.5% 1.94
May 11 17:00:00 - 17:59:59 1465 5.6% 0.416.3 MB 6.0% 1.78
May 11 18:00:00 - 18:59:59 1570 6.0% 0.445.9 MB 5.6% 1.67
May 11 19:00:00 - 19:59:59 1430 5.5% 0.403.8 MB 3.7% 1.09
May 11 20:00:00 - 20:59:59 1609 6.1% 0.455.9 MB 5.6% 1.67
May 11 21:00:00 - 21:59:59 1559 6.0% 0.434.5 MB 4.3% 1.27
May 11 22:00:00 - 22:59:59 1611 6.2% 0.457.6 MB 7.3% 2.17
May 11 23:00:00 - 23:59:59 1387 5.3% 0.394.6 MB 4.4% 1.31
May 12 00:00:00 - 00:00:14 26 0.1% 1.86110.6 KB 0.1% 7.90
TOTAL: 24:00:13 26185 100.0% 0.30104.5 MB 100.0% 1.24
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 12786 2220 0 7 1 0 0 010558
2feeder.eternal-september.org 5277 556 0 1 0 0 0 0 4720
3linuxd-out.news.weretis.net 2511 1270 0 2 0 0 0 0 1239
4news.albasani.net 1436 143 0 0 0 0 0 0 1293
5news-out.mixmin.net 1145 33 0 0 0 0 0 0 1112
6vc89.vc.panix.com 567 8 0 3 0 0 0 0 556
7news.netfront.net 38 8 0 4 0 0 0 0 26
8news.fcku.it 9 0 0 0 0 0 0 0 9
9nntp.pasdenom.info 3 2 0 1 0 0 0 0 0
10etla.org 2 0 0 0 0 0 0 0 2
TOTAL: 10 23774 4240 0 18 1 0 0 019515

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 1131
alt.politics.scorched-earth 324
lada.talk 244
ut.test 173
mailing.freebsd.svn-ports-all 94
mailing.freebsd.svn-ports-head 94
alt.survival 86
gmane.comp.gis.openstreetmap.tagging 73
mailing.freebsd.ports-bugs 70
mailing.freebsd.svn-src-all 68
fa.freebsd.cvs-all 66
it-alt.sport.calcio.juventus 63
mozilla.dev.tree-alerts 60
gmane.linux.alsa.devel 50
pld.cvs.commit 49
24hoursupport.helpdesk 49
mailing.freebsd.svn-src-head 48
lada.auto 41
mailing.openbsd.ports-changes 39
tin.it.sport.calcio 37
TOTAL: 268 4240

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

GroupsCount
it.scienza.astronomia 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14173
EMP (phn path) 3786
EMP (phl) 896
EMP (phn nph) 243
Too many newsgroups 161
PFM bot misc 69
User-issued cancel 49
Binary Image: misplaced jpg 21
Binary Payload 18
Binary: non-binary distribution 14
Binary: misplaced sfv 9
Binary: misplaced nzb 9
HTML post 6
Binary: misplaced pdf 3
TOTAL: 14 19457

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 29678 1950 9655 14494 0 269 6%23:58:52
2news.netfront.net 46759 1423 24470 141 0 616 3%23:58:57
3etla.org 32793 672 25337 40 0 921 2%23:55:53
4news.albasani.net 27382 265 21978 1302 0 68 0%23:56:40
5news.stack.nl 35790 195 25624 78 0 751 0%23:56:30
6weretis.net 19255 179 18001 0 0 3 0%23:55:55
7news.killfile.org 43100 166 23038 2871 0 274 0%23:57:19
8neva.ru 28054 147 22925 368 0 92 0%23:50:51
9news.fcku.it 25907 140 2813 22473 0 14 0%23:56:06
10nntp.pasdenom.info 31113 109 24767 923 0 76 0%23:53:55
11eternal-september 21483 1 20205 1 0 3 0%23:55:45
12news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 12 341314 5247 218813 42691 0 3087 1%263:26:43
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org26.2 MB124.4 KB26.4 MB0.3 KB/s37.9 KB23:55:53
2news.netfront.net17.0 MB10.9 MB27.9 MB0.3 KB/s18.3 KB23:58:57
3vc89.vc.panix.com6.4 MB46.5 MB52.9 MB0.6 KB/s3.3 KB23:58:52
4neva.ru928.2 KB910.1 KB1.8 MB0.0 KB/s3.6 KB23:50:51
5news.stack.nl851.7 KB273.4 KB1.1 MB0.0 KB/s4.1 KB23:56:30
6news.fcku.it692.2 KB93.0 MB93.6 MB1.1 KB/s4.2 KB23:56:06
7news.albasani.net649.6 KB9.4 MB10.0 MB0.1 KB/s6.6 KB23:56:40
8news.killfile.org561.5 KB12.2 MB12.7 MB0.2 KB/s4.3 KB23:57:19
9weretis.net425.3 KB0.0 KB425.3 KB0.0 KB/s2.4 KB23:55:55
10nntp.pasdenom.info390.8 KB1.7 MB2.1 MB0.0 KB/s2.1 KB23:53:55
11eternal-september2.7 KB1.3 KB4.0 KB0.0 KB/s2.0 KB23:55:45
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 1254.0 MB174.9 MB228.9 MB0.2 KB/s4.9 KB263:26:43
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1x55b2f4da.dyn.telefonica.de 42 3778.9 KB 28 0 000:00:59
2f051221089.adsl.alicedsl.de 6 611.7 KB 5 0 000:00:08
TOTAL: 2 48 4390.7 KB 33 0 000:01:08

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dyn.telefonica.de 42 3778.9 KB 28 0 000:00:59
2*.adsl.alicedsl.de 6 611.7 KB 5 0 000:00:08
3? 2 00.0 KB 0 0 000:00:00
TOTAL: 3 50 4390.7 KB 33 0 000:01:08

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
x55b2f4da.dyn.telefonica.de 2.852 0.176 0.00000:00:59
f051221089.adsl.alicedsl.de 0.412 0.020 0.00000:00:08
2001:ba8:1f1:f1ef::2 0.084 0.004 0.00000:00:00
TOTAL: 3 3.348 0.200 0.00000:01:08

Curious NNRP server explorers [Top 20]:

SystemConn
2001:ba8:1f1:f1ef::2 2
TOTAL: 1 2

Newsgroup request counts (by category):

CategoryCountPct
1de 43100.0%
TOTAL: 1 43100.0%

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

NewsgroupCount
1de.alt.rec.digitalfotografie 13
2de.comp.os.unix.networking.misc 11
3de.comm.provider.t-online 10
4de.talk.jokes 8
5de.comm.software.mailserver 1
TOTAL: 5 43