Daily Usenet report: Jul 14 15:26:54

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4254407	89.5%	411.2 MB	88.5%
innd	277351	5.8%	32.0 MB	6.9%
nnrpd	222423	4.7%	21.3 MB	4.6%
innfeed	550	0.0%	53.6 KB	0.0%
controlchan	26	0.0%	5.2 KB	0.0%
nocem	21	0.0%	2.1 KB	0.0%
TOTAL: 6	4754778	100.0%	464.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4242908	41.1%
Negative hits	4180502	40.5%
Cache misses	1896429	18.4%
Positive hits	5145	0.0%
TOTAL: 4	10324984	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.911	0.0%	1111	0.000	3.520	268.000
article cleanup	00:00:29.702	0.0%	4251966	0.000	0.007	0.044
article logging	00:00:52.149	0.0%	4253141	0.000	0.012	0.395
article parse	00:09:56.921	0.2%	48647815	0.000	0.012	0.059
article write	04:08:52.896	4.7%	3537028	0.339	4.222	37.660
artlog/artcncl	00:00:00.004	0.0%	242	0.000	0.017	1.000
artlog/artparse	00:00:00.003	0.0%	256	0.000	0.012	0.250
data move	00:01:24.019	0.0%	56021010	0.000	0.001	0.011
hisgrep/artcncl	00:00:01.081	0.0%	1328	0.000	0.814	18.000
hishave/artcncl	00:00:00.000	0.0%	242	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:14.328	0.0%	10351545	0.003	0.013	3.172
history sync	00:00:30.438	0.0%	1214	0.000	25.072	168.500
history write	04:33:23.288	5.2%	3537028	0.276	4.638	22.496
hiswrite/artcncl	00:00:00.034	0.0%	12	0.000	2.833	30.000
idle	72:47:38.954	83.3%	45084321	0.405	5.813	7485.512
nntp read	00:35:43.029	0.7%	50943194	0.000	0.042	0.096
overview write	00:35:36.079	0.7%	3537028	0.109	0.604	3149.068
perl filter	00:02:48.027	0.1%	4000046	0.000	0.042	0.208
python filter	04:03:55.174	4.7%	9823693	0.238	1.490	31.579
site send	00:01:55.978	0.0%	3537280	0.000	0.033	0.236
TOTAL: 87:23:26.809	87:05:26.015	99.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.000	0.0%	0	0.000	0.000	0.000
article prepare	00:00:00.000	0.0%	0	0.000	0.000	0.000
article read	00:00:00.000	0.0%	0	0.000	0.000	0.000
backlog stats	00:00:00.022	0.0%	13586	0.000	0.002	0.346
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.000	0.0%	6	0.000	0.000	0.000
data write	00:00:00.000	0.0%	6	0.000	0.000	0.000
idle	87:14:14.896	100.0%	13586	17151.200	23116.068	25036.708
status file	00:00:00.010	0.0%	12	0.000	0.833	2.000
TOTAL: 87:14:15.145	87:14:14.928	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.851	0.0%	337	0.000	2.525	27.000
idle	00:00:00.000	0.0%	0	0.000	0.000	0.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	01:56:04.830	2.2%	47705033	0.000	0.146	1158.033
readart	00:00:15.646	0.0%	339	7.333	46.153	146.500
TOTAL: 86:40:36.125	01:56:21.327	2.2%	-	-	-	-

Control commands to INND:

Command	Number
flush	8
flushlogs	2
go	3
logmode	2
lowmark	1
mode	472
name	2
pause	3
paused	3
reload	10
reserve	2
TOTAL: 11	508

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	2	0	2	2
TOTAL	0	0	2	0	2	2

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	localhost	70	5572433	3412633	1909862	249938	61%	276:19:42
2	feed1.usenet.blueworldhosting.com	237	590417	124536	0	465881	21%	106:42:12
TOTAL: 2		307	6162850	3537169	1909862	715819	57%	383:01:54

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed1.usenet.blueworldhosting.com	31.4 GB	175.3 GB	206.7 GB	15%	367.2 KB
2	localhost	11.8 GB	32.8 GB	44.6 GB	26%	12.8 KB
TOTAL: 2		43.2 GB	208.1 GB	251.3 GB	17%	62.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 14 15:26:54 - 15:59:59	6864	0.2%	3.46	23.1 MB	0.1%	11.92
Jul 14 16:00:00 - 16:59:59	2118	0.1%	0.59	375.1 MB	0.8%	106.69
Jul 14 17:00:00 - 17:59:59	3601	0.1%	1.00	698.3 MB	1.6%	198.64
Jul 14 18:00:00 - 18:59:59	702	0.0%	0.20	4.0 MB	0.0%	1.13
Jul 14 19:00:00 - 19:59:59	308	0.0%	0.09	1.3 MB	0.0%	0.37
Jul 14 20:00:00 - 20:59:59	291	0.0%	0.08	1.1 MB	0.0%	0.32
Jul 14 21:00:00 - 21:59:59	2898	0.1%	0.81	327.0 MB	0.7%	93.01
Jul 14 22:00:00 - 22:59:59	92410	2.6%	25.67	333.0 MB	0.8%	94.72
Jul 14 23:00:00 - 23:59:59	107373	3.0%	29.83	382.8 MB	0.9%	108.88
Jul 15 00:00:00 - 00:59:59	108393	3.1%	30.11	535.3 MB	1.2%	152.26
Jul 15 01:00:00 - 01:59:59	22642	0.6%	6.29	283.9 MB	0.6%	80.76
Jul 15 02:00:00 - 02:59:59	623	0.0%	0.17	44.7 MB	0.1%	12.73
Jul 15 03:00:00 - 03:59:59	885	0.0%	0.25	73.6 MB	0.2%	20.93
Jul 15 04:00:00 - 04:59:59	2951	0.1%	0.82	848.6 MB	1.9%	241.38
Jul 15 05:00:00 - 05:59:59	9994	0.3%	2.78	3.4 GB	7.9%	995.96
Jul 15 06:00:00 - 06:59:59	5338	0.2%	1.48	1.7 GB	3.9%	497.19
Jul 15 07:00:00 - 07:59:59	644	0.0%	0.18	2.1 MB	0.0%	0.59
Jul 15 08:00:00 - 08:59:59	721	0.0%	0.20	2.4 MB	0.0%	0.68
Jul 15 09:00:00 - 09:59:59	710	0.0%	0.20	5.7 MB	0.0%	1.62
Jul 15 10:00:00 - 10:59:59	817	0.0%	0.23	50.8 MB	0.1%	14.44
Jul 15 11:00:00 - 11:59:59	5929	0.2%	1.65	1.7 GB	4.0%	507.23
Jul 15 12:00:00 - 12:59:59	642	0.0%	0.18	2.2 MB	0.0%	0.61
Jul 15 13:00:00 - 13:59:59	828	0.0%	0.23	90.0 MB	0.2%	25.61
Jul 15 14:00:00 - 14:59:59	589	0.0%	0.16	3.1 MB	0.0%	0.88
Jul 15 15:00:00 - 15:59:59	538	0.0%	0.15	2.1 MB	0.0%	0.60
Jul 15 16:00:00 - 16:59:59	538	0.0%	0.15	9.4 MB	0.0%	2.68
Jul 15 17:00:00 - 17:59:59	400	0.0%	0.11	20.3 MB	0.0%	5.76
Jul 15 18:00:00 - 18:59:59	169326	4.8%	47.03	651.3 MB	1.5%	185.26
Jul 15 19:00:00 - 19:59:59	272285	7.7%	75.63	1003.8 MB	2.3%	285.52
Jul 15 20:00:00 - 20:59:59	271880	7.7%	75.52	946.8 MB	2.1%	269.30
Jul 15 21:00:00 - 21:59:59	261207	7.4%	72.56	1.1 GB	2.6%	330.49
Jul 15 22:00:00 - 22:59:59	264918	7.5%	73.59	1.0 GB	2.3%	294.44
Jul 15 23:00:00 - 23:59:59	268325	7.6%	74.53	1.0 GB	2.4%	299.22
Jul 16 00:00:00 - 00:59:59	242062	6.8%	67.24	999.7 MB	2.3%	284.36
Jul 16 01:00:00 - 01:59:59	233581	6.6%	64.88	914.3 MB	2.1%	260.06
Jul 16 02:00:00 - 02:59:59	123832	3.5%	34.40	3.5 GB	8.1%	1021.00
Jul 16 03:00:00 - 03:59:59	81818	2.3%	22.73	231.6 MB	0.5%	65.87
Jul 16 04:00:00 - 04:59:59	84377	2.4%	23.44	462.4 MB	1.0%	131.52
Jul 16 05:00:00 - 05:59:59	96595	2.7%	26.83	754.0 MB	1.7%	214.48
Jul 16 06:00:00 - 06:59:59	82018	2.3%	22.78	597.6 MB	1.4%	170.00
Jul 16 07:00:00 - 07:59:59	72364	2.0%	20.10	2.3 GB	5.3%	669.06
Jul 16 08:00:00 - 08:59:59	71734	2.0%	19.93	490.0 MB	1.1%	139.38
Jul 16 09:00:00 - 09:59:59	82755	2.3%	22.99	975.3 MB	2.2%	277.43
Jul 16 10:00:00 - 10:59:59	87973	2.5%	24.44	947.2 MB	2.1%	269.42
Jul 16 11:00:00 - 11:59:59	72934	2.1%	20.26	2.2 GB	5.2%	654.47
Jul 16 12:00:00 - 12:59:59	57700	1.6%	16.03	180.6 MB	0.4%	51.36
Jul 16 13:00:00 - 13:59:59	54220	1.5%	15.06	181.9 MB	0.4%	51.74
Jul 16 14:00:00 - 14:59:59	40178	1.1%	11.16	708.9 MB	1.6%	201.65
Jul 16 15:00:00 - 15:59:59	37449	1.1%	10.40	139.8 MB	0.3%	39.76
Jul 16 16:00:00 - 16:59:59	36929	1.0%	10.26	87.1 MB	0.2%	24.78
Jul 16 17:00:00 - 17:59:59	36920	1.0%	10.26	111.5 MB	0.3%	31.71
Jul 16 18:00:00 - 18:59:59	14580	0.4%	4.05	48.0 MB	0.1%	13.66
Jul 16 19:00:00 - 19:59:59	306	0.0%	0.09	953.3 KB	0.0%	0.26
Jul 16 20:00:00 - 20:59:59	351	0.0%	0.10	2.0 MB	0.0%	0.57
Jul 16 21:00:00 - 21:59:59	255	0.0%	0.07	794.7 KB	0.0%	0.22
Jul 16 22:00:00 - 22:59:59	237	0.0%	0.07	979.5 KB	0.0%	0.27
Jul 16 23:00:00 - 23:59:59	669	0.0%	0.19	105.0 MB	0.2%	29.85
Jul 17 00:00:00 - 00:59:59	928	0.0%	0.26	190.8 MB	0.4%	54.28
Jul 17 01:00:00 - 01:59:59	468	0.0%	0.13	40.1 MB	0.1%	11.39
Jul 17 02:00:00 - 02:59:59	422	0.0%	0.12	3.1 MB	0.0%	0.89
Jul 17 03:00:00 - 03:59:59	535	0.0%	0.15	1.4 MB	0.0%	0.39
Jul 17 04:00:00 - 04:59:59	644	0.0%	0.18	2.6 MB	0.0%	0.74
Jul 17 05:00:00 - 05:59:59	5412	0.2%	1.50	2.0 GB	4.6%	581.19
Jul 17 06:00:00 - 06:59:59	1029	0.0%	0.29	103.9 MB	0.2%	29.54
Jul 17 07:00:00 - 07:59:59	1913	0.1%	0.53	595.1 MB	1.3%	169.28
Jul 17 08:00:00 - 08:59:59	1384	0.0%	0.38	316.6 MB	0.7%	90.05
Jul 17 09:00:00 - 09:59:59	2600	0.1%	0.72	1.3 GB	3.0%	379.93
Jul 17 10:00:00 - 10:59:59	6345	0.2%	1.76	3.9 GB	9.1%	1149.82
Jul 17 11:00:00 - 11:59:59	592	0.0%	0.16	2.9 MB	0.0%	0.84
Jul 17 12:00:00 - 12:59:59	601	0.0%	0.17	2.0 MB	0.0%	0.57
Jul 17 13:00:00 - 13:59:59	925	0.0%	0.26	145.8 MB	0.3%	41.47
Jul 17 14:00:00 - 14:59:59	568	0.0%	0.16	2.2 MB	0.0%	0.64
Jul 17 15:00:00 - 15:59:59	604	0.0%	0.17	1.9 MB	0.0%	0.54
Jul 17 16:00:00 - 16:59:59	1168	0.0%	0.32	458.9 MB	1.0%	130.52
Jul 17 17:00:00 - 17:59:59	403	0.0%	0.11	1.5 MB	0.0%	0.43
Jul 17 18:00:00 - 18:59:59	411	0.0%	0.11	1.5 MB	0.0%	0.42
Jul 17 19:00:00 - 19:59:59	299	0.0%	0.08	985.0 KB	0.0%	0.27
Jul 17 20:00:00 - 20:59:59	269	0.0%	0.07	983.3 KB	0.0%	0.27
Jul 17 21:00:00 - 21:59:59	246	0.0%	0.07	3.2 MB	0.0%	0.92
Jul 17 22:00:00 - 22:59:59	196	0.0%	0.05	989.9 KB	0.0%	0.27
Jul 17 23:00:00 - 23:59:59	309	0.0%	0.09	975.1 KB	0.0%	0.27
Jul 18 00:00:00 - 00:59:59	327	0.0%	0.09	5.3 MB	0.0%	1.51
Jul 18 01:00:00 - 01:59:59	405	0.0%	0.11	3.4 MB	0.0%	0.96
Jul 18 02:00:00 - 02:59:59	1213	0.0%	0.34	194.8 MB	0.4%	55.42
Jul 18 03:00:00 - 03:59:59	955	0.0%	0.27	104.3 MB	0.2%	29.68
Jul 18 04:00:00 - 04:59:59	4665	0.1%	1.30	1.3 GB	3.0%	381.17
Jul 18 05:00:00 - 05:59:59	1070	0.0%	0.30	104.1 MB	0.2%	29.60
Jul 18 06:00:00 - 06:58:55	688	0.0%	0.19	27.3 MB	0.1%	7.92
TOTAL: 87:32:01	3537119	100.0%	11.22	43.2 GB	100.0%	143.84

Sites sending bad articles:

Server		Total	Group	Dist	Unapp	Other
1	feed1.usenet.blueworldhosting.com	466821	463409	0	3	3409
2	localhost	250225	58	27	172	249968
TOTAL: 2		717046	463467	27	175	253377

Unwanted newsgroups [Top 20]:

Newsgroup	Count
alt.binaries.ebook.french	454371
alt.binaries.cd.image.french	3451
alt.binaries.e-books.magazines	1780
talk.ukrainian.tg	905
al.binaries.test	694
alt.binaries.de	633
alt.binaries.mom	629
talk.english.tg	172
finet.binaries.keskustelu	75
alt.binaries.pictures.wallpaper	60
alt	56
alt.binaries.pictures.nude.celebrities.fake	54
alt.binaries.pictures.scenic	42
talk.generic.ua.ws	40
talk.english.tw	32
alt.binaries.e-books	31
alt.binaries.test.yenc	29
alt.binaries.comp	21
alt.binaries.pictures.teen-starlets	21
alt.binaries.multimedia.erotica.amateur	19
TOTAL: 119	463467

Unwanted distributions [Top 20]:

Distribution	Count
local	27
TOTAL: 1	27

Supposedly-moderated groups with unmoderated postings [Top 20]:

Groups	Count
perl.dbi.users	38
linux.debian.policy	21
fido7.krs.test	10
linux.test	10
fido7.kharkov.star.test	10
fido7.dn.test	10
posc.test	10
fido7.mikdim.test	10
fido7.f157.test	9
hannet.ml.test	9
sci.nanotech	4
comp.sys.ibm.pc.games.announce	4
rec.guns	3
gov.us.fed.congress.gao.discuss	3
soc.religion.hindu	3
bit.listserv.catholic	3
soc.culture.russian.moderated	2
perl.jobs	2
bionet.biology.deepsea	2
comp.lang.ml	2
TOTAL: 29	175

INND Python filter [Top 20]:

Reason	Count
Binary (yEnc)	117513
EMP Body Reject	66287
EMP FSL Reject	43877
Bad From (moaulanui@hotmail.co.nz)	12786
Bad From (MI5Victim@mi5.gov.uk)	2874
OS2 Crosspost	1325
Binary (uuEnc)	1238
HTML Misplaced	911
Crosspost Low Limit Exceeded	707
Bad From (FBInCIAnNSATerroristSlayer@yahoo.com)	651
Binary (base64)	561
Bad From (hclaudioblanco@gmail.com)	551
Bad From (fudotrick1@gmail.com)	288
Bad From (hester holt)	203
Bad Subject (Buy Xanax)	173
Bad Subject (Solutions manual and Test banks)	168
Bad Subject (Buy Oxycodon)	142
AUK Bad Crosspost	135
Bad From (huhai102@gmail.com)	130
OS2 Followup	120
TOTAL: 62	251686

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	13	0	13	13
TOTAL: 1	13	0	13	13

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	localhost	183
	TOTAL: 1	183
Including strange strings
	feed1.usenet.blueworldhosting.com	890
	localhost	287
	TOTAL: 2	1177
TOTAL: 2		1360

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	feed1.usenet.blueworldhosting.com	0	0	0	0	0	0	0%	00:20:01
TOTAL: 1		0	0	0	0	0	0	0%	00:20:01

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	feed1.usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:20:01
TOTAL: 1		0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:20:01

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	023-084-030-207.res.spectrum.com	581	339	105.4 MB	5	0	0	05:22:41
TOTAL: 1		581	339	105.4 MB	5	0	0	86:40:12

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.res.spectrum.com	581	339	105.4 MB	5	05:22:41
2	*.stehlik.co.uk	3376	0	0.0 KB	0	81:16:42
3	unresolved	35	0	0.0 KB	0	00:00:45
4	*.tor-exit.holonet.sh	3	0	0.0 KB	0	00:00:03
TOTAL: 4		3995	339	105.4 MB	5	86:40:12

NNRP auth users [Top 20]:

User	Conn
jesse	580
TOTAL: 1	580

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
toma-03.stehlik.co.uk	717.776	283.439	0.000	81:16:42
023-084-030-207.res.spectrum.com	50.345	21.309	6.874	05:22:41
185.220.101.186	0.072	0.016	0.000	00:00:14
50.31.21.10	0.227	0.016	0.000	00:00:13
185.220.101.145	0.059	0.025	0.000	00:00:07
r3-s6.tor-exit.holonet.sh	0.239	0.046	0.000	00:00:03
185.220.101.159	0.395	0.039	0.000	00:00:03
185.220.101.144	0.209	0.030	0.000	00:00:02
208.100.26.237	1.040	0.091	0.000	00:00:02
185.220.101.167	0.221	0.055	0.000	00:00:01
185.220.101.161	0.221	0.030	0.000	00:00:01
185.220.101.164	0.115	0.000	0.000	00:00:00
92.255.85.183	0.078	0.000	0.000	00:00:00
TOTAL: 13	770.997	305.096	6.874	86:40:12

Curious NNRP server explorers [Top 20]:

System	Conn
toma-03.stehlik.co.uk	3376
208.100.26.237	12
185.220.101.159	5
185.220.101.161	4
r3-s6.tor-exit.holonet.sh	3
185.220.101.144	3
185.220.101.167	3
50.31.21.10	3
185.220.101.164	2
92.255.85.183	1
185.220.101.145	1
185.220.101.186	1
TOTAL: 12	3414

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	23
TOTAL: 1	23

NNRP client timeouts [Top 20]:

System	Conn	Peer
023-084-030-207.res.spectrum.com	580	580
toma-03.stehlik.co.uk	4	4
TOTAL: 2	584	586

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	alt	7	100.0%
TOTAL: 1		7	100.0%

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

Newsgroup		Count
1	alt.binaries.e-book.magazines	7
TOTAL: 1		7

Daily Usenet report

Jul 14 15:26:54 -- Jul 18 06:58:55