Daily Usenet report: Oct 11 02:30:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Oct 12 02:01:59 spool1 rnews[65101]: usage error

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	139186	56.8%	13.9 MB	57.2%
nnrpd	81798	33.4%	7.6 MB	31.3%
innd	23806	9.7%	2.8 MB	11.4%
innfeed	158	0.1%	14.7 KB	0.1%
nocem	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
rnews	1	0.0%	0.1 KB	0.0%
TOTAL: 7	244951	100.0%	24.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	130326	65.1%
Negative hits	69725	34.9%
Positive hits	4	0.0%
Cache misses	3	0.0%
TOTAL: 4	200058	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.368	0.0%	79	0.000	4.658	27.000
article cleanup	00:00:00.874	0.0%	130375	0.000	0.007	0.056
article logging	00:00:01.759	0.0%	130839	0.000	0.013	0.077
article parse	00:01:02.696	0.1%	5467069	0.000	0.011	0.033
article write	00:50:57.777	3.5%	112280	4.558	27.233	42.579
artlog/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:00:08.142	0.0%	5811816	0.000	0.001	0.008
hisgrep/artcncl	00:00:00.116	0.0%	85	0.000	1.365	12.000
hishave/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:29.335	0.0%	200102	0.089	0.147	0.779
history sync	00:00:02.673	0.0%	329	0.000	8.125	10.500
history write	00:02:43.524	0.2%	130375	0.667	1.254	1.439
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	22:37:27.605	94.2%	5374245	1.114	15.155	10007.850
nntp read	00:02:18.664	0.2%	5458128	0.000	0.025	0.074
overview write	00:01:08.109	0.1%	112280	0.148	0.607	4.772
perl filter	00:00:10.058	0.0%	112573	0.000	0.089	0.198
python filter	00:23:25.850	1.6%	198897	0.640	7.068	33.100
site send	00:00:03.604	0.0%	112354	0.000	0.032	0.103
TOTAL: 24:00:42.259	24:00:01.154	100.0%	-	-	-	-

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.002	0.0%	3722	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.000	0.0%	3	0.000	0.000	0.000
idle	23:56:02.608	100.0%	3722	17929.500	23149.545	25135.667
status file	00:00:00.001	0.0%	6	0.000	0.167	0.500
TOTAL: 23:56:02.664	23:56:02.611	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:10.643	0.0%	21913	0.000	0.486	4.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:30:22.329	1.2%	13183316	0.000	0.138	149.500
readart	00:03:36.768	0.1%	20579	0.000	10.533	23.000
TOTAL: 42:37:25.592	00:34:09.740	1.3%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	feed1.usenet.blueworldhosting.com	8	62380	62007	0	373	99%	24:21:42
2	localhost	763	76120	56594	1	19525	74%	02:04:50
TOTAL: 2		771	138500	118601	1	19898	85%	26:26:32

Incoming Volume (INN):

Server		AcceptVol	RejVol	TotalVol	%Acc	Vol/Art
1	feed1.usenet.blueworldhosting.com	26.3 GB	27.3 MB	26.3 GB	99%	442.0 KB
2	localhost	187.4 MB	1.3 GB	1.5 GB	12%	20.2 KB
TOTAL: 2		26.5 GB	1.3 GB	27.8 GB	95%	210.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 11 02:30:00 - 02:59:59	2895	2.4%	1.61	1.1 GB	4.3%	659.88
Oct 11 03:00:00 - 03:59:59	9039	7.6%	2.51	3.6 GB	13.7%	1062.01
Oct 11 04:00:00 - 04:59:59	572	0.5%	0.16	12.0 MB	0.0%	3.41
Oct 11 05:00:00 - 05:59:59	552	0.5%	0.15	3.4 MB	0.0%	0.98
Oct 11 06:00:00 - 06:59:59	610	0.5%	0.17	1.9 MB	0.0%	0.55
Oct 11 07:00:00 - 07:59:59	635	0.5%	0.18	2.1 MB	0.0%	0.58
Oct 11 08:00:00 - 08:59:59	7140	6.0%	1.98	3.2 GB	12.2%	945.26
Oct 11 09:00:00 - 09:59:59	5589	4.7%	1.55	3.0 GB	11.2%	866.80
Oct 11 10:00:00 - 10:59:59	767	0.6%	0.21	2.0 MB	0.0%	0.58
Oct 11 11:00:00 - 11:59:59	703	0.6%	0.20	4.3 MB	0.0%	1.22
Oct 11 12:00:00 - 12:59:59	5017	4.2%	1.39	2.0 GB	7.4%	572.04
Oct 11 13:00:00 - 13:59:59	8103	6.8%	2.25	3.2 GB	11.9%	921.32
Oct 11 14:00:00 - 14:59:59	648	0.5%	0.18	10.5 MB	0.0%	3.00
Oct 11 15:00:00 - 15:59:59	976	0.8%	0.27	164.6 MB	0.6%	46.81
Oct 11 16:00:00 - 16:59:59	565	0.5%	0.16	2.2 MB	0.0%	0.63
Oct 11 17:00:00 - 17:59:59	498	0.4%	0.14	3.0 MB	0.0%	0.86
Oct 11 18:00:00 - 18:59:59	345	0.3%	0.10	1.5 MB	0.0%	0.43
Oct 11 19:00:00 - 19:59:59	311	0.3%	0.09	1.1 MB	0.0%	0.32
Oct 11 20:00:00 - 20:59:59	263	0.2%	0.07	1.0 MB	0.0%	0.30
Oct 11 21:00:00 - 21:59:59	235	0.2%	0.07	922.5 KB	0.0%	0.26
Oct 11 22:00:00 - 22:59:59	244	0.2%	0.07	1.3 MB	0.0%	0.38
Oct 11 23:00:00 - 23:59:59	353	0.3%	0.10	1.5 MB	0.0%	0.43
Oct 12 00:00:00 - 00:59:59	4900	4.1%	1.36	3.0 GB	11.5%	886.94
Oct 12 01:00:00 - 01:59:59	33236	28.0%	9.23	5.4 GB	20.1%	1559.29
Oct 12 02:00:00 - 02:30:00	34597	29.1%	19.22	1.8 GB	6.9%	1068.76
TOTAL: 24:00:00	118793	100.0%	1.37	26.6 GB	100.0%	322.45

Sites sending bad articles:

Server		Total	Group	Unapp	Other
1	localhost	19702	2	15	19685
2	feed1.usenet.blueworldhosting.com	685	277	0	408
TOTAL: 2		20387	279	15	20093

Unwanted newsgroups [Top 20]:

Newsgroup	Count
finet.binaries.keskustelu	51
wolfsburg.test	43
alt.binaries.e-books.magazines	38
alt.binaries.pictures.teen-starlets	14
alt.binaries.multimedia.teen.male	11
alt.binaries.test.yenc	10
alt.binaries.pictures.scenic	9
alt.binaries.e-books.flood	9
alt.binaries.pictures.nude.celebrities.fake	8
alt.binaries.3d.poser	8
alt.binaries.sacd.iso	7
alt.binaries.kindle	6
alt.binaries.pictures.wallpaper	5
alt.binaries.nospam.female.bodyhair	4
alt.binaries.sounds.mp3.christmas	3
alt.binaries.sounds.mp3.1980s	3
alt.binaries.sounds.karaoke	3
alt.binaries.mp3.audiobooks	3
alt.binaries.multimedia.videos.music	2
alt.binaries.boneless.nl	2
TOTAL: 51	279

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

Groups	Count
misc.activism.progressive	8
misc.business.consulting	2
misc.entrepreneurs.moderated	2
misc.business.moderated	1
biz.books.technical	1
misc.business.marketing.moderated	1
TOTAL: 6	15

INND Python filter [Top 20]:

Reason	Count
EMP Body Reject	14655
Binary (yEnc)	4733
Crosspost Low Limit Exceeded	105
Binary (uuEnc)	44
Binary (base64)	38
Bad Subject (Solution manual )	8
Symbols (695)	6
Bad Subject (Solution Manual )	6
Bad From (giressfrank1@gmail.com)	6
Bad From (FBInCIAnNSATerroristSlayer@yahoo.com)	2
TOTAL: 10	19603

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	localhost	9
	TOTAL: 1	9
Including strange strings
	feed1.usenet.blueworldhosting.com	312
	localhost	177
	TOTAL: 2	489
TOTAL: 2		498

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	144	2	3.9 KB	2	0	0	01:21:00
2	209.160.113.17	14	0	0.0 KB	0	6914	0	04:32:06
3	208.91.71.216	49	0	0.0 KB	0	13673	16	10:17:32
TOTAL: 3		207	2	3.9 KB	2	20587	16	42:37:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.res.spectrum.com	144	2	3.9 KB	2	0	0	01:21:00
2	unresolved	80	0	0.0 KB	0	20587	16	14:52:37
3	*.stehlik.co.uk	922	0	0.0 KB	0	0	0	26:23:42
TOTAL: 3		1146	2	3.9 KB	2	20587	16	42:37:19

NNRP auth users [Top 20]:

User	Conn
jesse	208
TOTAL: 1	208

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
toma-03.stehlik.co.uk	178.491	60.218	0.000	26:23:42
208.91.71.216	334.867	302.085	5.835	10:17:32
209.160.113.17	166.924	145.292	0.084	04:32:06
023-084-030-207.res.spectrum.com	10.912	2.554	0.648	01:21:00
128.90.117.144	0.580	0.280	0.023	00:02:55
208.100.26.235	0.838	0.081	0.000	00:00:02
TOTAL: 6	692.612	510.510	6.590	42:37:19

Curious NNRP server explorers [Top 20]:

System	Conn
toma-03.stehlik.co.uk	922
208.100.26.235	12
128.90.117.144	5
TOTAL: 3	939

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

System	Conn	Peer
023-084-030-207.res.spectrum.com	144	144
128.90.117.144	5	5
toma-03.stehlik.co.uk	2	0
TOTAL: 3	151	149

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	comp	1	50.0%
2	alt	1	50.0%
TOTAL: 2		2	100.0%

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

Newsgroup		Count
1	alt.free.newsservers	1
2	comp.security.unix	1
TOTAL: 2		2

Daily Usenet report

Oct 11 02:30:00 -- Oct 12 02:30:00