Daily Usenet report for csiph.com

Jan 26 04:15:00 -- Jan 28 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Jan 26 20:30:46 csiph controlchan[46615]: Cannot send mail: (<checkgroups-1548534005@news.novabbs.com>)

Log entries by program:

Program nameLines%LinesSize%Size
inn 43573 60.5%9.4 MB 70.9%
innd 21664 30.1%2.9 MB 21.6%
innfeed 5679 7.9%896.6 KB 6.6%
nnrpd 1083 1.5%125.2 KB 0.9%
controlchan 11 0.0%2.3 KB 0.0%
nocem 1 0.0%0.2 KB 0.0%
TOTAL: 6 72011 100.0%13.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 575362 52.8%
Negative hits 471583 43.3%
Do not exist 42282 3.9%
Cache misses 554 0.1%
TOTAL: 4 1089781 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.297 0.0%51 0.000 25.431 263.000
article cleanup00:00:00.234 0.0%41705 0.000 0.006 0.032
article logging00:00:01.175 0.0%42799 0.000 0.027 6.709
article parse00:00:08.008 0.0%129277 0.000 0.062 0.761
article write00:10:06.024 0.4%34175 1.664 17.733 75.633
artlog/artcncl00:00:00.000 0.0%50 0.000 0.000 0.000
artlog/artparse00:00:00.012 0.0%732 0.000 0.016 1.000
data move00:00:00.202 0.0%1039979 0.000 0.000 0.002
hisgrep/artcncl00:00:00.060 0.0%50 0.000 1.200 15.000
hishave/artcncl00:00:00.019 0.0%51 0.000 0.373 3.400
hishave/artparse00:00:00.005 0.0%727 0.000 0.007 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:04:02.123 0.1%1092741 0.001 0.222 0.970
history sync00:00:15.025 0.0%739 0.000 20.332 421.000
history write00:06:26.397 0.2%41688 0.197 9.269 31.556
hiswrite/artcncl00:00:00.001 0.0%1 1.000 1.000 1.000
hiswrite/artparse00:00:06.889 0.0%726 0.000 9.489 177.333
idle47:20:59.095 98.6%998973 90.847 170.634 690.193
nntp read00:00:04.668 0.0%1053549 0.001 0.004 0.038
overview write00:17:22.617 0.6%34175 1.250 30.508 220.031
perl filter00:01:13.597 0.0%41675 1.200 1.766 4.490
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.669 0.0%68352 0.000 0.010 0.049
TOTAL: 48:01:58.87048:00:48.117100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.117 0.0%34572 0.000 0.003 0.029
article prepare00:00:00.004 0.0%29928 0.000 0.000 0.012
article read00:00:18.432 0.0%3170 0.000 5.815 375.500
backlog stats00:00:00.367 0.0%616252 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:03.009 0.0%479030 0.001 0.006 0.013
data write00:00:03.093 0.0%448881 0.000 0.007 0.015
idle47:59:06.937100.0%616252 155.201 280.319 1030.338
status file00:00:03.688 0.0%527 0.000 6.998 316.000
TOTAL: 47:59:51.04547:59:35.647100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle45:56:19.613100.0%804 62.500205696.036600408.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.421 0.0%1820 0.000 0.231 10.175
readart00:00:00.750 0.0%43 0.000 17.442 48.000
TOTAL: 45:57:11.00745:56:20.784100.0%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 38
name 1
pause 2
paused 2
reload 4
reserve 2
TOTAL: 11 62

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
usenet@novabbs.com001001
TOTAL001001

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 13 103632 7604 95441 587 7%144:57:10
2csiph-out.feeder.erje.net 2880 176349 5190 168957 2202 2%237:42:37
3news-out.mixmin.net 6 35931 5138 29272 1521 14%48:20:06
4news.redatomik.org 6 31954 4240 27471 243 13%48:17:39
5feeder.eternal-september.org 4 37207 3810 32986 411 10%46:25:24
6news-out.aioe.org 5 33170 2824 30275 71 8%48:02:29
7newsfeed.fu-berlin.de 12 32110 1901 29825 384 5%49:15:00
8newsfeed.neva.ru 286 29905 1620 25759 2526 5%143:24:41
9newsfeeds.xmission.com 2 44063 767 43269 27 1%61:11:19
10news.freedyn.net 4 36588 513 35824 251 1%47:59:48
11news.fcku.it 31 2909 290 2613 6 9%44:15:20
12nntp.pasdenom.info 4 29029 173 28839 17 0%47:58:42
13news.swapon.de 10 2944 98 2844 2 3%46:16:01
14localhost 61 10 10 0 0 100%00:00:03
15news.bbs.nz 3 33430 1 33429 0 0%47:59:23
16xanadu-bbs.net 47 1004 0 1004 0 0%43:25:51
TOTAL: 16 3374 630235 34179 587808 8248 5%1105:31:33
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net24.6 MB4.8 KB23.1 MB47.6 MB 51%6.6 KB
2csiph-out.news.weretis.net24.4 MB29.2 KB12.6 MB37.0 MB 65%4.6 KB
3news-out.mixmin.net14.9 MB6.8 KB3.4 MB18.3 MB 81%2.8 KB
4news.redatomik.org12.1 MB4.2 KB6.0 MB18.1 MB 66%4.1 KB
5feeder.eternal-september.org10.1 MB4.0 KB7.8 MB17.9 MB 56%4.3 KB
6news-out.aioe.org6.9 MB53.9 KB377.6 KB7.3 MB 94%2.6 KB
7newsfeed.neva.ru5.0 MB2.7 KB11.7 MB16.7 MB 29%4.1 KB
8newsfeed.fu-berlin.de4.2 MB0.0 KB850.1 KB5.0 MB 83%2.2 KB
9newsfeeds.xmission.com2.1 MB18.9 KB2.1 MB4.2 MB 49%5.4 KB
10news.freedyn.net2.1 MB6.8 KB3.6 MB5.7 MB 36%7.6 KB
11news.fcku.it552.4 KB0.0 KB13.5 KB565.9 KB 97%1.9 KB
12nntp.pasdenom.info372.5 KB1.4 KB32.6 KB406.5 KB 91%2.1 KB
13news.swapon.de235.0 KB0.0 KB286.6 KB521.6 KB 45%5.2 KB
14localhost18.3 KB0.0 KB0.0 KB18.3 KB100%1.8 KB
15news.bbs.nz1.2 KB0.0 KB0.0 KB1.2 KB100%1.2 KB
16xanadu-bbs.net0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 16107.5 MB132.9 KB71.7 MB179.3 MB 59%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 26 04:15:00 - 04:59:59 216 0.6% 0.08675.3 KB 0.6% 0.25
Jan 26 05:00:00 - 05:59:59 388 1.1% 0.111.1 MB 1.0% 0.32
Jan 26 06:00:00 - 06:59:59 336 1.0% 0.091.0 MB 0.9% 0.29
Jan 26 07:00:00 - 07:59:59 500 1.5% 0.141.7 MB 1.6% 0.48
Jan 26 08:00:00 - 08:59:59 539 1.6% 0.151.9 MB 1.8% 0.54
Jan 26 09:00:00 - 09:59:59 654 1.9% 0.182.3 MB 2.1% 0.65
Jan 26 10:00:00 - 10:59:59 767 2.2% 0.212.2 MB 2.1% 0.63
Jan 26 11:00:00 - 11:59:59 760 2.2% 0.213.2 MB 2.9% 0.90
Jan 26 12:00:00 - 12:59:59 690 2.0% 0.192.2 MB 2.0% 0.61
Jan 26 13:00:00 - 13:59:59 759 2.2% 0.212.1 MB 1.9% 0.59
Jan 26 14:00:00 - 14:59:59 917 2.7% 0.252.7 MB 2.5% 0.76
Jan 26 15:00:00 - 15:59:59 952 2.8% 0.263.1 MB 2.9% 0.89
Jan 26 16:00:00 - 16:59:59 1016 3.0% 0.282.6 MB 2.4% 0.74
Jan 26 17:00:00 - 17:59:59 956 2.8% 0.272.7 MB 2.5% 0.77
Jan 26 18:00:00 - 18:59:59 942 2.8% 0.262.7 MB 2.5% 0.78
Jan 26 19:00:00 - 19:59:59 1014 3.0% 0.283.0 MB 2.8% 0.85
Jan 26 20:00:00 - 20:59:59 824 2.4% 0.232.2 MB 2.0% 0.62
Jan 26 21:00:00 - 21:59:59 908 2.7% 0.252.9 MB 2.7% 0.82
Jan 26 22:00:00 - 22:59:59 866 2.5% 0.242.6 MB 2.4% 0.73
Jan 26 23:00:00 - 23:59:59 750 2.2% 0.212.6 MB 2.4% 0.75
Jan 27 00:00:00 - 00:59:59 629 1.8% 0.171.8 MB 1.7% 0.52
Jan 27 01:00:00 - 01:59:59 425 1.2% 0.121.4 MB 1.3% 0.38
Jan 27 02:00:00 - 02:59:59 360 1.1% 0.101.1 MB 1.0% 0.31
Jan 27 03:00:00 - 03:59:59 377 1.1% 0.101.6 MB 1.5% 0.46
Jan 27 04:00:00 - 04:59:59 363 1.1% 0.101.1 MB 1.0% 0.30
Jan 27 05:00:00 - 05:59:59 283 0.8% 0.08877.7 KB 0.8% 0.24
Jan 27 06:00:00 - 06:59:59 312 0.9% 0.091.1 MB 1.0% 0.31
Jan 27 07:00:00 - 07:59:59 414 1.2% 0.121.6 MB 1.5% 0.45
Jan 27 08:00:00 - 08:59:59 681 2.0% 0.192.6 MB 2.4% 0.73
Jan 27 09:00:00 - 09:59:59 771 2.3% 0.212.6 MB 2.4% 0.74
Jan 27 10:00:00 - 10:59:59 776 2.3% 0.222.2 MB 2.0% 0.62
Jan 27 11:00:00 - 11:59:59 786 2.3% 0.222.6 MB 2.4% 0.73
Jan 27 12:00:00 - 12:59:59 816 2.4% 0.233.3 MB 3.0% 0.93
Jan 27 13:00:00 - 13:59:59 797 2.3% 0.222.3 MB 2.1% 0.64
Jan 27 14:00:00 - 14:59:59 825 2.4% 0.232.4 MB 2.3% 0.70
Jan 27 15:00:00 - 15:59:59 881 2.6% 0.243.0 MB 2.8% 0.85
Jan 27 16:00:00 - 16:59:59 870 2.5% 0.242.3 MB 2.1% 0.66
Jan 27 17:00:00 - 17:59:59 1049 3.1% 0.293.4 MB 3.2% 0.97
Jan 27 18:00:00 - 18:59:59 1128 3.3% 0.313.2 MB 3.0% 0.91
Jan 27 19:00:00 - 19:59:59 1044 3.1% 0.293.4 MB 3.2% 0.97
Jan 27 20:00:00 - 20:59:59 1167 3.4% 0.323.3 MB 3.1% 0.94
Jan 27 21:00:00 - 21:59:59 956 2.8% 0.273.0 MB 2.8% 0.85
Jan 27 22:00:00 - 22:59:59 789 2.3% 0.222.2 MB 2.0% 0.62
Jan 27 23:00:00 - 23:59:59 837 2.4% 0.232.8 MB 2.6% 0.80
Jan 28 00:00:00 - 00:59:59 631 1.8% 0.181.9 MB 1.8% 0.54
Jan 28 01:00:00 - 01:59:59 554 1.6% 0.152.0 MB 1.8% 0.56
Jan 28 02:00:00 - 02:59:59 396 1.2% 0.111.3 MB 1.2% 0.38
Jan 28 03:00:00 - 03:59:59 415 1.2% 0.121.8 MB 1.7% 0.52
Jan 28 04:00:00 - 04:15:00 83 0.2% 0.09371.9 KB 0.3% 0.41
TOTAL: 48:00:00 34169 100.0% 0.20107.7 MB 100.0% 0.64
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 2534 306 0 1 0 0 0 0 2227
2csiph-out.feeder.erje.net 2229 202 0 2 0 0 0 0 2025
3news-out.mixmin.net 1535 0 0 2 0 0 0 0 1533
4newsfeed.fu-berlin.de 1331 54 0 0 0 0 0 0 1277
5csiph-out.news.weretis.net 631 238 0 3 0 0 0 0 390
6feeder.eternal-september.org 438 37 0 1 0 0 0 0 400
7news.redatomik.org 255 12 0 2 0 0 0 0 241
8news.freedyn.net 250 10 0 2 0 0 0 0 238
9news-out.aioe.org 80 45 0 7 0 0 0 0 28
10news.swapon.de 29 0 0 0 0 0 0 0 29
11newsfeeds.xmission.com 19 0 0 1 0 0 0 0 18
12nntp.pasdenom.info 17 0 0 1 0 0 0 0 16
13news.fcku.it 6 2 0 0 0 0 0 0 4
TOTAL: 13 9354 906 0 22 0 0 0 0 8426

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 194
lada.auto 58
alt.music.pink-floyd.publius 49
alt.bbs.synchronet 36
gmane.comp.gis.openstreetmap.tagging 34
spline.fli4l 33
it-alt.sport.calcio.juventus 30
mozilla.support.seamonkey 29
gmane.linux.alsa.devel 28
alt.windows7.general 23
alt.binaries.ebook 16
alt.survival 15
alt.binaries.documentaries 15
alt.politics 15
lada.kitchen 15
mozilla.support.thunderbird 14
oesterreich.tratsch 14
alt.politics.usa 12
redatomik.test 12
Alt.binaries.sounds.mp3.soul-rhythm-and-blues 10
TOTAL: 138 906

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5027
EMP (phn path) 461
Binary: misplaced par2 288
EMP (phn nph) 217
Binary: misplaced nfo 155
User-issued cancel 148
Too many newsgroups 123
EMP (phl) 41
HTML Multipart 25
Binary: misplaced rar 24
Binary Payload 23
User-issued spam cancel 14
Scoring filter (10) 11
Scoring filter (12) 6
Scoring filter (8) 6
Too many newsgroups (meow) 4
HTML post 4
Scoring filter (9) 3
Too many newsgroups (low_xpost) 3
HTML Image Tags 3
TOTAL: 23 6592

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 90
TOTAL: 1 90
Huge articles
csiph-out.feeder.erje.net 347
csiph-out.news.weretis.net 150
feeder.eternal-september.org 120
news.redatomik.org 50
news.freedyn.net 26
news-out.mixmin.net 18
newsfeeds.xmission.com 14
news.swapon.de 2
TOTAL: 8 727
Including strange strings
newsfeed.fu-berlin.de 941
csiph-out.news.weretis.net 38
news-out.mixmin.net 30
csiph-out.feeder.erje.net 27
news.swapon.de 27
news.redatomik.org 14
news-out.aioe.org 7
newsfeed.neva.ru 5
newsfeeds.xmission.com 3
feeder.eternal-september.org 2
TOTAL: 10 1094
No colon-space in header
csiph-out.feeder.erje.net 5
TOTAL: 1 5
TOTAL: 4 1916

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.freedyn.net 33975 1402 32243 2 0 0 4%47:59:47
2xanadu-bbs 36125 1379 32790 0 0 0 3%47:59:53
3fu-berlin.de 31848 1304 30544 0 0 0 4%47:59:33
4news.bbs.nz 61424 224 32592 20 0 2 0%47:59:24
5usenet.pasdenom.info 34865 81 31206 2708 0 6 0%47:59:55
6aioe 28449 23 27952 0 0 393 0%47:59:42
7erje 29189 17 28775 13 0 0 0%47:59:26
8neva.ru 31189 15 30740 221 0 0 0%47:59:42
9xmission 37538 13 31903 1 0 0 0%47:59:43
10news.redatomik.org 29009 12 28532 0 0 6 0%47:59:55
11eternal-september 29108 11 28601 0 0 0 0%47:59:52
12news.mixmin.net 27608 11 27155 0 0 0 0%47:59:47
13weretis.net 24986 11 24843 4 0 0 0%47:59:12
14news.swapon.de 3103 9 3076 1 0 0 0%47:41:22
15news.fcku.it 2549 0 2547 0 0 0 0%43:51:30
TOTAL: 15 440965 4512 393499 2970 0 407 1%715:28:43
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.freedyn.net14.4 MB3.7 KB14.4 MB0.1 KB/s10.5 KB47:59:47
2xanadu-bbs14.4 MB0.0 KB14.4 MB0.1 KB/s10.7 KB47:59:53
3fu-berlin.de14.2 MB0.0 KB14.2 MB0.1 KB/s11.2 KB47:59:33
4news.bbs.nz530.5 KB184.2 KB714.7 KB0.0 KB/s2.9 KB47:59:24
5usenet.pasdenom.info148.3 KB16.5 MB16.6 MB0.1 KB/s6.1 KB47:59:55
6aioe40.2 KB0.0 KB40.2 KB0.0 KB/s1.7 KB47:59:42
7neva.ru34.3 KB532.9 KB567.2 KB0.0 KB/s2.4 KB47:59:42
8erje30.0 KB51.3 KB81.4 KB0.0 KB/s2.7 KB47:59:26
9xmission24.1 KB1.5 KB25.6 KB0.0 KB/s1.8 KB47:59:43
10news.redatomik.org24.1 KB0.0 KB24.1 KB0.0 KB/s2.0 KB47:59:55
11eternal-september20.0 KB0.0 KB20.0 KB0.0 KB/s1.8 KB47:59:52
12news.mixmin.net20.0 KB0.0 KB20.0 KB0.0 KB/s1.8 KB47:59:47
13weretis.net20.0 KB33.4 KB53.4 KB0.0 KB/s3.6 KB47:59:12
14news.swapon.de17.4 KB0.9 KB18.3 KB0.0 KB/s1.8 KB47:41:22
15news.fcku.it0.0 KB0.0 KB0.0 KB0.0 KB/s043:51:30
TOTAL: 1543.9 MB17.3 MB61.1 MB0.0 KB/s8.4 KB715:28:43
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
171-82-226-171.dhcp.stpt.wi.charter.com 71 1833.0 KB 16 11 922:09:25
2a131033.upc-a.chello.nl 62 1218.1 KB 5 0 017:18:36
3208-71-239-109.cust.centrio.cz 4 1018.0 KB 2 0 000:23:53
4p2e5b5424.dip0.t-ipconnect.de 1 22.9 KB 1 0 000:00:06
5ip72-222-200-117.ph.ph.cox.net 9 11.5 KB 1 0 004:04:37
TOTAL: 5 147 4373.4 KB 25 11 945:57:03

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dhcp.stpt.wi.charter.com 71 1833.0 KB 16 11 922:09:25
2*.upc-a.chello.nl 62 1218.1 KB 5 0 017:18:36
3*.cust.centrio.cz 4 1018.0 KB 2 0 000:23:53
4*.dip0.t-ipconnect.de 1 22.9 KB 1 0 000:00:06
5*.ph.ph.cox.net 9 11.5 KB 1 0 004:04:37
6*.hsd1.wa.comcast.net 3 00.0 KB 0 0 001:30:02
7unresolved 5 00.0 KB 0 0 000:00:14
8*.security.ipip.net 1 00.0 KB 0 0 000:00:00
9*.nielsenmedia.com 1 00.0 KB 0 0 000:30:07
TOTAL: 9 157 4373.4 KB 25 11 945:57:03

NNRP auth users [Top 20]:

UserConn
ohlandl 9
ctomczyk 4
TOTAL: 2 13

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
71-82-226-171.dhcp.stpt.wi.charter.com 5.969 1.093 0.02622:09:25
a131033.upc-a.chello.nl 4.722 0.831 0.00017:18:36
ip72-222-200-117.ph.ph.cox.net 1.014 0.142 0.00004:04:37
c-67-160-62-214.hsd1.wa.comcast.net 0.193 0.069 0.00001:30:02
challenger.nielsenmedia.com 0.065 0.019 0.00000:30:07
208-71-239-109.cust.centrio.cz 0.416 0.055 0.01000:23:53
104.131.145.95 0.069 0.015 0.00000:00:09
p2e5b5424.dip0.t-ipconnect.de 0.078 0.071 0.00000:00:06
94.163.149.85 0.047 0.039 0.00000:00:01
94.164.213.84 0.068 0.009 0.00000:00:01
5.86.93.107 0.062 0.016 0.00000:00:01
scan-141.security.ipip.net 0.067 0.015 0.00000:00:00
185.156.177.76 0.078 0.007 0.00000:00:00
TOTAL: 13 12.848 2.381 0.03645:57:03

Curious NNRP server explorers [Top 20]:

SystemConn
c-67-160-62-214.hsd1.wa.comcast.net 3
94.163.149.85 1
104.131.145.95 1
challenger.nielsenmedia.com 1
185.156.177.76 1
5.86.93.107 1
94.164.213.84 1
scan-141.security.ipip.net 1
TOTAL: 8 10

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 4
185.156.177.76 1
TOTAL: 2 5

NNRP unrecognized commands (by command):

CommandCount
^C 1
User-Agent: HTTP Banner Detection (https... 1
GET / HTTP/1.1 1
Host: 69.160.37.58:119 1
Connection: close 1
TOTAL: 5 5

NNRP client timeouts [Top 20]:

SystemConnPeer
71-82-226-171.dhcp.stpt.wi.charter.com 24 0
ip72-222-200-117.ph.ph.cox.net 8 0
c-67-160-62-214.hsd1.wa.comcast.net 3 0
challenger.nielsenmedia.com 1 0
185.156.177.76 1 1
TOTAL: 5 37 1

Newsgroup request counts (by category):

CategoryCountPct
1comp 31 72.1%
2pl 10 23.3%
3alt 2 4.7%
TOTAL: 3 43100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 31
2pl.comp.lang.javascript 10
3alt.free.newsservers 2
TOTAL: 3 43