Daily Usenet report for paganini.linuxd.org

Jul 1 00:00:03 -- Jul 2 00:00:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Jul 1 01:42:12 paganini overchan[3254]: input line too long, skipping
Jul 1 09:20:38 paganini overchan[3254]: input line too long, skipping
Jul 2 00:00:01 paganini overchan[24584]: 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 101314 61.8%12.4 MB 50.5%
inn 39305 24.0%9.1 MB 37.0%
innd 13164 8.0%2.0 MB 8.0%
nnrpd 9981 6.1%1.1 MB 4.4%
nocem 66 0.0%10.9 KB 0.0%
overchan 4 0.0%0.8 KB 0.0%
controlchan 1 0.0%0.0 KB 0.0%
TOTAL: 7 163835 100.0%24.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 112964 51.3%
Negative hits 68525 31.1%
Do not exist 38635 17.5%
Cache misses 70 0.0%
TOTAL: 4 220194 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.039 0.0%151 0.000 6.881 107.333
article cleanup00:00:00.291 0.0%38747 0.000 0.008 0.021
article logging00:00:00.357 0.0%39163 0.000 0.009 0.031
article parse00:00:00.881 0.0%72795 0.000 0.012 0.030
article write00:01:23.845 0.1%24166 0.230 3.470 34.312
artlog/artcncl00:00:00.001 0.0%151 0.000 0.007 1.000
data move00:00:00.145 0.0%251664 0.000 0.001 0.003
hisgrep/artcncl00:00:00.002 0.0%150 0.000 0.013 0.500
hishave/artcncl00:00:00.002 0.0%151 0.000 0.013 0.167
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.041 0.0%220724 0.015 0.045 0.724
history sync00:00:00.018 0.0%301 0.000 0.060 0.500
history write00:00:54.905 0.1%38747 0.044 1.417 4.704
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:55:04.100 99.6%241950 216.732 355.876 771.515
nntp read00:00:01.825 0.0%246050 0.001 0.007 0.014
overview write00:00:26.246 0.0%24166 0.031 1.086 4.237
perl filter00:01:23.676 0.1%38747 1.058 2.160 8.446
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.646 0.0%72777 0.003 0.023 0.074
TOTAL: 24:00:23.70323:59:29.020 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.121 0.0%39147 0.000 0.003 0.016
article prepare00:00:00.004 0.0%22764 0.000 0.000 0.006
article read00:00:02.200 0.0%4838 0.000 0.455 54.000
backlog stats00:00:00.193 0.0%339941 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.372 0.0%180372 0.002 0.008 0.018
data write00:00:02.267 0.0%156166 0.005 0.015 0.031
idle23:42:43.282 99.3%339941 165.586 251.112 420.468
status file00:09:32.973 0.7%42313 8.533 13.541 24.486
TOTAL: 23:52:45.76823:52:22.412100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:06:07.197 99.8%22952 27.667 800.244600625.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.386 0.0%46170 0.000 0.008 0.167
readart00:00:06.039 0.0%10499 0.117 0.575 4.484
TOTAL: 05:06:53.15205:06:13.622 99.8%----

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 4 61513 20432 35962 5119 33%48:23:44
2newsfeed.neva.ru 140 80984 2733 69162 9089 3%68:46:46
3news.fcku.it 1 8636 1016 7436 184 11%22:56:08
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 2 0 0 0 0 0%00:20:05
TOTAL: 5 172 151133 24181 112560 14392 15%140:26:43
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net66.4 MB0.0 KB46.0 MB112.4 MB 59%4.5 KB
2newsfeed.neva.ru8.6 MB0.0 KB50.2 MB58.8 MB 14%5.1 KB
3news.fcku.it3.7 MB0.0 KB1.4 MB5.2 MB 72%4.4 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: 578.7 MB0.0 KB97.6 MB176.3 MB 44%4.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 1 00:00:03 - 00:59:59 890 3.7% 0.252.9 MB 3.7% 0.83
Jul 1 01:00:00 - 01:59:59 783 3.2% 0.222.4 MB 3.0% 0.68
Jul 1 02:00:00 - 02:59:59 568 2.4% 0.162.2 MB 2.8% 0.63
Jul 1 03:00:00 - 03:59:59 471 2.0% 0.131.6 MB 2.1% 0.46
Jul 1 04:00:00 - 04:59:59 593 2.5% 0.161.8 MB 2.3% 0.51
Jul 1 05:00:00 - 05:59:59 608 2.5% 0.171.8 MB 2.3% 0.51
Jul 1 06:00:00 - 06:59:59 588 2.4% 0.162.4 MB 3.1% 0.69
Jul 1 07:00:00 - 07:59:59 531 2.2% 0.152.2 MB 2.8% 0.63
Jul 1 08:00:00 - 08:59:59 790 3.3% 0.223.2 MB 4.1% 0.91
Jul 1 09:00:00 - 09:59:59 950 3.9% 0.263.1 MB 3.9% 0.87
Jul 1 10:00:00 - 10:59:59 1129 4.7% 0.313.1 MB 3.9% 0.88
Jul 1 11:00:00 - 11:59:59 1223 5.1% 0.343.5 MB 4.4% 0.98
Jul 1 12:00:00 - 12:59:59 1209 5.0% 0.343.4 MB 4.3% 0.97
Jul 1 13:00:00 - 13:59:59 1176 4.9% 0.333.5 MB 4.5% 1.00
Jul 1 14:00:00 - 14:59:59 1273 5.3% 0.353.4 MB 4.3% 0.97
Jul 1 15:00:00 - 15:59:59 1302 5.4% 0.363.6 MB 4.6% 1.04
Jul 1 16:00:00 - 16:59:59 1516 6.3% 0.425.1 MB 6.5% 1.44
Jul 1 17:00:00 - 17:59:59 1571 6.5% 0.444.9 MB 6.2% 1.39
Jul 1 18:00:00 - 18:59:59 1259 5.2% 0.353.1 MB 4.0% 0.89
Jul 1 19:00:00 - 19:59:59 1174 4.9% 0.334.1 MB 5.2% 1.17
Jul 1 20:00:00 - 20:59:59 1226 5.1% 0.344.2 MB 5.3% 1.19
Jul 1 21:00:00 - 21:59:59 1152 4.8% 0.324.6 MB 5.9% 1.32
Jul 1 22:00:00 - 22:59:59 1098 4.5% 0.305.1 MB 6.5% 1.44
Jul 1 23:00:00 - 23:59:59 1066 4.4% 0.303.4 MB 4.3% 0.96
Jul 2 00:00:00 - 00:00:01 5 0.0% 5.0010.8 KB 0.0% 10.80
TOTAL: 23:59:58 24151 100.0% 0.2878.5 MB 100.0% 0.93
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9280 1397 0 0 2 0 0 0 7881
2linuxd-out.news.weretis.net 5494 1906 0 0 0 0 0 0 3588
3news.fcku.it 225 2 0 0 0 0 0 0 223
TOTAL: 3 14999 3305 0 0 2 0 0 011692

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 800
lada.talk 193
mailing.freebsd.pkg-fallout 155
ut.test 105
lada.auto 89
mailing.freebsd.ports-bugs 89
it-alt.sport.calcio.juventus 82
alt.sex.telephone.alt.sex 67
mailing.freebsd.svn-ports-all 64
alt.sex.breasts 62
mailing.freebsd.svn-ports-head 61
alt.windows7.general 40
mailing.openssl.cvs 37
gmane.linux.alsa.devel 36
mozilla.support.thunderbird 34
mailing.freebsd.questions 33
mailing.freebsd.fs 32
mozilla.support.firefox 31
24hoursupport.helpdesk 31
mailing.gcc.gcc-cvs 31
TOTAL: 345 3305

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

GroupsCount
it.scienza.astronomia 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 8652
EMP (phn path) 1596
EMP (phl) 639
EMP (phn nph) 142
PFM bot misc 112
Too many newsgroups 53
Binary Payload 33
User-issued spam cancel 23
User-issued cancel 11
HTML post 7
Binary: non-binary distribution 5
Binary Image: misplaced jpg 3
TOTAL: 12 11276

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 373
newsfeed.neva.ru 36
news.fcku.it 7
TOTAL: 3 416
TOTAL: 1 416

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 30794 1266 19558 2232 0 104 4%23:58:24
2nntp.pasdenom.info 43673 915 22544 632 0 620 2%23:58:46
3weretis.net 4223 185 3624 0 0 1 4%24:06:29
4neva.ru 32615 131 20919 364 0 325 0%24:02:38
5news.mixmin.net 0 0 0 0 0 0 0%00:10:00
6news.netfront.net 2757 0 0 0 0 26802 0%23:58:03
7etla.org 0 0 0 0 0 24251 0%24:07:38
8news.stack.nl 2856 0 0 0 0 26894 0%23:58:05
9eternal-september 0 0 0 0 0 20460 0%24:07:38
10news.albasani.net 2853 0 0 0 0 26073 0%23:58:05
11news.killfile.org 2843 0 0 0 0 26967 0%23:58:18
12vc89.vc.panix.com 2834 0 0 0 0 26958 0%23:58:18
TOTAL: 12 125448 2497 66645 3228 0 179455 1%264:22:22
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info4.2 MB1.6 MB5.7 MB0.1 KB/s3.8 KB23:58:46
2news.fcku.it3.4 MB7.3 MB10.8 MB0.1 KB/s3.2 KB23:58:24
3neva.ru880.8 KB1.3 MB2.1 MB0.0 KB/s4.4 KB24:02:38
4weretis.net848.0 KB0.0 KB848.0 KB0.0 KB/s4.6 KB24:06:29
5news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
6news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:03
7etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:38
8news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:05
9eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:38
10news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:05
11news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:18
12vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:18
TOTAL: 129.3 MB10.2 MB19.5 MB0.0 KB/s3.5 KB264:22:22
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 115 1942.9 KB 18 0 000:38:29
TOTAL: 1 115 1942.9 KB 18 0 005:06:52

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 115 1942.9 KB 18 0 000:38:29
2*.rev.poneytelecom.eu 1 00.0 KB 0 0 000:00:10
3*.188.099.pools.vodafone-ip.de 112 00.0 KB 0 0 000:00:25
4unresolved 4 00.0 KB 0 0 001:44:16
5*.nielsenmedia.com 7 00.0 KB 0 0 002:43:30
TOTAL: 5 239 1942.9 KB 18 0 005:06:52

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.436 0.016 0.00002:43:30
71.98.160.36 0.192 0.016 0.00001:44:16
mtrlpq2314w-lp130-02-1176034363.dsl.bell.ca 10.296 1.756 0.00000:38:29
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 5.896 0.368 0.00000:00:25
62-210-192-149.rev.poneytelecom.eu 0.060 0.000 0.00000:00:10
TOTAL: 5 16.880 2.156 0.00005:06:52

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 112
challenger.nielsenmedia.com 7
71.98.160.36 4
62-210-192-149.rev.poneytelecom.eu 1
TOTAL: 4 124

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 4 0
71.98.160.36 3 0
TOTAL: 2 7 0

Newsgroup request counts (by category):

CategoryCountPct
1uk 8 42.1%
2rec 6 31.6%
3alt 3 15.8%
4comp 2 10.5%
TOTAL: 4 19100.0%

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

NewsgroupCount
1uk.legal 3
2uk.media.radio.archers 2
3rec.arts.tv 2
4alt.happy.valley 2
5uk.rec.sheds 1
6rec.sport.tennis 1
7uk.sci.weather 1
8comp.mobile.android 1
9alt.sixtyplus 1
10comp.lang.python 1
11rec.autos.sport.f1 1
12rec.food.cooking 1
13rec.outdoors.rv-travel 1
14uk.d-i-y 1
TOTAL: 14 19