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

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	51366	48.6%	7.6 MB	60.3%
nnrpd	33220	31.5%	2.8 MB	22.3%
innd	19156	18.1%	1.9 MB	15.3%
innfeed	1872	1.8%	268.6 KB	2.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	105615	100.0%	12.6 MB	100.0%

History cache:

Reason	Count	%Count
Positive hits	283507	48.1%
Negative hits	250220	42.5%
Do not exist	50433	8.6%
Cache misses	4709	0.8%
TOTAL: 4	588869	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.484	0.0%	292	0.000	5.082	48.000
article cleanup	00:00:00.344	0.0%	49636	0.000	0.007	0.023
article logging	00:00:00.583	0.0%	50198	0.000	0.012	0.041
article parse	00:00:04.223	0.0%	267782	0.002	0.016	0.071
article write	00:01:25.511	0.1%	29405	0.175	2.908	9.258
artlog/artcncl	00:00:00.002	0.0%	252	0.000	0.008	0.250
artlog/artparse	00:00:00.004	0.0%	923	0.000	0.004	1.000
data move	00:00:00.294	0.0%	772204	0.000	0.000	0.002
hisgrep/artcncl	00:00:00.150	0.0%	291	0.000	0.515	26.000
hishave/artcncl	00:00:00.001	0.0%	292	0.000	0.003	0.056
hishave/artparse	00:00:00.004	0.0%	923	0.000	0.004	0.100
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:03.112	0.0%	589263	0.000	0.005	0.047
history sync	00:00:01.481	0.0%	339	0.000	4.369	48.500
history write	00:00:38.399	0.0%	49631	0.095	0.774	5.405
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
hiswrite/artparse	00:00:02.071	0.0%	922	0.000	2.246	17.000
idle	23:55:23.234	99.6%	756184	24.786	113.892	309.343
nntp read	00:00:02.399	0.0%	756306	0.000	0.003	0.007
overview write	00:01:11.235	0.1%	29405	0.311	2.423	14.331
perl filter	00:01:20.133	0.1%	49631	1.049	1.615	3.402
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.543	0.0%	58810	0.000	0.009	0.022
TOTAL: 24:00:53.367	24:00:15.207	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.093	0.0%	29365	0.000	0.003	0.018
article prepare	00:00:00.001	0.0%	8440	0.000	0.000	0.014
article read	00:00:00.248	0.0%	1566	0.000	0.158	11.250
backlog stats	00:00:00.107	0.0%	324167	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.299	0.0%	251341	0.000	0.005	0.011
data write	00:00:01.881	0.0%	226228	0.002	0.008	0.017
idle	23:54:15.917	100.0%	324167	135.087	265.468	782.911
status file	00:00:00.091	0.0%	191	0.000	0.476	3.000
TOTAL: 23:54:25.401	23:54:19.637	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:02.303	0.0%	59601	0.000	0.039	0.333
idle	248:59:45.008	100.0%	91384	0.000	9808.993	600197.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.328	0.0%	184087	0.000	0.007	0.167
readart	00:00:40.427	0.0%	11892	0.000	3.400	23.000
TOTAL: 249:02:44.105	249:00:29.066	100.0%	-	-	-	-

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	au2pb.net	1	31938	7208	23756	974	22%	24:04:51
2	feeder.eternal-september.org	6	44979	7150	34627	3202	15%	24:10:00
3	xanadu-bbs.net	1	50559	4555	36998	9006	9%	24:16:32
4	news-out.mixmin.net	4	36223	3477	30320	2426	9%	24:19:31
5	newsfeed.neva.ru	144	65525	3002	57599	4924	4%	71:42:24
6	newsfeeds.xmission.com	2	29992	1878	27976	138	6%	23:24:05
7	newsfeed.fu-berlin.de	68	35971	1531	33977	463	4%	37:35:01
8	nntp.pasdenom.info	4	35398	309	35028	61	0%	28:09:18
9	news.swapon.de	1	2323	248	2056	19	10%	23:04:56
10	localhost	50	11298	0	11298	0	0%	00:01:02
TOTAL: 10		281	344206	29358	293635	21213	8%	280:47:40

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	feeder.eternal-september.org	20.5 MB	0.0 KB	13.4 MB	33.9 MB	60%	3.4 KB
2	au2pb.net	18.0 MB	21.9 KB	7.2 MB	25.2 MB	71%	3.2 KB
3	xanadu-bbs.net	12.5 MB	26.1 KB	168.9 MB	181.4 MB	6%	13.7 KB
4	news-out.mixmin.net	11.1 MB	1.2 KB	5.2 MB	16.3 MB	68%	2.8 KB
5	newsfeed.neva.ru	9.9 MB	0.0 KB	19.1 MB	29.0 MB	34%	3.7 KB
6	newsfeeds.xmission.com	6.3 MB	0.0 KB	3.8 MB	10.1 MB	62%	5.1 KB
7	newsfeed.fu-berlin.de	3.4 MB	0.0 KB	1.2 MB	4.5 MB	74%	2.3 KB
8	nntp.pasdenom.info	649.7 KB	0.0 KB	495.7 KB	1.1 MB	56%	3.1 KB
9	news.swapon.de	589.4 KB	0.0 KB	6.6 MB	7.2 MB	7%	27.7 KB
10	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 10		82.9 MB	49.3 KB	225.9 MB	308.9 MB	26%	6.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 12 04:15:00 - 04:59:59	477	1.6%	0.18	1.6 MB	2.0%	0.62
Oct 12 05:00:00 - 05:59:59	604	2.1%	0.17	1.9 MB	2.3%	0.54
Oct 12 06:00:00 - 06:59:59	765	2.6%	0.21	2.1 MB	2.5%	0.59
Oct 12 07:00:00 - 07:59:59	1223	4.2%	0.34	3.5 MB	4.1%	0.98
Oct 12 08:00:00 - 08:59:59	1446	4.9%	0.40	4.0 MB	4.9%	1.15
Oct 12 09:00:00 - 09:59:59	1365	4.6%	0.38	3.8 MB	4.6%	1.08
Oct 12 10:00:00 - 10:59:59	1381	4.7%	0.38	3.9 MB	4.7%	1.12
Oct 12 11:00:00 - 11:59:59	1217	4.1%	0.34	3.3 MB	4.0%	0.95
Oct 12 12:00:00 - 12:59:59	1544	5.3%	0.43	4.1 MB	4.9%	1.16
Oct 12 13:00:00 - 13:59:59	1672	5.7%	0.46	4.6 MB	5.5%	1.31
Oct 12 14:00:00 - 14:59:59	1807	6.1%	0.50	4.9 MB	5.8%	1.38
Oct 12 15:00:00 - 15:59:59	1888	6.4%	0.52	5.1 MB	6.2%	1.46
Oct 12 16:00:00 - 16:59:59	1779	6.1%	0.49	4.7 MB	5.7%	1.34
Oct 12 17:00:00 - 17:59:59	1780	6.1%	0.49	5.0 MB	6.0%	1.42
Oct 12 18:00:00 - 18:59:59	1755	6.0%	0.49	4.6 MB	5.6%	1.32
Oct 12 19:00:00 - 19:59:59	1542	5.2%	0.43	4.2 MB	5.1%	1.21
Oct 12 20:00:00 - 20:59:59	1466	5.0%	0.41	4.1 MB	4.9%	1.16
Oct 12 21:00:00 - 21:59:59	1415	4.8%	0.39	3.9 MB	4.7%	1.11
Oct 12 22:00:00 - 22:59:59	941	3.2%	0.26	2.8 MB	3.4%	0.80
Oct 12 23:00:00 - 23:59:59	707	2.4%	0.20	2.0 MB	2.3%	0.56
Oct 13 00:00:00 - 00:59:59	630	2.1%	0.17	2.0 MB	2.4%	0.56
Oct 13 01:00:00 - 01:59:59	654	2.2%	0.18	2.1 MB	2.5%	0.59
Oct 13 02:00:00 - 02:59:59	550	1.9%	0.15	2.2 MB	2.6%	0.62
Oct 13 03:00:00 - 03:59:59	612	2.1%	0.17	2.2 MB	2.6%	0.62
Oct 13 04:00:00 - 04:15:00	178	0.6%	0.20	654.7 KB	0.8%	0.73
TOTAL: 24:00:00	29398	100.0%	0.34	83.2 MB	100.0%	0.99

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	xanadu-bbs.net	8914	599	3	8312
2	newsfeed.neva.ru	4916	1769	0	3147
3	feeder.eternal-september.org	3303	307	0	2996
4	news-out.mixmin.net	2445	13	1	2431
5	au2pb.net	995	911	1	83
6	newsfeed.fu-berlin.de	884	25	0	859
7	newsfeeds.xmission.com	144	107	0	37
8	nntp.pasdenom.info	67	51	0	16
9	news.swapon.de	49	0	0	49
TOTAL: 9		21717	3782	5	17930

Unwanted newsgroups [Top 20]:

Newsgroup	Count
fa.linux.kernel	948
lada.talk	295
lada.auto	126
alt.politics	124
alt.job	79
mailing.freebsd.pkg-fallout	77
mailing.freebsd.ports-bugs	76
alt.politics.scorched-earth	70
ut.test	69
it-alt.sport.calcio.juventus	68
mailing.freebsd.svn-ports-head	65
mailing.freebsd.svn-ports-all	65
mailing.openbsd.source-changes	61
pld.cvs.commit	61
alt.windows7.general	54
gmane.linux.alsa.devel	52
mailing.openbsd.tech	49
fa.netbsd.bugs	48
fa.openbsd.source-changes	48
fa.openbsd.tech	45
TOTAL: 288	3782

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12413
EMP (phn path)	2497
EMP (phl)	1018
PFM bot misc	196
EMP (phn nph)	191
HTML Multipart	51
Too many newsgroups	38
Binary Image: misplaced jpg	21
Too many newsgroups (low_xpost)	9
HTML post	5
HTML Image Tags	2
Binary: misplaced txt	1
Binary Payload	1
Binary: misplaced nfo	1
Binary: misplaced zip	1
TOTAL: 15	16445

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Huge articles
	xanadu-bbs.net	751
	feeder.eternal-september.org	55
	au2pb.net	51
	newsfeeds.xmission.com	30
	news-out.mixmin.net	18
	news.swapon.de	11
	nntp.pasdenom.info	5
	newsfeed.fu-berlin.de	2
	TOTAL: 8	923
Including strange strings
	newsfeed.fu-berlin.de	408
	feeder.eternal-september.org	58
	news.swapon.de	30
	au2pb.net	26
	news-out.mixmin.net	20
	newsfeed.neva.ru	6
	xanadu-bbs.net	6
	newsfeeds.xmission.com	5
	nntp.pasdenom.info	3
	TOTAL: 9	562
TOTAL: 2		1485

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	au2pb	22910	340	21659	188	0	1%	23:59:35
2	usenet.pasdenom.info	34472	233	28000	905	4	0%	23:59:56
3	xanadu-bbs	26634	211	24632	32	10	0%	23:59:56
4	fu-berlin.de	27495	52	27417	25	0	0%	23:59:55
5	xmission	34626	20	27277	2	1	0%	23:59:49
6	news.mixmin.net	26597	17	25706	8	0	0%	23:59:56
7	eternal-september	22871	12	21519	6	0	0%	23:59:56
8	neva.ru	27624	8	26063	46	0	0%	23:59:54
9	news.swapon.de	2323	1	2297	4	0	0%	23:59:24
TOTAL: 9		225552	894	204570	1216	15	0%	215:58:21

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Vol/Art	Elapsed
1	au2pb	1.6 MB	698.2 KB	2.3 MB	4.5 KB	23:59:35
2	usenet.pasdenom.info	1.2 MB	1.6 MB	2.9 MB	2.6 KB	23:59:56
3	xanadu-bbs	1.2 MB	85.3 KB	1.3 MB	5.4 KB	23:59:56
4	fu-berlin.de	333.3 KB	36.0 KB	369.2 KB	4.8 KB	23:59:55
5	news.mixmin.net	92.2 KB	16.5 KB	108.8 KB	4.4 KB	23:59:56
6	xmission	37.4 KB	41.1 KB	78.5 KB	3.6 KB	23:59:49
7	neva.ru	34.2 KB	260.7 KB	294.9 KB	5.5 KB	23:59:54
8	eternal-september	33.9 KB	7.5 KB	41.3 KB	2.3 KB	23:59:56
9	news.swapon.de	1.6 KB	9.9 KB	11.5 KB	2.3 KB	23:59:24
TOTAL: 9		4.6 MB	2.8 MB	7.3 MB	3.6 KB	215:58:21

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	202.89.176.220	288	69	180.8 KB	62	04:02:12
2	tempe0.bbox.io	2	2	4.0 KB	1	01:02:12
TOTAL: 2		290	71	184.8 KB	63	249:02:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	489	69	180.8 KB	62	211:42:22
2	*.bbox.io	2	2	4.0 KB	1	01:02:12
3	*.tu.ok.cox.net	4	0	0.0 KB	0	02:01:09
4	*.br.br.cox.net	1	0	0.0 KB	0	00:00:56
5	*.hsd1.nj.comcast.net	11	0	0.0 KB	0	03:28:48
6	*.chello.nl	75	0	0.0 KB	0	30:41:09
7	*.ip-188-165-239.eu	24	0	0.0 KB	0	00:05:57
TOTAL: 7		606	71	184.8 KB	63	249:02:36

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
108.175.241.207	16.129	4.821	0.000	139:01:42
162.212.88.198	1.339	0.737	0.000	50:36:35
dhcp-089-099-203-254.chello.nl	5.966	1.165	0.000	30:41:09
71.94.21.184	1.330	0.817	0.000	18:01:04
202.89.176.220	27.445	5.798	0.719	04:02:12
c-73-195-247-24.hsd1.nj.comcast.net	0.765	0.174	0.000	03:28:48
ip72-222-107-224.tu.ok.cox.net	0.314	0.083	0.000	02:01:09
tempe0.bbox.io	0.208	0.024	0.000	01:02:12
ns342002.ip-188-165-239.eu	1.787	0.375	0.000	00:05:57
ip70-186-183-22.br.br.cox.net	0.097	0.022	0.000	00:00:56
173.0.15.172	0.395	1.041	0.000	00:00:46
69.160.37.54	1.849	0.358	0.000	00:00:01
TOTAL: 12	57.624	15.415	0.719	249:02:36

Curious NNRP server explorers [Top 20]:

System	Conn
108.175.241.207	150
dhcp-089-099-203-254.chello.nl	75
69.160.37.54	25
ns342002.ip-188-165-239.eu	24
71.94.21.184	13
162.212.88.198	12
c-73-195-247-24.hsd1.nj.comcast.net	11
ip72-222-107-224.tu.ok.cox.net	4
ip70-186-183-22.br.br.cox.net	1
173.0.15.172	1
TOTAL: 10	316

NNRP unrecognized commands (by host):

System	Conn
71.94.21.184	13
TOTAL: 1	13

NNRP unrecognized commands (by command):

Command	Count
XFEATURE COMPRESS GZIP	13
TOTAL: 1	13

NNRP client timeouts [Top 20]:

System	Conn	Peer
dhcp-089-099-203-254.chello.nl	55	0
108.175.241.207	50	0
c-73-195-247-24.hsd1.nj.comcast.net	5	0
162.212.88.198	4	0
ip72-222-107-224.tu.ok.cox.net	4	0
tempe0.bbox.io	2	0
71.94.21.184	1	0
TOTAL: 7	121	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	69	97.2%
2	news	2	2.8%
TOTAL: 2		71	100.0%

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

Newsgroup		Count
1	comp.os.vms	17
2	comp.arch.embedded	12
3	comp.sys.ibm.pc.hardware.storage	10
4	comp.arch	9
5	comp.sys.ibm.pc.games.war-historical	7
6	comp.sys.ibm.pc.games.action	4
7	comp.archives.ms-windows.discuss	3
8	comp.sys.ibm.as400.misc	3
9	news.software.nntp	2
10	comp.benchmarks	1
11	comp.ai.neural-nets	1
12	comp.ai.philosophy	1
13	comp.os.os2.apps	1
TOTAL: 13		71

Daily Usenet report for csiph.com

Oct 12 04:15:00 -- Oct 13 04:15:00