Daily Usenet report for paganini.linuxd.org

Jan 26 00:00:01 -- Jan 27 00:00:07

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Jan 26 18:22:50 paganini innd: filter: EMP database could not be dumped: File o directory non esistente
Jan 26 18:22:50 paganini innd: SERVER execv /usr/lib/news/bin/innd
Jan 26 18:22:50 paganini innd: logging hist stats to /var/spool/news/www/inn.status
Jan 26 18:22:58 paganini overchan[26318]: 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
nnrpd 56796 47.2%6.2 MB 34.3%
inn 46591 38.7%9.8 MB 54.5%
innd 15038 12.5%1.7 MB 9.7%
innfeed 1860 1.5%270.6 KB 1.5%
nocem 121 0.1%14.5 KB 0.1%
overchan 2 0.0%0.1 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 7 120409 100.0%18.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 272509 53.5%
Negative hits 191141 37.5%
Do not exist 45844 9.0%
Cache misses 146 0.0%
TOTAL: 4 509640 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.318 0.0%245 0.000 1.298 19.800
article cleanup00:00:00.258 0.0%45961 0.000 0.006 0.019
article logging00:00:00.525 0.0%46042 0.000 0.011 0.223
article parse00:00:01.075 0.0%114655 0.000 0.009 0.023
article write00:01:18.488 0.1%29850 0.109 2.629 14.188
artlog/artcncl00:00:00.000 0.0%214 0.000 0.000 0.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.189 0.0%537908 0.000 0.000 0.002
hisgrep/artcncl00:00:00.058 0.0%241 0.000 0.241 10.800
hishave/artcncl00:00:00.002 0.0%245 0.000 0.008 0.125
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:24.326 0.0%510794 0.013 0.048 0.266
history sync00:00:00.081 0.0%301 0.000 0.269 34.000
history write00:01:41.352 0.1%45959 0.201 2.205 7.132
hiswrite/artcncl00:00:00.000 0.0%4 0.000 0.000 0.000
idle23:42:55.624 99.5%528452 94.343 161.558 549.356
nntp read00:00:02.907 0.0%542185 0.002 0.005 0.008
overview write00:00:42.769 0.0%29850 0.011 1.433 4.677
perl filter00:02:00.341 0.1%45959 1.119 2.618 6.505
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:07.628 0.0%119586 0.021 0.064 0.101
TOTAL: 23:50:24.01723:49:15.941 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.080 0.0%30974 0.000 0.003 0.030
article prepare00:00:00.010 0.0%84504 0.000 0.000 0.004
article read00:00:17.581 0.0%20655 0.000 0.851 10.893
backlog stats00:00:00.164 0.0%485357 0.000 0.000 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.408 0.0%320983 0.003 0.008 0.013
data write00:00:03.737 0.0%295085 0.008 0.013 0.019
idle23:52:46.528 99.9%485357 89.012 177.120 554.246
status file00:00:06.883 0.0%188 0.000 36.612 275.000
TOTAL: 23:53:30.42623:53:17.391100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:05:10.507 99.9%171627 102.329 106.688 109.746
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.532 0.0%343290 0.006 0.007 0.009
readart00:00:01.791 0.0%3435 0.142 0.521 1.540
TOTAL: 05:05:21.38005:05:14.830100.0%----

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 68475 11157 53943 3375 16%49:02:39
2feeder.eternal-september.org 9 43314 5769 35009 2536 13%25:24:14
3news-out.mixmin.net 8 36474 4155 31257 1062 11%25:16:47
4vc89.vc.panix.com 2 8170 3777 4015 378 46%23:29:23
5newsfeed.neva.ru 152 93778 3578 81205 8995 3%71:34:30
6etla.org 2 29735 1822 27913 0 6%24:41:49
7news.netfront.net 2 71175 141 71006 28 0%40:04:31
8localhost 25 0 0 0 0 0%00:00:00
TOTAL: 8 204 351121 30399 304348 16374 8%259:33:53
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net40.4 MB0.0 KB11.0 MB51.3 MB 78%3.6 KB
2feeder.eternal-september.org17.1 MB0.0 KB30.9 MB48.0 MB 35%5.9 KB
3vc89.vc.panix.com15.0 MB111.7 KB3.3 MB18.4 MB 81%4.5 KB
4newsfeed.neva.ru10.7 MB0.0 KB64.6 MB75.3 MB 14%6.1 KB
5news-out.mixmin.net10.0 MB0.0 KB3.0 MB13.0 MB 76%2.5 KB
6etla.org5.2 MB0.0 KB0.0 KB5.2 MB100%2.9 KB
7news.netfront.net1.7 MB0.0 KB1.5 MB3.2 MB 54%19.4 KB
8localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 8100.2 MB111.7 KB114.1 MB214.5 MB 46%4.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 26 00:00:01 - 00:59:59 927 3.1% 0.262.7 MB 2.7% 0.76
Jan 26 01:00:00 - 01:59:59 809 2.7% 0.223.5 MB 3.5% 0.99
Jan 26 02:00:00 - 02:59:59 671 2.2% 0.192.3 MB 2.3% 0.66
Jan 26 03:00:00 - 03:59:59 524 1.7% 0.151.6 MB 1.5% 0.44
Jan 26 04:00:00 - 04:59:59 543 1.8% 0.152.3 MB 2.3% 0.66
Jan 26 05:00:00 - 05:59:59 617 2.1% 0.172.5 MB 2.5% 0.71
Jan 26 06:00:00 - 06:59:59 588 2.0% 0.162.1 MB 2.1% 0.61
Jan 26 07:00:00 - 07:59:59 602 2.0% 0.173.2 MB 3.2% 0.91
Jan 26 08:00:00 - 08:59:59 889 3.0% 0.253.2 MB 3.2% 0.92
Jan 26 09:00:00 - 09:59:59 1098 3.7% 0.303.1 MB 3.1% 0.88
Jan 26 10:00:00 - 10:59:59 1389 4.6% 0.393.5 MB 3.5% 1.00
Jan 26 11:00:00 - 11:59:59 1366 4.5% 0.384.5 MB 4.5% 1.28
Jan 26 12:00:00 - 12:59:59 1334 4.4% 0.374.1 MB 4.1% 1.17
Jan 26 13:00:00 - 13:59:59 1489 5.0% 0.413.8 MB 3.8% 1.08
Jan 26 14:00:00 - 14:59:59 1524 5.1% 0.424.5 MB 4.4% 1.27
Jan 26 15:00:00 - 15:59:59 1638 5.4% 0.464.6 MB 4.6% 1.31
Jan 26 16:00:00 - 16:59:59 1680 5.6% 0.475.3 MB 5.2% 1.50
Jan 26 17:00:00 - 17:59:59 1830 6.1% 0.518.6 MB 8.5% 2.45
Jan 26 18:00:00 - 18:59:59 2037 6.8% 0.576.6 MB 6.6% 1.88
Jan 26 19:00:00 - 19:59:59 1940 6.4% 0.545.5 MB 5.5% 1.56
Jan 26 20:00:00 - 20:59:59 1823 6.1% 0.515.3 MB 5.2% 1.49
Jan 26 21:00:00 - 21:59:59 1691 5.6% 0.474.5 MB 4.4% 1.27
Jan 26 22:00:00 - 22:59:59 1642 5.5% 0.466.8 MB 6.8% 1.93
Jan 26 23:00:00 - 23:59:59 1410 4.7% 0.396.6 MB 6.6% 1.88
Jan 27 00:00:00 - 00:00:07 17 0.1% 2.4354.1 KB 0.1% 7.73
TOTAL: 24:00:06 30078 100.0% 0.35100.7 MB 100.0% 1.19
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9029 1978 0 0 0 0 0 0 7051
2linuxd-out.news.weretis.net 3315 1599 0 0 0 0 0 0 1716
3feeder.eternal-september.org 2522 184 0 0 0 0 0 0 2338
4news-out.mixmin.net 1007 13 0 0 0 0 0 0 994
5vc89.vc.panix.com 403 1 0 2 0 0 0 0 400
6news.netfront.net 16 2 0 0 0 0 0 0 14
7etla.org 6 0 0 0 0 0 0 0 6
TOTAL: 7 16298 3777 0 2 0 0 0 012519

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 795
lada.talk 176
mailing.freebsd.ports-bugs 164
ut.test 131
alt.politics 114
tin.it.sport.calcio 73
lada.auto 69
alt.survival 65
mailing.freebsd.svn-src-all 62
fa.freebsd.cvs-all 62
pld.cvs.commit 59
mozilla.dev.tree-alerts 59
mpc.lists.freebsd.ports.bugs 58
mailing.freebsd.svn-ports-all 57
mailing.freebsd.svn-ports-head 56
it-alt.sport.calcio.juventus 52
mailing.gcc.gcc-cvs 45
mailing.freebsd.svn-src-head 44
mailing.openbsd.ports-changes 37
mailing.freebsd.questions 35
TOTAL: 288 3777

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 8106
EMP (phn path) 3110
EMP (phl) 723
EMP (phn nph) 198
Too many newsgroups 114
User-issued cancel 73
Binary Image: misplaced jpg 57
Binary: non-binary distribution 19
Binary Payload 14
Binary: misplaced PAR2 8
HTML post 6
Too many newsgroups (low_xpost) 3
Poison newsgroup 2
Too many newsgroups (meow) 1
U2 violation - invalid distribution 1
Binary: misplaced par2 1
Binary: misplaced rar 1
TOTAL: 17 12437

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 36891 786 13859 15345 0 199 2%23:56:13
2news.netfront.net 53438 270 29637 33 0 423 0%24:01:32
3etla.org 35202 268 29763 5 0 227 0%23:59:23
4news.killfile.org 48431 142 25474 4350 0 157 0%23:54:51
5news.stack.nl 31449 9 29710 12 0 92 0%23:54:29
6neva.ru 29585 8 26183 8 0 32 0%23:59:53
7eternal-september 25330 5 24219 0 0 0 0%24:00:24
8weretis.net 19782 3 19031 1 0 0 0%23:59:54
9news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 9 280108 1491 197876 19754 0 1130 0%191:56:39
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org16.3 MB45.5 KB16.4 MB0.2 KB/s61.3 KB23:59:23
2news.netfront.net8.8 MB1.3 MB10.1 MB0.1 KB/s34.1 KB24:01:32
3vc89.vc.panix.com2.7 MB46.8 MB49.5 MB0.6 KB/s3.1 KB23:56:13
4news.killfile.org480.6 KB14.2 MB14.7 MB0.2 KB/s3.4 KB23:54:51
5neva.ru202.5 KB82.5 KB285.0 KB0.0 KB/s17.8 KB23:59:53
6news.stack.nl78.5 KB25.3 KB103.8 KB0.0 KB/s4.9 KB23:54:29
7eternal-september19.0 KB0.0 KB19.0 KB0.0 KB/s3.8 KB24:00:24
8weretis.net11.0 KB1.2 KB12.3 KB0.0 KB/s3.1 KB23:59:54
9news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 928.6 MB62.5 MB91.1 MB0.1 KB/s4.4 KB191:56:39
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-02-1176034897.dsl.bell.ca 20 1839.9 KB 8 0 005:05:21
TOTAL: 1 20 1839.9 KB 8 0 005:05:21

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 20 1839.9 KB 8 0 005:05:21
TOTAL: 1 20 1839.9 KB 8 0 005:05:21

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mtrlpq2314w-lp130-02-1176034897.dsl.bell.ca 6.280 5.464 0.00005:05:21
TOTAL: 1 6.280 5.464 0.00005:05:21

NNRP client timeouts [Top 20]:

SystemConnPeer
mtrlpq2314w-lp130-02-1176034897.dsl.bell.ca 10 10
TOTAL: 1 10 10

Newsgroup request counts (by category):

CategoryCountPct
1alt 13 72.2%
2aus 2 11.1%
3free 1 5.6%
4muc 1 5.6%
5soc 1 5.6%
TOTAL: 5 18100.0%

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

NewsgroupCount
1alt.comp.os.windows-8 9
2alt.gossip.celebrities 2
3aus.religion.christian 2
4muc.lists.freebsd.security 1
5alt.politics.obama 1
6free.speech 1
7soc.religion.quaker 1
8alt.atheism 1
TOTAL: 8 18