Daily Usenet report: Sep 8 02:30:00

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1973363	91.2%	173.0 MB	89.6%
nnrpd	98247	4.5%	10.3 MB	5.3%
innd	92068	4.3%	9.8 MB	5.1%
innfeed	157	0.0%	15.7 KB	0.0%
TOTAL: 4	2163835	100.0%	193.1 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1956494	49.8%
Negative hits	1886152	48.0%
Cache misses	80558	2.0%
Positive hits	8648	0.2%
TOTAL: 4	3931852	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.523	0.0%	289	0.000	12.190	223.000
article cleanup	00:00:10.186	0.0%	1972094	0.000	0.005	0.034
article logging	00:00:22.975	0.0%	1978898	0.000	0.012	0.047
article parse	00:01:50.709	0.1%	12363009	0.000	0.009	0.027
article write	07:28:46.682	31.2%	1959577	3.369	13.741	24.275
artlog/artcncl	00:00:00.000	0.0%	10	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:11.993	0.0%	9322834	0.000	0.001	0.005
hisgrep/artcncl	00:00:01.795	0.0%	299	0.000	6.003	121.444
hishave/artcncl	00:00:00.000	0.0%	10	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:05:48.048	0.4%	3963320	0.055	0.088	0.345
history sync	00:00:02.011	0.0%	323	0.000	6.226	8.000
history write	00:32:20.253	2.2%	1972095	0.533	0.984	1.411
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	14:30:40.825	60.4%	10187466	0.289	5.128	3237.789
nntp read	00:03:32.025	0.2%	12454475	0.003	0.017	0.090
overview write	00:13:58.284	1.0%	1959577	0.139	0.428	2.483
perl filter	00:01:08.729	0.1%	1961028	0.009	0.035	0.138
python filter	00:55:55.588	3.9%	3959745	0.324	0.847	19.705
site send	00:00:51.693	0.1%	1959615	0.000	0.026	0.078
TOTAL: 24:00:40.427	23:55:45.319	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.003	0.0%	3723	0.000	0.001	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.001	0.0%	3	0.000	0.333	0.500
idle	23:51:34.525	100.0%	3723	17423.029	23071.320	25113.917
status file	00:00:00.000	0.0%	6	0.000	0.000	0.000
TOTAL: 23:51:34.574	23:51:34.529	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:13.994	0.0%	30233	0.000	0.463	76.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	00:23:47.391	0.9%	13439091	0.000	0.106	100.463
readart	00:07:30.850	0.3%	28901	0.000	15.600	816.000
TOTAL: 43:42:26.877	00:31:32.235	1.2%	-	-	-	-

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	19847	1983669	1880819	91202	11648	94%	47:21:33
2	feed1.usenet.blueworldhosting.com	13	74205	73264	0	941	98%	25:10:28
TOTAL: 2		19860	2057874	1954083	91202	12589	94%	72:32:01

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed1.usenet.blueworldhosting.com	33.4 GB	31.3 MB	33.4 GB	99%	472.1 KB
2	localhost	5.8 GB	70.6 MB	5.8 GB	98%	3.2 KB
TOTAL: 2		39.2 GB	101.9 MB	39.3 GB	99%	20.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 8 02:30:00 - 02:59:59	46849	2.4%	26.03	139.8 MB	0.3%	79.51
Sep 8 03:00:00 - 03:59:59	22943	1.2%	6.37	102.9 MB	0.3%	29.27
Sep 8 04:00:00 - 04:59:59	611	0.0%	0.17	2.3 MB	0.0%	0.65
Sep 8 05:00:00 - 05:59:59	949	0.0%	0.26	174.3 MB	0.4%	49.58
Sep 8 06:00:00 - 06:59:59	1847	0.1%	0.51	448.4 MB	1.1%	127.54
Sep 8 07:00:00 - 07:59:59	8427	0.4%	2.34	3.3 GB	8.4%	952.86
Sep 8 08:00:00 - 08:59:59	49385	2.5%	13.72	2.6 GB	6.7%	760.77
Sep 8 09:00:00 - 09:59:59	110935	5.7%	30.82	787.9 MB	2.0%	224.11
Sep 8 10:00:00 - 10:59:59	124016	6.3%	34.45	2.5 GB	6.4%	729.56
Sep 8 11:00:00 - 11:59:59	117931	6.0%	32.76	4.0 GB	10.3%	1173.42
Sep 8 12:00:00 - 12:59:59	103312	5.3%	28.70	5.2 GB	13.3%	1513.55
Sep 8 13:00:00 - 13:59:59	106838	5.5%	29.68	3.0 GB	7.6%	868.02
Sep 8 14:00:00 - 14:59:59	115966	5.9%	32.21	1.7 GB	4.3%	485.77
Sep 8 15:00:00 - 15:59:59	108632	5.6%	30.18	3.5 GB	8.9%	1011.80
Sep 8 16:00:00 - 16:59:59	112369	5.8%	31.21	2.4 GB	6.1%	693.08
Sep 8 17:00:00 - 17:59:59	117216	6.0%	32.56	950.1 MB	2.4%	270.25
Sep 8 18:00:00 - 18:59:59	115677	5.9%	32.13	391.7 MB	1.0%	111.41
Sep 8 19:00:00 - 19:59:59	121654	6.2%	33.79	450.5 MB	1.1%	128.16
Sep 8 20:00:00 - 20:59:59	118024	6.0%	32.78	392.3 MB	1.0%	111.58
Sep 8 21:00:00 - 21:59:59	107059	5.5%	29.74	426.4 MB	1.1%	121.30
Sep 8 22:00:00 - 22:59:59	90084	4.6%	25.02	2.4 GB	6.3%	713.01
Sep 8 23:00:00 - 23:59:59	82516	4.2%	22.92	1.7 GB	4.3%	486.69
Sep 9 00:00:00 - 00:59:59	70923	3.6%	19.70	220.2 MB	0.5%	62.64
Sep 9 01:00:00 - 01:59:59	64607	3.3%	17.95	207.9 MB	0.5%	59.14
Sep 9 02:00:00 - 02:30:00	35193	1.8%	19.55	2.3 GB	6.0%	1366.37
TOTAL: 24:00:00	1953963	100.0%	22.62	39.2 GB	100.0%	475.30

Sites sending bad articles:

Server		Total	Group	Dist	Unapp	Other
1	localhost	18104	1	1	370	17732
2	feed1.usenet.blueworldhosting.com	1286	918	0	1	367
TOTAL: 2		19390	919	1	371	18099

Unwanted newsgroups [Top 20]:

Newsgroup	Count
talk.ukrainian.tg	360
al.binaries.test	250
talk.english.tg	55
alt.binaries.e-books.magazines	43
finet.binaries.keskustelu	39
alt.binaries.e-books.flood	13
alt.binaries.nospam.female.bodyhair	12
alt.binaries.nospam.female.bodyhair.pubes	11
talk.generic.ua.ws	11
wolfsburg.test	9
de.org.politik.misc	9
alt.binaries.pictures.teen-starlets	9
alt.binaries.adan	7
alt.binaries.3d.poser	5
alt.binaries.mp3.audiobooks	5
alt.binaries.pictures.rail	5
alt.binaries.pictures.nude.celebrities.fake	5
alt.binaries.multimedia.erotica.amateur	4
news.oriens-occasus.ru	4
alt.binaries.sounds.radio.coasttocoast.am	4
TOTAL: 58	919

Unwanted distributions [Top 20]:

Distribution	Count
local	1
TOTAL: 1	1

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

Groups	Count
alt.music.sultans-of-ping	286
alt.alien.moderated	29
seattle.gay.news	14
soc.genealogy.surnames.canada	9
alt.bigfoot	5
alt.dads-rights	4
soc.genealogy.surnames.britain	3
soc.politics	3
chile.mercado.trabajos	2
edm.announce	2
soc.genealogy.surnames.ireland	2
it.cultura.storia.militare	2
italia.roma.discussioni	1
linux.debian.devel	1
soc.genealogy.surnames.misc	1
bionet.genome.chromosomes	1
bit.listserv.catholic	1
soc.personals	1
fido7.ru.russia	1
clari.usa.politics	1
TOTAL: 22	371

INND Python filter [Top 20]:

Reason	Count
EMP Body Reject	7259
Binary (uuEnc)	2573
Crosspost Low Limit Exceeded	625
AUK Bad Crosspost	380
Binary (base64)	136
Binary (yEnc)	73
Bad Subject (Solution Manual )	2
Bad Subject (Solution manual )	2
OS2 Followup	2
Bad From (FBInCIAnNSATerroristSlayer@yahoo.com)	1
Symbols (959)	1
Symbols (228)	1
Symbols (1281)	1
Symbols (280)	1
TOTAL: 14	11057

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	localhost	306
	TOTAL: 1	306
Including strange strings
	localhost	6456
	feed1.usenet.blueworldhosting.com	362
	TOTAL: 2	6818
No colon-space in header field
	localhost	1
	TOTAL: 1	1
TOTAL: 3		7125

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	594	6	16.2 KB	3	21964	556	16:50:42
2	69.166.206.175	6	0	0.0 KB	0	1284	0	00:50:51
3	69.166.206.188	12	0	0.0 KB	0	4462	0	02:37:24
4	209.160.114.61	6	0	0.0 KB	0	1284	0	00:48:58
TOTAL: 4		618	6	16.2 KB	3	28994	556	43:42:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	594	6	16.2 KB	3	21964	556	16:50:42
2	*.stehlik.co.uk	887	0	0.0 KB	0	0	0	22:34:20
3	unresolved	27	0	0.0 KB	0	7030	0	04:17:16
TOTAL: 3		1508	6	16.2 KB	3	28994	556	43:42:18

NNRP auth users [Top 20]:

User	Conn
jesse	618
TOTAL: 1	618

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
toma-03.stehlik.co.uk	183.856	63.555	0.000	22:34:20
023-084-030-207.res.spectrum.com	565.022	497.656	3.100	16:50:42
69.166.206.188	103.678	96.875	0.057	02:37:24
69.166.206.175	30.272	28.231	0.048	00:50:51
209.160.114.61	30.294	29.592	0.027	00:48:58
167.94.146.58	0.161	0.000	0.000	00:00:01
104.156.155.23	0.049	0.033	0.000	00:00:00
TOTAL: 7	913.332	715.942	3.232	43:42:18

Curious NNRP server explorers [Top 20]:

System	Conn
toma-03.stehlik.co.uk	887
167.94.146.58	2
104.156.155.23	1
TOTAL: 3	890

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host) [Top 20]:

System	Conn
167.94.146.58	1
TOTAL: 1	1

NNRP unrecognized commands (by command) [Top 20]:

Command	Count
EHLO www.censys.io	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
023-084-030-207.res.spectrum.com	492	492
167.94.146.58	1	2
TOTAL: 2	493	495

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	soc	3	60.0%
2	alt	2	40.0%
TOTAL: 2		5	100.0%

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

Newsgroup		Count
1	soc.culture.singapore	3
2	alt.binaries.ebooks	2
TOTAL: 2		5

Daily Usenet report

Sep 8 02:30:00 -- Sep 9 02:30:00