Daily Usenet report for csiph.com: Nov 6 04:15:00

Unknown entries from news log file:

First 50 / 2031 lines (2.5%)

Nov 6 10:55:54 csiph nnrpd[45330]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:56:35 csiph nnrpd[45347]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:57:05 csiph nnrpd[45357]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:57:36 csiph nnrpd[45383]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:58:08 csiph nnrpd[45384]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:58:39 csiph nnrpd[45545]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:59:09 csiph nnrpd[45546]: fleegle.mixmin.net noperm ihave without permission
Nov 6 10:59:40 csiph nnrpd[45557]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:00:11 csiph nnrpd[45617]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:00:41 csiph nnrpd[45619]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:01:11 csiph nnrpd[45649]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:01:41 csiph nnrpd[45650]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:02:13 csiph nnrpd[45676]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:02:44 csiph nnrpd[45677]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:03:14 csiph nnrpd[45710]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:03:45 csiph nnrpd[45717]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:04:16 csiph nnrpd[45749]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:04:46 csiph nnrpd[45756]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:05:16 csiph nnrpd[45796]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:05:47 csiph nnrpd[45807]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:06:18 csiph nnrpd[45838]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:06:49 csiph nnrpd[45848]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:07:20 csiph nnrpd[45865]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:07:50 csiph nnrpd[45889]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:08:21 csiph nnrpd[46029]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:08:51 csiph nnrpd[46055]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:09:22 csiph nnrpd[46056]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:09:52 csiph nnrpd[46082]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:10:23 csiph nnrpd[46096]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:10:54 csiph nnrpd[46122]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:11:25 csiph nnrpd[46144]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:11:56 csiph nnrpd[46170]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:12:26 csiph nnrpd[46171]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:12:57 csiph nnrpd[46197]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:13:28 csiph nnrpd[46198]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:13:58 csiph nnrpd[46224]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:14:29 csiph nnrpd[46225]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:15:00 csiph nnrpd[46243]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:15:31 csiph nnrpd[46262]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:16:02 csiph nnrpd[46263]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:16:33 csiph nnrpd[46290]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:17:04 csiph nnrpd[46291]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:17:35 csiph nnrpd[46317]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:18:06 csiph nnrpd[46318]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:18:36 csiph nnrpd[46481]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:19:08 csiph nnrpd[46485]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:19:38 csiph nnrpd[46511]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:20:08 csiph nnrpd[46522]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:20:39 csiph nnrpd[46548]: fleegle.mixmin.net noperm ihave without permission
Nov 6 11:21:10 csiph nnrpd[46549]: fleegle.mixmin.net noperm ihave without permission

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	61022	45.3%	8.5 MB	55.5%
nnrpd	42556	31.6%	3.8 MB	24.5%
innd	29355	21.8%	2.8 MB	18.1%
innfeed	1887	1.4%	288.3 KB	1.8%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	134821	100.0%	15.4 MB	100.0%

History cache:

Reason	Count	%Count
Negative hits	289135	45.6%
Positive hits	285149	44.9%
Do not exist	60004	9.5%
Cache misses	359	0.1%
TOTAL: 4	634647	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.380	0.0%	212	0.000	1.792	25.000
article cleanup	00:00:00.372	0.0%	56999	0.000	0.007	0.025
article logging	00:00:00.775	0.0%	57493	0.000	0.013	0.037
article parse	00:00:06.956	0.0%	758530	0.002	0.009	0.019
article write	00:00:13.294	0.0%	28596	0.171	0.465	1.139
artlog/artcncl	00:00:00.001	0.0%	177	0.000	0.006	0.071
artlog/artparse	00:00:00.041	0.0%	3114	0.000	0.013	0.250
data move	00:00:00.763	0.0%	1249056	0.000	0.001	0.001
hisgrep/artcncl	00:00:00.003	0.0%	212	0.000	0.014	1.000
hishave/artcncl	00:00:00.001	0.0%	212	0.000	0.005	1.000
hishave/artparse	00:00:00.018	0.0%	3114	0.000	0.006	0.143
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:02.005	0.0%	632951	0.001	0.003	0.009
history sync	00:00:00.669	0.0%	308	0.000	2.172	4.000
history write	00:00:18.130	0.0%	56994	0.075	0.318	0.417
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
hiswrite/artparse	00:00:00.736	0.0%	3114	0.000	0.236	4.000
idle	23:56:21.964	99.7%	1223488	8.919	70.440	395.309
nntp read	00:00:04.115	0.0%	1222538	0.001	0.003	0.006
overview write	00:00:14.107	0.0%	28596	0.108	0.493	25.177
perl filter	00:02:09.374	0.1%	56994	0.843	2.270	4.431
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.530	0.0%	57192	0.000	0.009	0.032
TOTAL: 24:00:27.883	23:59:34.234	99.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.069	0.0%	28726	0.000	0.002	0.023
article prepare	00:00:00.003	0.0%	6792	0.000	0.000	0.028
article read	00:00:00.205	0.0%	1299	0.000	0.158	22.000
backlog stats	00:00:00.113	0.0%	320095	0.000	0.000	0.002
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.303	0.0%	245842	0.000	0.005	0.012
data write	00:00:01.701	0.0%	221956	0.001	0.008	0.017
idle	23:54:30.839	100.0%	320095	163.045	268.892	939.333
status file	00:00:00.090	0.0%	195	0.000	0.462	1.000
TOTAL: 23:54:38.539	23:54:34.323	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.879	0.0%	52991	0.000	0.017	0.333
idle	82:26:42.947	99.9%	90602	1.000	3275.898	600091.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.106	0.0%	182144	0.000	0.006	0.333
readart	00:00:00.088	0.0%	71	0.000	1.239	55.000
TOTAL: 82:31:35.081	82:26:45.020	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	1	53255	7949	38407	6899	14%	24:09:00
2	au2pb.net	1	30987	6622	23324	1041	21%	23:02:58
3	news-out.mixmin.net	4	36469	3830	29568	3071	10%	24:19:30
4	xanadu-bbs.net	9	59326	3462	46873	8991	5%	32:25:01
5	newsfeed.neva.ru	141	66613	2745	53246	10622	4%	71:33:26
6	newsfeeds.xmission.com	1	28875	2091	26631	153	7%	23:12:43
7	newsfeed.fu-berlin.de	53	35215	1241	33496	478	3%	33:55:10
8	nntp.pasdenom.info	12	29274	297	28797	180	1%	24:16:43
9	news.swapon.de	2	2478	278	2179	21	11%	23:26:57
10	localhost	26	0	0	0	0	0%	00:00:01
TOTAL: 10		250	342492	28515	282521	31456	8%	280:21:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	22.8 MB	12.5 KB	58.8 MB	81.6 MB	27%	5.6 KB
2	au2pb.net	18.5 MB	0.0 KB	3.6 MB	22.1 MB	83%	3.0 KB
3	news-out.mixmin.net	13.7 MB	31.7 KB	6.5 MB	20.3 MB	67%	3.0 KB
4	xanadu-bbs.net	10.3 MB	44.2 KB	1006.2 MB	1016.5 MB	1%	83.6 KB
5	newsfeed.neva.ru	9.5 MB	0.0 KB	100.9 MB	110.4 MB	8%	8.5 KB
6	newsfeeds.xmission.com	5.9 MB	0.0 KB	976.0 KB	6.9 MB	86%	3.1 KB
7	newsfeed.fu-berlin.de	2.7 MB	0.0 KB	1.1 MB	3.8 MB	69%	2.3 KB
8	news.swapon.de	712.6 KB	0.0 KB	96.7 KB	809.3 KB	88%	2.7 KB
9	nntp.pasdenom.info	689.2 KB	31.6 KB	2.3 MB	3.0 MB	22%	6.5 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		84.7 MB	119.9 KB	1.2 GB	1.2 GB	6%	21.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 6 04:15:00 - 04:59:59	458	1.6%	0.17	1.4 MB	1.7%	0.54
Nov 6 05:00:00 - 05:59:59	626	2.2%	0.17	2.0 MB	2.4%	0.57
Nov 6 06:00:00 - 06:59:59	825	2.9%	0.23	3.8 MB	4.4%	1.07
Nov 6 07:00:00 - 07:59:59	908	3.2%	0.25	2.5 MB	2.9%	0.71
Nov 6 08:00:00 - 08:59:59	1171	4.1%	0.33	3.6 MB	4.2%	1.02
Nov 6 09:00:00 - 09:59:59	1421	5.0%	0.39	3.8 MB	4.4%	1.07
Nov 6 10:00:00 - 10:59:59	1420	5.0%	0.39	3.8 MB	4.4%	1.07
Nov 6 11:00:00 - 11:59:59	1256	4.4%	0.35	3.4 MB	4.0%	0.97
Nov 6 12:00:00 - 12:59:59	1423	5.0%	0.40	4.1 MB	4.8%	1.16
Nov 6 13:00:00 - 13:59:59	1517	5.3%	0.42	4.1 MB	4.8%	1.16
Nov 6 14:00:00 - 14:59:59	1616	5.6%	0.45	4.6 MB	5.5%	1.32
Nov 6 15:00:00 - 15:59:59	1602	5.6%	0.45	4.5 MB	5.3%	1.28
Nov 6 16:00:00 - 16:59:59	1742	6.1%	0.48	5.1 MB	6.0%	1.46
Nov 6 17:00:00 - 17:59:59	1638	5.7%	0.46	4.9 MB	5.7%	1.39
Nov 6 18:00:00 - 18:59:59	1535	5.4%	0.43	4.2 MB	4.9%	1.18
Nov 6 19:00:00 - 19:59:59	1477	5.2%	0.41	4.2 MB	4.9%	1.18
Nov 6 20:00:00 - 20:59:59	1619	5.7%	0.45	4.9 MB	5.7%	1.39
Nov 6 21:00:00 - 21:59:59	1282	4.5%	0.36	3.7 MB	4.4%	1.05
Nov 6 22:00:00 - 22:59:59	1152	4.0%	0.32	3.6 MB	4.2%	1.02
Nov 6 23:00:00 - 23:59:59	1136	4.0%	0.32	3.5 MB	4.1%	1.00
Nov 7 00:00:00 - 00:59:59	768	2.7%	0.21	2.4 MB	2.9%	0.69
Nov 7 01:00:00 - 01:59:59	644	2.3%	0.18	2.1 MB	2.5%	0.60
Nov 7 02:00:00 - 02:59:59	714	2.5%	0.20	2.7 MB	3.2%	0.78
Nov 7 03:00:00 - 03:59:59	534	1.9%	0.15	1.8 MB	2.1%	0.50
Nov 7 04:00:00 - 04:15:00	133	0.5%	0.15	373.3 KB	0.4%	0.41
TOTAL: 24:00:00	28617	100.0%	0.33	84.8 MB	100.0%	1.01

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.neva.ru	10763	1681	0	9082
2	xanadu-bbs.net	9156	576	2	8578
3	feeder.eternal-september.org	6889	375	1	6513
4	news-out.mixmin.net	3091	8	1	3082
5	au2pb.net	1085	1033	0	52
6	newsfeed.fu-berlin.de	835	63	0	772
7	nntp.pasdenom.info	181	154	1	26
8	newsfeeds.xmission.com	169	155	0	14
9	news.swapon.de	59	0	0	59
TOTAL: 9		32228	4045	5	28178

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	917
lada.talk	307
lada.auto	141
mailing.freebsd.pkg-fallout	139
mailing.freebsd.ports-bugs	115
alt.politics.scorched-earth	94
ut.test	93
mailing.freebsd.svn-ports-all	92
alt.politics	91
mailing.freebsd.svn-ports-head	86
it-alt.sport.calcio.juventus	80
mailing.gcc.gcc-cvs	78
mailing.freebsd.svn-src-all	67
fa.freebsd.cvs-all	66
alt.survival	65
mailing.freebsd.svn-src-head	65
nemo.test2	58
mozilla.dev.tree-alerts	50
it-alt.discussioni.ebay	41
mailing.openbsd.source-changes	41
TOTAL: 262	4045

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	18690
EMP (phn path)	3952
EMP (phl)	1151
EMP (phn nph)	308
PFM bot misc	198
Binary: misplaced rar	92
Too many newsgroups	63
HTML Multipart	43
HTML post	27
Binary Image: misplaced jpg	18
Too many newsgroups (low_xpost)	14
HTML Image Tags	7
Binary: misplaced rev	5
User-issued spam cancel	2
Binary: misplaced BRK	1
TOTAL: 15	24571

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	2972
	feeder.eternal-september.org	78
	news-out.mixmin.net	23
	nntp.pasdenom.info	20
	au2pb.net	13
	newsfeeds.xmission.com	5
	newsfeed.fu-berlin.de	2
	news.swapon.de	1
	TOTAL: 8	3114
Including strange strings
	newsfeed.fu-berlin.de	395
	news.swapon.de	38
	feeder.eternal-september.org	15
	news-out.mixmin.net	14
	au2pb.net	13
	xanadu-bbs.net	10
	newsfeeds.xmission.com	7
	newsfeed.neva.ru	1
	TOTAL: 8	493
TOTAL: 2		3607

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	22696	193	21513	166	0	0%	23:59:58
2	usenet.pasdenom.info	34159	187	27408	725	130	0%	23:59:58
3	xanadu-bbs	27575	122	24916	96	12	0%	23:59:58
4	news.mixmin.net	26350	48	24573	0	0	0%	23:59:52
5	fu-berlin.de	27027	34	26967	26	0	0%	23:59:41
6	xmission	34001	16	26329	1	0	0%	23:59:56
7	eternal-september	21204	7	20125	8	0	0%	23:59:57
8	neva.ru	26834	4	25673	61	0	0%	23:59:57
9	news.swapon.de	2202	0	2189	4	0	0%	23:59:25
TOTAL: 9		222048	611	199693	1087	142	0%	215:58:42

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	xanadu-bbs	898.0 KB	228.4 KB	1.1 MB	5.2 KB	23:59:58
2	au2pb	824.7 KB	462.2 KB	1.3 MB	3.6 KB	23:59:58
3	usenet.pasdenom.info	742.9 KB	1.9 MB	2.6 MB	2.9 KB	23:59:58
4	news.mixmin.net	168.4 KB	0.0 KB	168.4 KB	3.5 KB	23:59:52
5	fu-berlin.de	138.6 KB	34.9 KB	173.5 KB	2.9 KB	23:59:41
6	xmission	35.0 KB	1.5 KB	36.5 KB	2.1 KB	23:59:56
7	eternal-september	34.7 KB	10.6 KB	45.3 KB	3.0 KB	23:59:57
8	neva.ru	28.3 KB	312.6 KB	340.9 KB	5.2 KB	23:59:57
9	news.swapon.de	0.0 KB	5.0 KB	5.0 KB	1.2 KB	23:59:25
TOTAL: 9		2.8 MB	2.9 MB	5.7 MB	3.4 KB	215:58:42

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	65	135.7 KB	59	04:49:19
2	challenger.nielsenmedia.com	4	2	3.8 KB	2	01:00:22
3	ip70-186-183-52.br.br.cox.net	2	2	3.8 KB	1	00:04:45
4	dhcp-089-099-203-254.chello.nl	22	1	1.6 KB	1	04:45:58
5	tempe0.bbox.io	6	1	1.6 KB	1	02:28:31
TOTAL: 5		322	71	146.6 KB	64	82:31:13

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	321	65	135.7 KB	59	68:39:33
2	*.br.br.cox.net	2	2	3.8 KB	1	00:04:45
3	*.nielsenmedia.com	4	2	3.8 KB	2	01:00:22
4	*.bbox.io	6	1	1.6 KB	1	02:28:31
5	*.chello.nl	22	1	1.6 KB	1	04:45:58
6	*.mixmin.net	2031	0	0.0 KB	0	00:20:17
7	*.tampfl.dsl-w.verizon.net	11	0	0.0 KB	0	01:39:42
8	*.ip-188-165-239.eu	115	0	0.0 KB	0	01:25:36
9	*.147.22.98.dynamic.ip.windstream.net	3	0	0.0 KB	0	02:06:25
TOTAL: 9		2515	71	146.6 KB	64	82:31:13

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	1.043	0.921	0.000	63:50:12
202.89.176.220	28.190	6.018	0.727	04:49:19
dhcp-089-099-203-254.chello.nl	1.987	0.294	0.000	04:45:58
tempe0.bbox.io	0.658	0.078	0.000	02:28:31
h168.147.22.98.dynamic.ip.windstream.net	0.223	0.085	0.000	02:06:25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	0.884	0.166	0.000	01:39:42
ns342002.ip-188-165-239.eu	10.219	2.904	0.000	01:25:36
challenger.nielsenmedia.com	0.453	0.079	0.000	01:00:22
fleegle.mixmin.net	169.450	33.276	0.000	00:20:17
ip70-186-183-52.br.br.cox.net	0.204	0.017	0.000	00:04:45
69.160.37.54	2.018	0.273	0.000	00:00:02
TOTAL: 11	215.329	44.111	0.727	82:31:13

Curious NNRP server explorers [Top 20]:

System	Conn
fleegle.mixmin.net	2031
ns342002.ip-188-165-239.eu	115
69.160.37.54	25
pool-71-98-160-84.tampfl.dsl-w.verizon.net	11
162.212.88.214	8
h168.147.22.98.dynamic.ip.windstream.net	3
TOTAL: 6	2193

NNRP client timeouts [Top 20]:

System	Conn	Peer
162.212.88.214	4	4
tempe0.bbox.io	4	0
pool-71-98-160-84.tampfl.dsl-w.verizon.net	2	0
challenger.nielsenmedia.com	2	0
h168.147.22.98.dynamic.ip.windstream.net	2	0
TOTAL: 5	14	4

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	71	100.0%
TOTAL: 1		71	100.0%

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

Newsgroup		Count
1	comp.sys.ibm.pc.games.war-historical	16
2	comp.os.vms	15
3	comp.arch	12
4	comp.arch.embedded	4
5	comp.os.os2.apps	4
6	comp.sys.ibm.ps2.hardware	4
7	comp.sys.ibm.pc.hardware.storage	3
8	comp.ai.philosophy	3
9	comp.sys.ibm.pc.games.adventure	2
10	comp.sys.ibm.pc.games.action	2
11	comp.sys.apple2	2
12	comp.lang.awk	2
13	comp.admin.policy	1
14	comp.ai.neural-nets	1
TOTAL: 14		71

Daily Usenet report for csiph.com

Nov 6 04:15:00 -- Nov 7 04:15:00