Daily Usenet report for paganini.linuxd.org

Apr 29 00:00:09 -- Apr 30 00:00:11

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Apr 29 02:39:41 paganini overchan[18921]: input line too long, skipping
Apr 29 13:43:58 paganini overchan[18921]: input line too long, skipping
Apr 29 13:48:55 paganini overchan[18921]: input line too long, skipping
Apr 29 14:36:06 paganini overchan[18921]: input line too long, skipping
Apr 29 14:38:11 paganini overchan[18921]: input line too long, skipping
Apr 29 14:46:10 paganini overchan[18921]: input line too long, skipping
Apr 29 16:38:46 paganini overchan[18921]: input line too long, skipping
Apr 29 18:17:27 paganini overchan[18921]: input line too long, skipping
Apr 29 18:23:26 paganini overchan[18921]: input line too long, skipping
Apr 29 18:25:44 paganini overchan[18921]: input line too long, skipping
Apr 29 18:32:32 paganini overchan[18921]: input line too long, skipping
Apr 29 18:35:50 paganini overchan[18921]: input line too long, skipping
Apr 29 18:47:53 paganini overchan[18921]: input line too long, skipping
Apr 29 18:52:50 paganini overchan[18921]: input line too long, skipping
Apr 29 19:32:14 paganini overchan[18921]: input line too long, skipping
Apr 29 22:57:27 paganini overchan[18921]: input line too long, skipping
Apr 30 00:00:10 paganini overchan[7032]: 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 291255 66.3%30.0 MB 56.5%
innfeed 100434 22.8%12.2 MB 23.1%
inn 37368 8.5%9.1 MB 17.2%
innd 10381 2.4%1.7 MB 3.2%
nocem 82 0.0%13.7 KB 0.0%
overchan 18 0.0%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 439539 100.0%53.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 103045 46.5%
Negative hits 82181 37.1%
Do not exist 36277 16.4%
Cache misses 100 0.0%
TOTAL: 4 221603 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:10.144 0.0%177 0.000 57.311 215.500
article cleanup00:00:00.260 0.0%36752 0.000 0.007 0.027
article logging00:00:01.191 0.0%37139 0.000 0.032 1.452
article parse00:00:00.777 0.0%55955 0.000 0.014 0.034
article write00:35:56.017 2.5%25116 12.853 85.842 247.563
artlog/artcncl00:00:00.000 0.0%176 0.000 0.000 0.000
data move00:00:03.071 0.0%221330 0.000 0.014 0.742
hisgrep/artcncl00:00:02.409 0.0%173 0.000 13.925 42.111
hishave/artcncl00:00:00.806 0.0%177 0.000 4.554 55.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:22:18.537 1.5%222985 0.030 6.003 18.762
history sync00:00:00.140 0.0%391 0.000 0.358 35.333
history write00:23:21.671 1.6%36530 3.994 38.370 116.835
hiswrite/artcncl00:00:00.036 0.0%4 6.000 9.000 15.000
idle22:22:27.450 93.2%183960 214.262 437.853 1116.131
nntp read00:00:07.190 0.0%221579 0.001 0.032 0.763
overview write00:12:09.232 0.8%25116 6.479 29.035 146.571
perl filter00:02:09.143 0.1%36530 1.024 3.535 36.875
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:03.219 0.0%75554 0.000 0.043 2.692
TOTAL: 24:01:04.05523:58:51.293 99.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.097 0.0%39418 0.000 0.002 0.013
article prepare00:00:00.000 0.0%8268 0.000 0.000 0.000
article read00:00:07.696 0.0%1880 0.000 4.094 469.286
backlog stats00:00:00.165 0.0%270334 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.207 0.0%156800 0.000 0.008 0.014
data write00:00:01.921 0.0%132370 0.007 0.015 0.026
idle22:27:39.696 94.0%270334 201.261 299.110 485.523
status file01:19:52.624 5.6%42002 24.448 114.105 265.751
TOTAL: 23:53:57.53623:47:43.406 99.6%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle30:10:52.703 99.3%769974 95.064 141.112600106.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:20.049 0.0%1631811 0.000 0.012 0.113
readart00:08:12.233 0.4%24949 0.000 19.730 92.000
TOTAL: 30:23:30.52430:19:24.985 99.8%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 147
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 170

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 7 57141 19238 35420 2483 33%48:09:24
2newsfeed.neva.ru 148 73660 4925 59749 8986 6%72:05:54
3news.fcku.it 1 8624 859 7624 141 9%21:25:02
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 2 0 0 0 0 0%00:20:03
TOTAL: 5 183 139425 25022 102793 11610 17%142:00:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net65.1 MB288.5 KB14.9 MB80.3 MB 81%3.8 KB
2newsfeed.neva.ru16.7 MB435.1 KB30.7 MB47.7 MB 34%3.5 KB
3news.fcku.it2.1 MB20.2 KB1.1 MB3.2 MB 65%3.3 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: 583.9 MB743.8 KB46.7 MB131.3 MB 63%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 29 00:00:09 - 00:59:59 914 3.6% 0.253.2 MB 3.7% 0.90
Apr 29 01:00:00 - 01:59:59 798 3.2% 0.222.5 MB 3.0% 0.72
Apr 29 02:00:00 - 02:59:59 655 2.6% 0.182.5 MB 3.0% 0.71
Apr 29 03:00:00 - 03:59:59 589 2.3% 0.162.1 MB 2.4% 0.58
Apr 29 04:00:00 - 04:59:59 698 2.8% 0.192.6 MB 3.1% 0.74
Apr 29 05:00:00 - 05:59:59 531 2.1% 0.152.0 MB 2.3% 0.56
Apr 29 06:00:00 - 06:59:59 603 2.4% 0.172.6 MB 3.1% 0.75
Apr 29 07:00:00 - 07:59:59 615 2.4% 0.172.1 MB 2.4% 0.59
Apr 29 08:00:00 - 08:59:59 742 3.0% 0.212.4 MB 2.9% 0.69
Apr 29 09:00:00 - 09:59:59 1086 4.3% 0.303.3 MB 3.9% 0.94
Apr 29 10:00:00 - 10:59:59 1076 4.3% 0.303.1 MB 3.7% 0.88
Apr 29 11:00:00 - 11:59:59 1154 4.6% 0.323.1 MB 3.6% 0.87
Apr 29 12:00:00 - 12:59:59 1232 4.9% 0.344.8 MB 5.7% 1.37
Apr 29 13:00:00 - 13:59:59 1160 4.6% 0.323.2 MB 3.7% 0.90
Apr 29 14:00:00 - 14:59:59 1289 5.1% 0.364.0 MB 4.7% 1.13
Apr 29 15:00:00 - 15:59:59 1328 5.3% 0.374.1 MB 4.8% 1.16
Apr 29 16:00:00 - 16:59:59 1457 5.8% 0.409.0 MB 10.7% 2.57
Apr 29 17:00:00 - 17:59:59 1511 6.0% 0.425.0 MB 5.9% 1.41
Apr 29 18:00:00 - 18:59:59 1405 5.6% 0.394.3 MB 5.1% 1.23
Apr 29 19:00:00 - 19:59:59 1414 5.6% 0.393.9 MB 4.6% 1.12
Apr 29 20:00:00 - 20:59:59 1229 4.9% 0.343.6 MB 4.3% 1.03
Apr 29 21:00:00 - 21:59:59 1186 4.7% 0.333.7 MB 4.4% 1.05
Apr 29 22:00:00 - 22:59:59 1224 4.9% 0.343.7 MB 4.4% 1.06
Apr 29 23:00:00 - 23:59:59 1205 4.8% 0.333.6 MB 4.2% 1.02
Apr 30 00:00:00 - 00:00:11 34 0.1% 3.09173.5 KB 0.2% 15.78
TOTAL: 24:00:02 25135 100.0% 0.2984.4 MB 100.0% 1.00
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9024 1590 0 129 0 0 0 0 7305
2linuxd-out.news.weretis.net 2812 1359 0 78 0 0 0 0 1375
3news.fcku.it 205 1 0 10 0 0 0 0 194
TOTAL: 3 12041 2950 0 217 0 0 0 0 8874

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 890
ut.test 148
lada.talk 117
mailing.freebsd.pkg-fallout 96
lada.auto 75
fa.freebsd.cvs-all 62
mailing.freebsd.svn-src-all 62
mailing.freebsd.svn-ports-all 61
mailing.openbsd.ports-changes 59
mailing.freebsd.svn-ports-head 54
mailing.openssl.cvs 44
it-alt.sport.calcio.juventus 44
mailing.freebsd.ports-bugs 43
mailing.freebsd.svn-src-head 42
mailing.gcc.gcc-cvs 41
alt.survival 35
gmane.linux.alsa.devel 28
fa.openbsd.source-changes 26
mailing.openbsd.source-changes 26
mpc.lists.freebsd.ports.bugs 25
TOTAL: 236 2950

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 6307
EMP (phn path) 1105
EMP (phl) 643
PFM bot misc 219
EMP (phn nph) 108
Too many newsgroups 34
Binary: non-binary distribution 27
Binary Payload 21
User-issued cancel 17
HTML post 2
MI5 Filter 1
Too many newsgroups (meow) 1
Binary Image: misplaced jpg 1
TOTAL: 13 8486

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 325
newsfeed.neva.ru 58
news.fcku.it 5
TOTAL: 3 388
TOTAL: 1 388

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 27537 303 23319 530 0 10 1%23:59:57
2nntp.pasdenom.info 34013 197 24146 677 0 287 0%23:57:50
3neva.ru 22777 62 19783 274 0 149 0%23:59:57
4weretis.net 5871 33 5621 0 0 25 0%23:59:28
5news.killfile.org 2821 0 0 0 0 27955 0%23:59:57
6news.albasani.net 2836 0 0 0 0 26994 0%23:59:57
7vc89.vc.panix.com 2810 0 0 0 0 27944 0%23:59:57
8news.mixmin.net 0 0 0 0 0 0 0%00:10:00
9eternal-september 0 0 0 0 0 21036 0%23:59:57
10news.stack.nl 2836 0 0 0 0 27896 0%23:59:57
11etla.org 0 0 0 0 0 25135 0%23:59:57
12news.netfront.net 2728 0 0 0 0 27764 0%23:59:56
TOTAL: 12 104229 595 72869 1481 0 185195 0%264:06:50
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1neva.ru1.2 MB1.1 MB2.3 MB0.0 KB/s7.1 KB23:59:57
2nntp.pasdenom.info1.1 MB1.3 MB2.4 MB0.0 KB/s2.8 KB23:57:50
3news.fcku.it820.0 KB1.7 MB2.5 MB0.0 KB/s3.1 KB23:59:57
4weretis.net48.3 KB0.0 KB48.3 KB0.0 KB/s1.5 KB23:59:28
5news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
6news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
7vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
8news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
9eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
11etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
12news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:56
TOTAL: 123.1 MB4.2 MB7.3 MB0.0 KB/s3.6 KB264:06:50
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1bas2-montrealak-1177634386.dsl.bell.ca 24 209364.7 KB 38 0 021:56:26
2challenger.nielsenmedia.com 47 4256.3 KB 9 0 007:38:49
371.98.160.36 9 24.0 KB 1 0 000:48:14
TOTAL: 3 80 253425.1 KB 48 0 030:23:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 24 209364.7 KB 38 0 021:56:26
2*.nielsenmedia.com 47 4256.3 KB 9 0 007:38:49
3unresolved 9 24.0 KB 1 0 000:48:14
TOTAL: 3 80 253425.1 KB 48 0 030:23:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
bas2-montrealak-1177634386.dsl.bell.ca 26.072 30.904 0.00021:56:26
challenger.nielsenmedia.com 2.924 0.216 0.00007:38:49
71.98.160.36 0.524 0.020 0.00000:48:14
TOTAL: 3 29.520 31.140 0.00030:23:30

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 13 2
71.98.160.36 1 0
TOTAL: 2 14 2

Newsgroup request counts (by category):

CategoryCountPct
1alt 178 70.4%
2comp 45 17.8%
3free 10 4.0%
4soc 5 2.0%
5muc 4 1.6%
6sci 2 0.8%
7hr 2 0.8%
8misc 2 0.8%
9uk 1 0.4%
10mail 1 0.4%
11talk 1 0.4%
12rec 1 0.4%
13aus 1 0.4%
TOTAL: 13 253100.0%

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

NewsgroupCount
1alt.drugs.heroin 160
2comp.lang.awk 44
3free.at 10
4soc.culture.israel 4
5alt.comp.freeware 3
6alt.fan.rush-limbaugh 3
7alt.happy.valley 3
8alt.home.repair 2
9sci.skeptic 2
10hr.alt.drugs 2
11misc.phone.mobile.iphone 2
12alt.sixtyplus 1
13talk.politics.misc 1
14alt.abortion 1
15alt.os.linux.ubuntu 1
16rec.arts.tv 1
17alt.politics.republicans 1
18uk.rec.drugs.cannabis 1
19aus.politics 1
20muc.lists.freebsd.bugs 1
21muc.lists.freebsd.hardware 1
22comp.os.msdos.djgpp 1
23soc.culture.jewish 1
24muc.lists.freebsd.ports 1
25mail.cypherpunks 1
26muc.lists.freebsd.multimedia 1
27alt.politics.greens 1
28alt.agnosticism 1
29alt.sports.football.pro.sd-chargers 1
TOTAL: 29 253