Daily Usenet report: Sep 7 02:30:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2851304	91.1%	247.3 MB	89.4%
innd	199945	6.4%	21.1 MB	7.6%
nnrpd	77549	2.5%	8.2 MB	3.0%
innfeed	157	0.0%	16.0 KB	0.0%
nocem	2	0.0%	0.2 KB	0.0%
TOTAL: 5	3128957	100.0%	276.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2827197	49.1%
Negative hits	2788537	48.5%
Cache misses	127001	2.2%
Positive hits	9731	0.2%
TOTAL: 4	5752466	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.897	0.0%	262	0.000	11.057	113.750
article cleanup	00:00:15.586	0.0%	2827312	0.001	0.006	0.024
article logging	00:00:31.997	0.0%	2835766	0.009	0.011	0.015
article parse	00:02:14.634	0.2%	14279158	0.002	0.009	0.027
article write	04:58:11.384	20.8%	2743560	0.677	6.521	13.700
artlog/artcncl	00:00:00.000	0.0%	17	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:00:15.178	0.0%	9660041	0.000	0.002	0.003
hisgrep/artcncl	00:00:00.323	0.0%	279	0.000	1.158	14.333
hishave/artcncl	00:00:00.000	0.0%	18	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:07:50.653	0.5%	5752865	0.070	0.082	0.097
history sync	00:00:01.570	0.0%	294	1.500	5.340	44.500
history write	00:37:19.125	2.6%	2827308	0.534	0.792	1.136
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	16:33:32.040	69.5%	12289314	0.581	4.851	14.858
nntp read	00:03:18.605	0.2%	14441228	0.004	0.014	0.023
overview write	00:13:49.873	1.0%	2743560	0.125	0.302	0.609
perl filter	00:01:25.320	0.1%	2746683	0.019	0.031	0.055
python filter	01:03:36.766	4.4%	5749194	0.255	0.664	2.525
site send	00:01:13.102	0.1%	2743605	0.019	0.027	0.091
TOTAL: 23:50:14.412	23:43:39.053	99.5%	-	-	-	-

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.001	0.0%	3731	0.000	0.000	0.038
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.000	0.0%	3	0.000	0.000	0.000
data write	00:00:00.000	0.0%	3	0.000	0.000	0.000
idle	23:51:46.421	100.0%	3731	17500.029	23025.039	23328.667
status file	00:00:00.001	0.0%	6	0.000	0.167	0.500
TOTAL: 23:51:46.465	23:51:46.423	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:08.050	0.0%	7719	0.000	1.043	235.500
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	00:28:22.151	1.5%	13312763	0.000	0.128	531.860
readart	00:01:12.781	0.1%	7719	0.000	9.429	256.000
TOTAL: 31:18:26.237	00:29:42.982	1.6%	-	-	-	-

Control commands to INND:

Command	Number
flush	5
flushlogs	2
go	1
logmode	2
mode	148
name	2
pause	1
paused	1
reload	2
renumber	1
TOTAL: 10	165

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	localhost	29388	2937416	2717725	136796	82895	92%	45:47:22
2	feed1.usenet.blueworldhosting.com	15	42172	41271	0	901	97%	25:22:49
TOTAL: 2		29403	2979588	2758996	136796	83796	92%	71:10:11

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed1.usenet.blueworldhosting.com	13.6 GB	7.0 MB	13.6 GB	99%	339.1 KB
2	localhost	9.2 GB	24.4 GB	33.5 GB	27%	12.6 KB
TOTAL: 2		22.8 GB	24.4 GB	47.2 GB	48%	17.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 7 02:30:01 - 02:59:59	67777	2.5%	37.67	248.9 MB	1.1%	141.68
Sep 7 03:00:00 - 03:59:59	128610	4.7%	35.73	578.3 MB	2.5%	164.49
Sep 7 04:00:00 - 04:59:59	109664	4.0%	30.46	456.4 MB	2.0%	129.81
Sep 7 05:00:00 - 05:59:59	107641	3.9%	29.90	425.0 MB	1.8%	120.89
Sep 7 06:00:00 - 06:59:59	105412	3.8%	29.28	440.2 MB	1.9%	125.20
Sep 7 07:00:00 - 07:59:59	106167	3.8%	29.49	578.5 MB	2.5%	164.55
Sep 7 08:00:00 - 08:59:59	108011	3.9%	30.00	1.3 GB	5.7%	379.04
Sep 7 09:00:00 - 09:59:59	125946	4.6%	34.98	559.4 MB	2.4%	159.11
Sep 7 10:00:00 - 10:59:59	131944	4.8%	36.65	558.1 MB	2.4%	158.74
Sep 7 11:00:00 - 11:59:59	135428	4.9%	37.62	2.9 GB	12.8%	852.26
Sep 7 12:00:00 - 12:59:59	128805	4.7%	35.78	4.2 GB	18.4%	1218.57
Sep 7 13:00:00 - 13:59:59	116724	4.2%	32.42	669.8 MB	2.9%	190.53
Sep 7 14:00:00 - 14:59:59	120136	4.4%	33.37	1.0 GB	4.5%	297.89
Sep 7 15:00:00 - 15:59:59	113683	4.1%	31.58	414.2 MB	1.8%	117.81
Sep 7 16:00:00 - 16:59:59	120542	4.4%	33.48	339.3 MB	1.5%	96.50
Sep 7 17:00:00 - 17:59:59	124803	4.5%	34.67	385.2 MB	1.7%	109.57
Sep 7 18:00:00 - 18:59:59	120269	4.4%	33.41	376.9 MB	1.6%	107.20
Sep 7 19:00:00 - 19:59:59	122358	4.4%	33.99	681.1 MB	2.9%	193.74
Sep 7 20:00:00 - 20:59:59	115491	4.2%	32.08	287.3 MB	1.2%	81.72
Sep 7 21:00:00 - 21:59:59	111403	4.0%	30.95	310.6 MB	1.3%	88.34
Sep 7 22:00:00 - 22:59:59	112029	4.1%	31.12	1.2 GB	5.3%	351.59
Sep 7 23:00:00 - 23:59:59	104733	3.8%	29.09	4.4 GB	19.2%	1271.21
Sep 8 00:00:00 - 00:59:59	88772	3.2%	24.66	244.9 MB	1.0%	69.65
Sep 8 01:00:00 - 01:59:59	87015	3.2%	24.17	247.9 MB	1.1%	70.53
Sep 8 02:00:00 - 02:30:00	45665	1.7%	25.37	155.0 MB	0.7%	88.18
TOTAL: 23:59:59	2759028	100.0%	31.93	22.8 GB	100.0%	276.41

Sites sending bad articles:

Server		Total	Group	Dist	Unapp	Other
1	localhost	90923	0	19	127	90777
2	feed1.usenet.blueworldhosting.com	1336	879	0	3	454
TOTAL: 2		92259	879	19	130	91231

Unwanted newsgroups [Top 20]:

Newsgroup	Count
talk.ukrainian.tg	300
al.binaries.test	252
talk.english.tg	56
talk.english.tw	21
alt.binaries.ebooks.flood	20
finet.binaries.keskustelu	20
alt.binaries.nospam.female.bodyhair	17
talk.generic.ua.ws	15
alt.binaries.test.yenc	12
alt.binaries.pictures.nude.celebrities.fake	12
alt.binaries.nospam.female.bodyhair.pubes	11
alt.binaries.mp3.audiobooks	11
alt.binaries.comp	9
alt.binaries.sounds.lossless	8
alt.binaries.pictures.wallpaper	7
news.oriens-occasus.ru	6
alt.binaries.drwho	6
alt.binaries.pictures.earlmiller	6
alt.binaries.alt5	5
alt.binaries.pictures.rail	4
TOTAL: 73	879

Unwanted distributions [Top 20]:

Distribution	Count
local	19
TOTAL: 1	19

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

Groups	Count
soc.history.war.world-war-ii	30
soc.genealogy.jewish	17
alt.alien.moderated	17
seattle.gay.news	13
it.cultura.storia.militare	8
soc.genealogy.african	7
fr.misc.handicap	5
italia.roma.discussioni	4
edm.announce	3
soc.genealogy.methods	3
clari.world.mideast.palestine	2
soc.feminism	2
soc.history.war.us-civil-war	2
soc.genealogy.surnames.german	2
alt.penthouse.sex.spanking	2
soc.genealogy.surnames.canada	2
soc.culture.russian.moderated	1
gov.us.fed.congress.record.senate	1
linux.gentoo.user	1
soc.genealogy.surnames.ireland	1
TOTAL: 27	130

INND Python filter [Top 20]:

Reason	Count
Binary (yEnc)	58087
EMP Body Reject	17909
Binary (uuEnc)	3643
Binary (base64)	511
Bad From (MI5Victim@mi5.gov.uk)	371
Crosspost Low Limit Exceeded	37
Bad Subject (Solution Manual )	12
Bad Subject (Solution manual )	7
AUK Bad Crosspost	5
Symbols (756)	2
Symbols (143)	2
Bad From (FBInCIAnNSATerroristSlayer@yahoo.com)	2
Symbols (234)	2
Symbols (173)	2
Symbols (184)	1
Bad From (giannicipolla@protonmail.com)	1
Symbols (102)	1
Symbols (105)	1
Symbols (101)	1
Symbols (126)	1
TOTAL: 36	80614

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	localhost	601
	TOTAL: 1	601
Including strange strings
	localhost	8028
	feed1.usenet.blueworldhosting.com	432
	TOTAL: 2	8460
No colon-space in header field
	localhost	1
	TOTAL: 1	1
TOTAL: 3		9062

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:10:00
TOTAL: 1		0	0	0	0	0	0	0%	00:10:00

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:10:00
TOTAL: 1		0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	00:10:00

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	023-084-030-207.res.spectrum.com	565	24	107.4 KB	2	7317	0	08:39:43
TOTAL: 1		565	24	107.4 KB	2	7317	0	31:18:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.res.spectrum.com	565	24	107.4 KB	2	7317	08:39:43
2	*.tor.uwaterloo.ca	1	0	0.0 KB	0	0	00:00:03
3	*.biz.spectrum.com	15	0	0.0 KB	0	0	00:08:25
4	*.stehlik.co.uk	887	0	0.0 KB	0	0	22:29:15
5	unresolved	8	0	0.0 KB	0	0	00:00:27
6	*.stormycloud.org	2	0	0.0 KB	0	0	00:00:23
TOTAL: 6		1478	24	107.4 KB	2	7317	31:18:18

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	180.828	62.755	0.000	22:29:15
023-084-030-207.res.spectrum.com	215.991	159.277	2.806	08:39:43
071-014-221-118.biz.spectrum.com	1.245	0.305	0.096	00:08:25
tor-exit07.stormycloud.org	0.144	0.016	0.000	00:00:23
185.220.101.167	0.051	0.030	0.000	00:00:18
185.244.192.175	0.074	0.008	0.000	00:00:06
exit.tor.uwaterloo.ca	0.065	0.016	0.000	00:00:03
185.220.101.159	0.233	0.008	0.000	00:00:01
185.220.101.164	0.062	0.016	0.000	00:00:00
TOTAL: 9	398.693	222.431	2.902	31:18:18

Curious NNRP server explorers [Top 20]:

System	Conn
toma-03.stehlik.co.uk	887
071-014-221-118.biz.spectrum.com	15
185.220.101.159	4
tor-exit07.stormycloud.org	2
exit.tor.uwaterloo.ca	1
185.220.101.179	1
185.220.101.167	1
185.220.101.164	1
185.244.192.175	1
TOTAL: 9	913

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
023-084-030-207.res.spectrum.com	545	545
071-014-221-118.biz.spectrum.com	15	15
TOTAL: 2	560	561

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	soc	5	62.5%
2	alt	3	37.5%
TOTAL: 2		8	100.0%

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

Newsgroup		Count
1	soc.culture.singapore	5
2	alt.security.pgp	3
TOTAL: 2		8

Daily Usenet report

Sep 7 02:30:01 -- Sep 8 02:30:00