Daily Usenet report for csiph.com: Oct 22 04:15:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	57522	48.8%	8.1 MB	59.6%
nnrpd	32236	27.3%	2.7 MB	19.6%
innd	26308	22.3%	2.5 MB	18.6%
innfeed	1895	1.6%	312.1 KB	2.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	117962	100.0%	13.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	286744	46.2%
Negative hits	273896	44.1%
Do not exist	56766	9.1%
Cache misses	3609	0.6%
TOTAL: 4	621015	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.755	0.0%	221	0.000	3.416	28.000
article cleanup	00:00:00.348	0.0%	54863	0.000	0.006	0.024
article logging	00:00:00.636	0.0%	55415	0.000	0.011	0.029
article parse	00:00:08.428	0.0%	483134	0.002	0.017	0.051
article write	00:01:25.075	0.1%	28351	0.153	3.001	11.444
artlog/artcncl	00:00:00.001	0.0%	194	0.000	0.005	0.059
artlog/artparse	00:00:00.022	0.0%	1927	0.000	0.011	0.167
data move	00:00:00.415	0.0%	955622	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.176	0.0%	215	0.000	0.819	22.000
hishave/artcncl	00:00:00.000	0.0%	221	0.000	0.000	0.000
hishave/artparse	00:00:00.008	0.0%	1927	0.000	0.004	0.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.773	0.0%	619539	0.001	0.004	0.049
history sync	00:00:01.709	0.0%	352	0.000	4.855	47.000
history write	00:00:43.091	0.0%	54854	0.084	0.786	10.508
hiswrite/artcncl	00:00:00.009	0.0%	6	0.000	1.500	4.000
hiswrite/artparse	00:00:04.878	0.0%	1926	0.000	2.533	53.000
idle	23:54:43.155	99.6%	943132	9.087	91.274	353.798
nntp read	00:00:02.999	0.0%	944888	0.001	0.003	0.007
overview write	00:01:00.828	0.1%	28351	0.216	2.146	11.238
perl filter	00:02:00.808	0.1%	54854	0.968	2.202	4.208
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.498	0.0%	56702	0.000	0.009	0.032
TOTAL: 24:00:51.320	24:00:16.612	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.074	0.0%	28286	0.000	0.003	0.020
article prepare	00:00:00.003	0.0%	6920	0.000	0.000	0.029
article read	00:00:00.231	0.0%	1355	0.000	0.170	9.400
backlog stats	00:00:00.106	0.0%	315582	0.000	0.000	0.003
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.297	0.0%	243288	0.002	0.005	0.010
data write	00:00:01.870	0.0%	219147	0.004	0.009	0.019
idle	23:53:51.290	100.0%	315582	154.280	272.612	714.798
status file	00:00:00.094	0.0%	203	0.000	0.463	1.000
TOTAL: 23:53:59.385	23:53:54.965	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.437	0.0%	11918	0.000	0.037	1.000
idle	50:28:23.017	99.9%	43489	0.000	4178.137	600161.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.800	0.0%	88265	0.000	0.009	6.727
readart	00:00:12.647	0.0%	4201	0.000	3.010	44.000
TOTAL: 50:30:57.097	50:28:36.901	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	4
flushlogs	2
go	2
logmode	2
mode	146
pause	2
paused	2
reload	2
reserve	2
TOTAL: 10	165

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feeder.eternal-september.org	3	51160	7313	38229	5618	14%	24:19:23
2	au2pb.net	1	31279	6154	24087	1038	19%	24:15:26
3	xanadu-bbs.net	7	56439	4203	44353	7883	7%	26:32:53
4	news-out.mixmin.net	3	34338	3890	27611	2837	11%	24:09:40
5	newsfeed.neva.ru	147	68027	2744	54475	10808	4%	71:54:07
6	newsfeeds.xmission.com	1	29134	2163	26873	98	7%	23:55:59
7	newsfeed.fu-berlin.de	62	32704	1315	31127	262	4%	34:14:03
8	nntp.pasdenom.info	4	39241	486	38633	122	1%	32:59:11
9	news.swapon.de	4	2404	257	2147	0	10%	27:36:27
10	localhost	50	10951	0	10951	0	0%	00:01:03
TOTAL: 10		282	355677	28525	298486	28666	8%	289:58:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.7 MB	4.0 KB	46.2 MB	66.9 MB	30%	5.3 KB
2	au2pb.net	16.1 MB	0.0 KB	4.7 MB	20.8 MB	77%	3.0 KB
3	news-out.mixmin.net	12.2 MB	2.7 KB	6.0 MB	18.1 MB	67%	2.8 KB
4	xanadu-bbs.net	11.9 MB	37.3 KB	414.2 MB	426.1 MB	2%	36.1 KB
5	newsfeed.neva.ru	8.8 MB	108.0 KB	103.2 MB	112.1 MB	7%	8.5 KB
6	newsfeeds.xmission.com	6.6 MB	0.0 KB	3.7 MB	10.2 MB	64%	4.6 KB
7	newsfeed.fu-berlin.de	2.9 MB	0.0 KB	593.0 KB	3.5 MB	83%	2.3 KB
8	nntp.pasdenom.info	1.1 MB	0.0 KB	1.1 MB	2.1 MB	49%	3.6 KB
9	news.swapon.de	576.6 KB	0.0 KB	0.0 KB	576.6 KB	100%	2.2 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		80.7 MB	151.9 KB	579.6 MB	660.4 MB	12%	11.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 22 04:15:00 - 04:59:59	427	1.5%	0.16	1.6 MB	2.0%	0.62
Oct 22 05:00:00 - 05:59:59	592	2.1%	0.16	1.8 MB	2.3%	0.52
Oct 22 06:00:00 - 06:59:59	743	2.6%	0.21	2.0 MB	2.5%	0.57
Oct 22 07:00:00 - 07:59:59	1206	4.3%	0.34	3.2 MB	4.0%	0.92
Oct 22 08:00:00 - 08:59:59	1418	5.0%	0.39	3.7 MB	4.7%	1.06
Oct 22 09:00:00 - 09:59:59	1574	5.6%	0.44	4.4 MB	5.5%	1.25
Oct 22 10:00:00 - 10:59:59	1326	4.7%	0.37	3.6 MB	4.4%	1.01
Oct 22 11:00:00 - 11:59:59	1184	4.2%	0.33	3.2 MB	4.0%	0.91
Oct 22 12:00:00 - 12:59:59	1445	5.1%	0.40	4.1 MB	5.1%	1.18
Oct 22 13:00:00 - 13:59:59	1488	5.3%	0.41	3.9 MB	4.8%	1.10
Oct 22 14:00:00 - 14:59:59	1778	6.3%	0.49	5.1 MB	6.3%	1.44
Oct 22 15:00:00 - 15:59:59	1790	6.3%	0.50	4.9 MB	6.0%	1.38
Oct 22 16:00:00 - 16:59:59	1641	5.8%	0.46	4.7 MB	5.8%	1.33
Oct 22 17:00:00 - 17:59:59	1518	5.4%	0.42	4.3 MB	5.4%	1.23
Oct 22 18:00:00 - 18:59:59	1584	5.6%	0.44	4.5 MB	5.6%	1.28
Oct 22 19:00:00 - 19:59:59	1547	5.5%	0.43	4.4 MB	5.5%	1.26
Oct 22 20:00:00 - 20:59:59	1306	4.6%	0.36	3.3 MB	4.1%	0.95
Oct 22 21:00:00 - 21:59:59	1416	5.0%	0.39	4.0 MB	5.0%	1.14
Oct 22 22:00:00 - 22:59:59	1015	3.6%	0.28	2.8 MB	3.5%	0.80
Oct 22 23:00:00 - 23:59:59	793	2.8%	0.22	2.3 MB	2.9%	0.66
Oct 23 00:00:00 - 00:59:59	706	2.5%	0.20	2.1 MB	2.6%	0.59
Oct 23 01:00:00 - 01:59:59	605	2.1%	0.17	2.3 MB	2.8%	0.65
Oct 23 02:00:00 - 02:59:59	572	2.0%	0.16	1.7 MB	2.1%	0.49
Oct 23 03:00:00 - 03:59:59	502	1.8%	0.14	1.8 MB	2.2%	0.51
Oct 23 04:00:00 - 04:15:00	163	0.6%	0.18	559.8 KB	0.7%	0.62
TOTAL: 24:00:00	28339	100.0%	0.33	80.4 MB	100.0%	0.95

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	10622	1644	4	8974
2	xanadu-bbs.net	7946	579	1	7366
3	feeder.eternal-september.org	5573	470	2	5101
4	news-out.mixmin.net	2864	11	1	2852
5	au2pb.net	1054	1001	0	53
6	newsfeed.fu-berlin.de	680	72	0	608
7	newsfeeds.xmission.com	120	67	0	53
8	nntp.pasdenom.info	104	78	1	25
9	news.swapon.de	27	0	0	27
TOTAL: 9		28990	3922	9	25059

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	795
alt.politics.scorched-earth	237
lada.talk	236
mailing.freebsd.pkg-fallout	177
ut.test	157
it-alt.sport.calcio.juventus	128
lada.auto	90
mailing.openbsd.source-changes	88
mailing.freebsd.ports-bugs	88
gmane.linux.alsa.devel	81
mailing.freebsd.svn-ports-all	66
mailing.freebsd.svn-ports-head	66
fa.openbsd.source-changes	62
alt.politics	61
mailing.freebsd.svn-src-all	61
fa.freebsd.cvs-all	58
mailing.freebsd.svn-src-head	58
mailing.gcc.gcc-cvs	52
alt.windows7.general	47
spline.eisfair	43
TOTAL: 263	3922

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	17444
EMP (phn path)	3289
EMP (phl)	1034
EMP (phn nph)	313
PFM bot misc	196
HTML Multipart	94
HTML post	59
Too many newsgroups	54
Too many newsgroups (low_xpost)	44
Binary Image: misplaced jpg	26
Binary Payload	17
Binary Image: misplaced jpeg	3
User-issued spam cancel	3
Too many newsgroups (meow)	2
HTML Image Tags	1
Binary: misplaced com	1
TOTAL: 16	22580

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	1804
	feeder.eternal-september.org	39
	newsfeeds.xmission.com	33
	au2pb.net	26
	news-out.mixmin.net	14
	nntp.pasdenom.info	10
	newsfeed.fu-berlin.de	1
	TOTAL: 7	1927
Including strange strings
	newsfeed.fu-berlin.de	403
	xanadu-bbs.net	36
	news.swapon.de	27
	news-out.mixmin.net	25
	feeder.eternal-september.org	25
	newsfeeds.xmission.com	20
	au2pb.net	15
	newsfeed.neva.ru	1
	TOTAL: 8	552
TOTAL: 2		2479

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	23423	335	21684	198	31	1%	23:59:56
2	usenet.pasdenom.info	32898	170	27213	626	4	0%	23:59:57
3	xanadu-bbs	26273	104	23944	84	3	0%	23:59:57
4	fu-berlin.de	26662	45	26586	30	1	0%	23:59:57
5	news.mixmin.net	25915	33	24301	0	2	0%	23:59:53
6	xmission	32849	29	25981	1	1	0%	23:59:39
7	neva.ru	26925	7	25364	67	1	0%	23:59:57
8	eternal-september	21981	2	20514	0	2	0%	23:59:50
9	news.swapon.de	2117	0	2097	4	0	0%	23:49:01
TOTAL: 9		219043	725	197684	1010	45	0%	215:48:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.2 MB	466.2 KB	1.7 MB	3.2 KB	23:59:56
2	usenet.pasdenom.info	641.7 KB	1.4 MB	2.0 MB	2.6 KB	23:59:57
3	xanadu-bbs	615.1 KB	171.9 KB	787.0 KB	4.2 KB	23:59:57
4	fu-berlin.de	233.3 KB	38.0 KB	271.3 KB	3.6 KB	23:59:57
5	news.mixmin.net	140.4 KB	0.0 KB	140.4 KB	4.3 KB	23:59:53
6	xmission	99.4 KB	2.3 KB	101.7 KB	3.4 KB	23:59:39
7	neva.ru	48.5 KB	303.1 KB	351.6 KB	4.8 KB	23:59:57
8	eternal-september	5.2 KB	0.0 KB	5.2 KB	2.6 KB	23:59:50
9	news.swapon.de	0.0 KB	13.5 KB	13.5 KB	3.4 KB	23:49:01
TOTAL: 9		3.0 MB	2.4 MB	5.3 MB	3.1 KB	215:48:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	127	347.9 KB	112	04:25:45
2	tempe0.bbox.io	10	11	24.3 KB	5	01:13:48
3	dhcp-089-099-203-254.chello.nl	14	1	1.2 KB	1	05:08:42
TOTAL: 3		312	139	373.4 KB	118	50:30:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	368	127	347.9 KB	112	41:54:42
2	*.bbox.io	10	11	24.3 KB	5	01:13:48
3	*.chello.nl	14	1	1.2 KB	1	05:08:42
4	*.nielsenmedia.com	2	0	0.0 KB	0	01:00:07
5	*.ip-188-165-239.eu	10	0	0.0 KB	0	00:02:53
6	*.tampfl.dsl-w.verizon.net	6	0	0.0 KB	0	01:10:38
TOTAL: 6		410	139	373.4 KB	118	50:30:53

NNRP auth users [Top 20]:

User	Conn
moussa	288
TOTAL: 1	288

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
162.212.88.214	0.539	0.525	0.000	33:47:38
dhcp-089-099-203-254.chello.nl	1.207	0.235	0.000	05:08:42
202.89.176.220	29.436	6.447	0.715	04:25:45
63.142.120.8	3.506	0.833	0.000	03:40:59
tempe0.bbox.io	0.934	0.175	0.000	01:13:48
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.389	0.106	0.000	01:10:38
challenger.nielsenmedia.com	0.119	0.031	0.000	01:00:07
ns342002.ip-188-165-239.eu	0.870	0.198	0.000	00:02:53
173.0.15.153	0.230	0.376	0.000	00:00:16
69.160.37.54	1.945	0.425	0.000	00:00:02
TOTAL: 10	39.175	9.351	0.715	50:30:53

Curious NNRP server explorers [Top 20]:

System	Conn
63.142.120.8	50
69.160.37.54	25
ns342002.ip-188-165-239.eu	10
pool-71-98-160-84.tampfl.dsl-w.verizon.net	6
162.212.88.214	4
challenger.nielsenmedia.com	2
173.0.15.153	1
TOTAL: 7	98

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	8	0
challenger.nielsenmedia.com	2	0
tempe0.bbox.io	1	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	1	0
TOTAL: 4	12	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	136	97.8%
2	news	3	2.2%
TOTAL: 2		139	100.0%

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

Newsgroup		Count
1	comp.os.vms	31
2	comp.sys.ibm.pc.games.war-historical	30
3	comp.arch	26
4	comp.arch.fpga	21
5	comp.sys.ibm.pc.games.action	7
6	comp.os.linux.networking	4
7	comp.arch.embedded	3
8	news.software.nntp	3
9	comp.sys.ibm.pc.hardware.storage	3
10	comp.sys.ibm.ps2.hardware	3
11	comp.sys.ibm.pc.hardware.misc	2
12	comp.sys.ibm.as400.misc	1
13	comp.unix.aix	1
14	comp.ai.neural-nets	1
15	comp.os.os2.programmer.misc	1
16	comp.arch.storage	1
17	comp.os.os2.advocacy	1
TOTAL: 17		139

Daily Usenet report for csiph.com

Oct 22 04:15:00 -- Oct 23 04:15:00