Daily Usenet report for paganini.linuxd.org

Jan 11 00:00:02 -- Jan 12 00:00:03

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Jan 11 03:55:22 paganini overchan[27790]: input line too long, skipping
Jan 11 11:03:43 paganini overchan[27790]: input line too long, skipping
Jan 11 13:25:57 paganini overchan[27790]: input line too long, skipping
Jan 11 19:45:38 paganini overchan[27790]: input line too long, skipping
Jan 12 00:00:02 paganini overchan[18715]: 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 101300 61.7%12.5 MB 51.5%
inn 42949 26.1%9.1 MB 37.5%
innd 19379 11.8%2.6 MB 10.6%
nnrpd 552 0.3%76.8 KB 0.3%
nocem 117 0.1%18.6 KB 0.1%
overchan 6 0.0%1.1 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 164304 100.0%24.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 146127 53.6%
Negative hits 84590 31.0%
Do not exist 41937 15.4%
Cache misses 78 0.0%
TOTAL: 4 272732 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.490 0.0%205 0.000 2.390 66.000
article cleanup00:00:00.239 0.0%42097 0.000 0.006 0.023
article logging00:00:00.381 0.0%42729 0.000 0.009 0.034
article parse00:00:01.163 0.0%93271 0.002 0.012 0.031
article write00:00:55.860 0.1%23610 0.225 2.366 23.293
artlog/artcncl00:00:00.001 0.0%205 0.000 0.005 0.053
data move00:00:00.168 0.0%281752 0.000 0.001 0.003
hisgrep/artcncl00:00:00.005 0.0%204 0.000 0.025 0.077
hishave/artcncl00:00:00.002 0.0%205 0.000 0.010 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:15.967 0.0%273801 0.015 0.058 1.047
history sync00:00:00.107 0.0%307 0.000 0.349 46.500
history write00:00:41.366 0.0%42091 0.021 0.983 4.277
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:55:46.175 99.7%281011 176.014 306.558 910.725
nntp read00:00:02.227 0.0%291943 0.003 0.008 0.015
overview write00:00:25.544 0.0%23610 0.022 1.082 3.266
perl filter00:01:32.061 0.1%42091 1.142 2.187 6.232
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.550 0.0%71113 0.000 0.022 0.060
TOTAL: 24:00:23.85223:59:43.306100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.088 0.0%39063 0.000 0.002 0.011
article prepare00:00:00.004 0.0%23124 0.000 0.000 0.004
article read00:00:04.804 0.0%4931 0.000 0.974 32.333
backlog stats00:00:00.231 0.0%371825 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.511 0.0%202572 0.003 0.007 0.020
data write00:00:03.009 0.0%179555 0.007 0.017 0.030
idle23:41:56.015 99.3%371825 149.626 229.452 447.542
status file00:10:10.750 0.7%42286 6.803 14.443 58.255
TOTAL: 23:52:35.46623:52:16.412100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:07:30.428 99.9%471 29.000 39172.883600140.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.016 0.0%1218 0.000 0.013 0.125
TOTAL: 05:07:54.11505:07:30.444 99.9%----

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 52359 20572 29239 2548 39%48:10:52
2newsfeed.neva.ru 150 87852 2651 70330 14871 3%71:13:29
3news.fcku.it 2 8265 221 7981 63 2%22:46:52
4nntp.pasdenom.info 2 38507 58 37470 979 0%24:11:59
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 184 186983 23502 145020 18461 12%166:23:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net64.6 MB2.3 KB15.8 MB80.3 MB 80%3.6 KB
2newsfeed.neva.ru7.5 MB6.8 KB129.6 MB137.2 MB 5%8.0 KB
3news.fcku.it469.6 KB2.5 KB209.5 KB681.6 KB 68%2.4 KB
4nntp.pasdenom.info142.2 KB17.5 KB4.2 MB4.4 MB 3%4.3 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 572.7 MB29.1 KB149.8 MB222.6 MB 32%5.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 11 00:00:02 - 00:59:59 860 3.6% 0.242.6 MB 3.6% 0.75
Jan 11 01:00:00 - 01:59:59 662 2.8% 0.182.0 MB 2.8% 0.58
Jan 11 02:00:00 - 02:59:59 588 2.5% 0.162.1 MB 2.8% 0.59
Jan 11 03:00:00 - 03:59:59 504 2.1% 0.142.4 MB 3.3% 0.69
Jan 11 04:00:00 - 04:59:59 401 1.7% 0.111.2 MB 1.6% 0.34
Jan 11 05:00:00 - 05:59:59 471 2.0% 0.131.2 MB 1.7% 0.35
Jan 11 06:00:00 - 06:59:59 461 2.0% 0.131.5 MB 2.1% 0.44
Jan 11 07:00:00 - 07:59:59 515 2.2% 0.141.7 MB 2.3% 0.48
Jan 11 08:00:00 - 08:59:59 691 2.9% 0.192.1 MB 2.9% 0.61
Jan 11 09:00:00 - 09:59:59 915 3.9% 0.252.5 MB 3.4% 0.72
Jan 11 10:00:00 - 10:59:59 1138 4.8% 0.323.1 MB 4.2% 0.87
Jan 11 11:00:00 - 11:59:59 1185 5.0% 0.332.9 MB 4.0% 0.84
Jan 11 12:00:00 - 12:59:59 996 4.2% 0.282.8 MB 3.8% 0.80
Jan 11 13:00:00 - 13:59:59 1064 4.5% 0.303.4 MB 4.7% 0.98
Jan 11 14:00:00 - 14:59:59 1220 5.2% 0.343.3 MB 4.5% 0.95
Jan 11 15:00:00 - 15:59:59 1172 5.0% 0.333.1 MB 4.2% 0.88
Jan 11 16:00:00 - 16:59:59 1449 6.1% 0.404.4 MB 6.0% 1.26
Jan 11 17:00:00 - 17:59:59 1467 6.2% 0.414.6 MB 6.3% 1.32
Jan 11 18:00:00 - 18:59:59 1359 5.8% 0.384.2 MB 5.8% 1.20
Jan 11 19:00:00 - 19:59:59 1415 6.0% 0.394.9 MB 6.6% 1.38
Jan 11 20:00:00 - 20:59:59 1443 6.1% 0.405.6 MB 7.6% 1.59
Jan 11 21:00:00 - 21:59:59 1217 5.2% 0.343.2 MB 4.3% 0.91
Jan 11 22:00:00 - 22:59:59 1243 5.3% 0.353.7 MB 5.0% 1.04
Jan 11 23:00:00 - 23:59:59 1155 4.9% 0.324.8 MB 6.5% 1.35
Jan 12 00:00:00 - 00:00:03 9 0.0% 3.0036.1 KB 0.0% 12.03
TOTAL: 24:00:01 23600 100.0% 0.2773.6 MB 100.0% 0.87
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 14974 490 0 3 0 0 0 014481
2linuxd-out.news.weretis.net 3151 704 0 1 0 0 0 0 2446
3nntp.pasdenom.info 950 0 0 1 0 0 0 0 949
4news.fcku.it 77 0 0 1 0 0 0 0 76
TOTAL: 4 19152 1194 0 6 0 0 0 017952

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.auto 143
lada.talk 131
ut.test 113
it.annunci.varie 69
it.annunci.commerciali 67
it.annunci.immobiliari 67
alt.windows7.general 46
alt.survival 34
it-alt.sport.calcio.juventus 28
alt.politics.scorched-earth 27
gmane.linux.alsa.devel 23
mpc.lists.nanog 21
mozilla.support.seamonkey 21
lada.movies 20
uk.radio.amateur.moderated 17
spline.eisfair 16
lada.commerce 15
alt.2600 14
gmane.comp.gis.openstreetmap.tagging 14
mozilla.dev.security.policy 14
TOTAL: 110 1194

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14801
EMP (phn path) 1235
EMP (phr path) 616
EMP (phl) 428
EMP (phn nph) 139
Too many newsgroups 50
User-issued cancel 15
Binary Payload 13
HTML post 11
User-issued spam cancel 5
Binary Image: misplaced jpg 3
Binary: non-binary distribution 2
Binary: misplaced JPG 1
Too many newsgroups (low_xpost) 1
TOTAL: 14 17320

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 600
newsfeed.neva.ru 31
news.fcku.it 1
TOTAL: 3 632
TOTAL: 1 632

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 33708 1347 20066 1913 0 363 3%23:59:49
2nntp.pasdenom.info 42637 1169 21161 1173 0 1000 2%23:57:41
3weretis.net 3179 86 2892 0 0 10 2%23:59:18
4neva.ru 30637 59 20781 62 0 91 0%23:59:49
5eternal-september 25517 20 20150 0 0 71 0%23:59:46
6etla.org 0 0 0 0 0 23600 0%23:59:49
7vc89.vc.panix.com 2840 0 0 0 0 26439 0%23:59:49
8news.albasani.net 2863 0 0 0 0 25558 0%23:59:49
9news.stack.nl 2859 0 0 0 0 26413 0%23:59:49
10news.killfile.org 2847 0 0 0 0 26446 0%23:59:49
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12news.netfront.net 2739 0 0 0 0 26251 0%23:59:49
TOTAL: 12 149826 2681 85050 3148 0 156242 1%264:05:17
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info3.9 MB2.1 MB5.9 MB0.1 KB/s2.6 KB23:57:41
2news.fcku.it3.7 MB6.7 MB10.5 MB0.1 KB/s3.3 KB23:59:49
3neva.ru183.8 KB223.6 KB407.4 KB0.0 KB/s3.4 KB23:59:49
4weretis.net117.7 KB0.0 KB117.7 KB0.0 KB/s1.4 KB23:59:18
5eternal-september68.3 KB0.0 KB68.3 KB0.0 KB/s3.4 KB23:59:46
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
7vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
8news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
9news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
TOTAL: 128.0 MB9.0 MB17.0 MB0.0 KB/s3.0 KB264:05:17
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-094-216-025-116.094.216.pools.vodafone-ip.de 134 00.0 KB 0 0 000:00:27
247-192-187-101.drr02.clwr.fl.frontiernet.net 5 00.0 KB 0 0 001:00:29
3challenger.nielsenmedia.com 28 00.0 KB 0 0 004:06:55
4ip232.208-100-26.static.steadfastdns.net 2 00.0 KB 0 0 000:00:01
TOTAL: 4 169 00.0 KB 0 0 005:07:53

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.094.216.pools.vodafone-ip.de 134 00.0 KB 0 0 000:00:27
2*.drr02.clwr.fl.frontiernet.net 5 00.0 KB 0 0 001:00:29
3*.208-100-26.static.steadfastdns.net 2 00.0 KB 0 0 000:00:01
4*.nielsenmedia.com 28 00.0 KB 0 0 004:06:55
TOTAL: 4 169 00.0 KB 0 0 005:07:53

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.740 0.112 0.00004:06:55
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.316 0.028 0.00001:00:29
dslb-094-216-025-116.094.216.pools.vodafone-ip.de 7.060 0.496 0.00000:00:27
ip232.208-100-26.static.steadfastdns.net 0.108 0.004 0.00000:00:01
TOTAL: 4 9.224 0.640 0.00005:07:53

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 6 1
47-192-187-101.drr02.clwr.fl.frontiernet.net 2 0
TOTAL: 2 8 1