Daily Usenet report: May 13 03:00:02

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

May 13 03:16:48 news innd: message repeated 2 times: [ ctlinnd command s]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	451031	76.9%	70.9 MB	83.6%
innd	130030	22.2%	13.4 MB	15.8%
innfeed	2615	0.4%	280.3 KB	0.3%
nnrpd	2462	0.4%	224.2 KB	0.3%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 5	586139	100.0%	84.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	431323	45.8%
Negative hits	295415	31.4%
Cache misses	215387	22.9%
Positive hits	55	0.0%
TOTAL: 4	942180	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.027	0.0%	89	0.000	0.303	13.000
article cleanup	00:00:00.739	0.0%	451251	0.000	0.002	0.150
article logging	00:00:03.961	0.0%	451269	0.000	0.009	0.185
article parse	00:00:02.711	0.0%	1023996	0.000	0.003	0.012
article write	00:01:03.976	0.1%	310625	0.000	0.206	8.458
data move	00:00:00.242	0.0%	1299397	0.000	0.000	0.005
hisgrep/artcncl	00:00:00.003	0.0%	89	0.000	0.034	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:02:26.597	0.2%	942467	0.000	0.156	5.574
history sync	00:00:00.003	0.0%	291	0.000	0.010	1.000
history write	00:04:01.892	0.3%	430988	0.000	0.561	7.910
idle	23:44:05.555	98.9%	1250765	17.429	68.315	194.634
nntp read	00:00:06.065	0.0%	1250809	0.000	0.005	0.220
overview write	00:00:36.736	0.0%	310625	0.000	0.118	6.087
perl filter	00:07:03.961	0.5%	430988	0.290	0.984	3.382
python filter	00:00:06.957	0.0%	698255	0.000	0.010	0.424
site send	00:00:01.378	0.0%	310625	0.000	0.004	0.055
TOTAL: 24:00:13.671	23:59:40.803	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.254	0.0%	310138	0.000	0.001	0.027
article prepare	00:00:00.029	0.0%	574752	0.000	0.000	0.003
article read	00:00:16.663	0.0%	143688	0.000	0.116	1.500
backlog stats	00:00:00.126	0.0%	867671	0.000	0.000	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:03.956	0.0%	586391	0.000	0.007	0.041
data write	00:00:04.118	0.0%	276367	0.000	0.015	0.071
idle	23:58:32.544	100.0%	867671	33.504	99.476	369.117
status file	00:00:00.333	0.0%	240	0.000	1.387	23.000
TOTAL: 23:59:05.242	23:58:58.023	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	33:39:23.386	99.4%	438969	0.000	276.018	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:07.679	0.0%	1214434	0.000	0.006	1.000
readart	00:02:13.005	0.1%	108477	1.226	1.226	16.000
TOTAL: 33:51:36.416	33:41:44.070	99.5%	-	-	-	-

Control commands to INND:

Command	Number
flush	2
flushlogs	2
go	2
logmode	2
lowmark	1
mode	146
name	2
newgroup	1
pause	2
paused	2
reload	2
reserve	2
TOTAL: 12	166

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	news.twilightnode.org	164	378808	166370	192021	20417	43%	12:08:18
2	chimia.se	2	267219	143864	3177	120178	53%	23:51:46
3	localhost	25	0	0	0	0	0%	00:00:00
TOTAL: 3		191	646027	310234	195198	140595	48%	36:00:04

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	news.twilightnode.org	610.8 MB	72.4 MB	420.4 KB	683.6 MB	89%	3.7 KB
2	chimia.se	300.2 MB	6.4 KB	241.4 MB	541.6 MB	55%	2.1 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		911.0 MB	72.4 MB	241.8 MB	1.2 GB	74%	2.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 13 03:00:02 - 03:59:59	44077	14.2%	12.25	139.6 MB	15.3%	39.74
May 13 04:00:00 - 04:59:59	32480	10.5%	9.02	76.5 MB	8.4%	21.77
May 13 05:00:00 - 05:59:59	30653	9.9%	8.51	86.5 MB	9.5%	24.61
May 13 06:00:00 - 06:59:59	12341	4.0%	3.43	37.6 MB	4.1%	10.71
May 13 07:00:00 - 07:59:59	12596	4.1%	3.50	60.1 MB	6.6%	17.08
May 13 08:00:00 - 08:59:59	8044	2.6%	2.23	14.6 MB	1.6%	4.15
May 13 09:00:00 - 09:59:59	5671	1.8%	1.58	10.7 MB	1.2%	3.04
May 13 10:00:00 - 10:59:59	4897	1.6%	1.36	9.6 MB	1.0%	2.72
May 13 11:00:00 - 11:59:59	5297	1.7%	1.47	11.0 MB	1.2%	3.14
May 13 12:00:00 - 12:59:59	5796	1.9%	1.61	12.6 MB	1.4%	3.59
May 13 13:00:00 - 13:59:59	6659	2.1%	1.85	15.3 MB	1.7%	4.35
May 13 14:00:00 - 14:59:59	8222	2.6%	2.28	23.9 MB	2.6%	6.78
May 13 15:00:00 - 15:59:59	6561	2.1%	1.82	11.9 MB	1.3%	3.38
May 13 16:00:00 - 16:59:59	5756	1.9%	1.60	10.7 MB	1.2%	3.06
May 13 17:00:00 - 17:59:59	10637	3.4%	2.95	29.4 MB	3.2%	8.35
May 13 18:00:00 - 18:59:59	11234	3.6%	3.12	29.7 MB	3.3%	8.44
May 13 19:00:00 - 19:59:59	4534	1.5%	1.26	8.9 MB	1.0%	2.53
May 13 20:00:00 - 20:59:59	4766	1.5%	1.32	9.8 MB	1.1%	2.79
May 13 21:00:00 - 21:59:59	4307	1.4%	1.20	9.1 MB	1.0%	2.59
May 13 22:00:00 - 22:59:59	5212	1.7%	1.45	11.7 MB	1.3%	3.32
May 13 23:00:00 - 23:59:59	5266	1.7%	1.46	11.4 MB	1.3%	3.24
May 14 00:00:00 - 00:59:59	18452	5.9%	5.13	83.7 MB	9.2%	23.81
May 14 01:00:00 - 01:59:59	25705	8.3%	7.14	104.6 MB	11.5%	29.74
May 14 02:00:00 - 02:59:59	31243	10.1%	8.68	92.8 MB	10.2%	26.41
May 14 03:00:00 - 03:00:01	10	0.0%	10.00	66.5 KB	0.0%	66.52
TOTAL: 23:59:59	310416	100.0%	3.59	911.8 MB	100.0%	10.81

Sites sending bad articles:

Server		Total	Duplic	Other
1	chimia.se	120191	2	120189
2	news.twilightnode.org	20424	20243	181
TOTAL: 2		140615	20245	120370

INND Perl filter [Top 20]:

Reason	Count
EMP (phn nph)	52203
EMP (phn path)	44801
EMP (phl)	22485
EMP (md5)	419
Subject Rejected	144
HTML Multipart	142
Too many newsgroups (low_xpost)	59
MI5 Filter	48
Scoring filter	30
Binary Image: misplaced gif	13
Binary: misplaced binary	4
HTML post	2
Binary Image: misplaced jpg	1
EMP (fsl)	1
TOTAL: 14	120352

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Bad Message-ID's offered
	chimia.se	5
	TOTAL: 1	5
Including strange strings
	chimia.se	11
	news.twilightnode.org	7
	TOTAL: 2	18
TOTAL: 2		23

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	twilightnode.org	143867	11655	89	132121	0	8%	23:59:57
2	sklaff.dev	0	0	0	0	310415	0%	23:57:43
3	sklaffkom.se	0	0	0	0	310412	0%	23:59:57
4	thebunker.nu	0	0	0	0	310415	0%	23:57:43
5	usenet.blueworldhosting.com	0	0	0	0	294236	0%	23:59:57
TOTAL: 5		143867	11655	89	132121	1225478	8%	119:55:17

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	twilightnode.org	27.4 MB	273.1 MB	300.4 MB	3.6 KB/s	2.1 KB	23:59:57
2	sklaff.dev	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:43
3	sklaffkom.se	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
4	thebunker.nu	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:43
5	usenet.blueworldhosting.com	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:59:57
TOTAL: 5		27.4 MB	273.1 MB	300.4 MB	0.7 KB/s	2.1 KB	119:55:17

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Elapsed
1	66.61.98.95	2	108476	374.3 MB	429	33:50:32
2	195-252-55-9-no600.tbcn.telia.com	2	1	3.7 KB	1	00:00:40
TOTAL: 2		4	108477	374.3 MB	430	33:51:36

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	unresolved	15	108476	374.3 MB	429	33:50:35
2	*.tbcn.telia.com	2	1	3.7 KB	1	00:00:40
3	*.172.132.66.censys-scanner.com	3	0	0.0 KB	0	00:00:04
4	*.ip.linodeusercontent.com	3	0	0.0 KB	0	00:00:01
5	*.probe.onyphe.net	2	0	0.0 KB	0	00:00:03
6	*.stretchoid.com	2	0	0.0 KB	0	00:00:10
7	*.195.132.66.censys-scanner.com	1	0	0.0 KB	0	00:00:00
TOTAL: 7		28	108477	374.3 MB	430	33:51:36

NNRP auth users [Top 20]:

User	Conn
testperson456	1
TOTAL: 1	1

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
66.61.98.95	13.254	30.441	0.000	33:50:32
195-252-55-9-no600.tbcn.telia.com	0.114	0.222	0.046	00:00:40
azpdssibk9eo.stretchoid.com	0.016	0.011	0.000	00:00:10
140.172.132.66.censys-scanner.com	0.049	0.019	0.000	00:00:04
roth.probe.onyphe.net	0.008	0.006	0.000	00:00:02
193.124.20.244	0.010	0.003	0.000	00:00:01
69-164-217-245.ip.linodeusercontent.com	0.007	0.007	0.000	00:00:00
78.195.132.66.censys-scanner.com	0.039	0.006	0.000	00:00:00
blackburn.probe.onyphe.net	0.006	0.007	0.000	00:00:00
71.6.134.231	0.007	0.005	0.000	00:00:00
212.102.40.218	0.151	0.021	0.000	00:00:00
71.6.238.238	0.050	0.005	0.000	00:00:00
5.226.140.45	0.005	0.007	0.000	00:00:00
205.210.31.48	0.006	0.006	0.000	00:00:00
69.5.169.188	0.006	0.006	0.000	00:00:00
45-33-14-197.ip.linodeusercontent.com	0.039	0.003	0.000	00:00:00
217.146.80.115	0.007	0.007	0.000	00:00:00
45-56-79-53.ip.linodeusercontent.com	0.039	0.004	0.000	00:00:00
69.5.169.19	0.005	0.009	0.000	00:00:00
TOTAL: 19	13.818	30.795	0.046	33:51:36

Curious NNRP server explorers [Top 20]:

System	Conn
212.102.40.218	4
140.172.132.66.censys-scanner.com	3
71.6.238.238	2
azpdssibk9eo.stretchoid.com	2
193.124.20.244	1
205.210.31.48	1
217.146.80.115	1
45-33-14-197.ip.linodeusercontent.com	1
45-56-79-53.ip.linodeusercontent.com	1
5.226.140.45	1
69-164-217-245.ip.linodeusercontent.com	1
69.5.169.188	1
69.5.169.19	1
71.6.134.231	1
78.195.132.66.censys-scanner.com	1
blackburn.probe.onyphe.net	1
roth.probe.onyphe.net	1
TOTAL: 17	24

NNRP gethostbyaddr failures [Top 20]:

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

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

System	Conn
blackburn.probe.onyphe.net	2
140.172.132.66.censys-scanner.com	1
78.195.132.66.censys-scanner.com	1
azpdssibk9eo.stretchoid.com	1
TOTAL: 4	5

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

Command	Count
EHLO www.censys.io	2
#026#003#003#002d#001	1
MGLNDD_109.238.130.155_119	1
À$ÀÀ¯À,ÀrÀsÌ©#023#002#023#001Ì#024À#007À#022À#023À'À/À#024À(À0À`Àa...	1
TOTAL: 4	5

NNRP client timeouts [Top 20]:

System	Conn	Peer
140.172.132.66.censys-scanner.com	1	2
193.124.20.244	1	1
5.226.140.45	1	1
69.5.169.188	1	1
TOTAL: 4	4	9

Newsgroup request counts (by hierarchy):

Hierarchy		Count	Pct
1	comp	10936	41.3%
2	alt	6384	24.1%
3	rec	4230	16.0%
4	sci	4114	15.5%
5	soc	361	1.4%
6	misc	348	1.3%
7	news	90	0.3%
8	fr	12	0.0%
9	swnet	10	0.0%
10	humanities	8	0.0%
11	uk	2	0.0%
TOTAL: 11		26495	100.0%

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

Newsgroup		Count
1	sci.bio.herp	2604
2	comp.misc	2384
3	alt.activism	1734
4	comp.infosystems.www.browsers.mac	1382
5	rec.radio.info	924
6	sci.bio.fisheries	890
7	comp.sys.ibm.pc.hardware.chips	808
8	rec.autos.simulators	798
9	comp.sys.ibm.pc.hardware.video	792
10	comp.software.year-2000	754
11	alt.games.programming	746
12	comp.mail.eudora.mac	542
13	alt.alien.visitors	508
14	comp.dcom.cell-relay	472
15	rec.antiques.radio+phono	354
16	comp.sys.ibm.pc.games.flight-sim	324
17	comp.databases.visual-dbase	316
18	rec.outdoors.fishing.fly	298
19	alt.usenet.news-server-comparison	288
20	rec.pets.herp	286
21	misc.handicap	262
22	comp.theory	252
23	comp.sys.mentor	230
24	soc.history.science	230
25	comp.os.msdos.programmer	228
26	alt.games.quake3	224
27	sci.electronics.basics	212
28	comp.lang.beta	194
29	sci.fractals	194
30	rec.music.theory	183
31	alt.history.what-if	178
32	comp.sys.ibm.pc.games.space-sim	158
33	rec.skydiving	158
34	alt.comp.periphs.scanner	156
35	comp.graphics.api.opengl	152
36	comp.infosystems.www.servers.unix	146
37	alt.drugs.usenet	142
38	alt.current-events.clinton.whitewater	140
39	comp.security.misc	126
40	comp.music.midi	123
41	comp.ai	114
42	alt.parenting.solutions	108
43	comp.infosystems.www.authoring.stylesheets	108
44	comp.lang.postscript	104
45	alt.non.sequitur	92
46	comp.sys.hp.apps	88
47	comp.dcom.xdsl	86
48	news.admin.hierarchies	86
49	rec.sport.baseball.college	74
50	sci.engr.television.broadcast	74
51	alt.graphics.photoshop	72
52	rec.music.makers	66
53	comp.soft-sys.powerbuilder	64
54	comp.sys.ibm.pc.games.announce	64
55	rec.travel.asia	64
56	comp.protocols.misc	62
57	rec.games.chess.play-by-email	62
58	rec.games.roguelike.misc	62
59	alt.teens	60
60	rec.sport.skating.inline	60
61	alt.fan.jiro-nakamura	58
62	alt.alien.research	56
63	rec.music.classical.contemporary	56
64	rec.music.newage	54
65	alt.lycra	50
66	comp.os.ms-windows.programmer.graphics	50
67	comp.os.netware.connectivity	50
68	alt.motorcycle.sportbike	48
69	alt.microcontrollers.8bit	46
70	alt.rec.camping	46
71	comp.archives.admin	46
72	alt.chinese	44
73	comp.databases.ms-sqlserver	44
74	comp.sys.mac.hardware.misc	44
75	alt.memetics	42
76	alt.music.marillion	42
77	alt.support.tinnitus	42
78	rec.photo.marketplace	42
79	alt.aol-sucks	40
80	alt.music.progressive	40
81	comp.mail.mime	40
82	rec.arts.comics.misc	40
83	sci.engr.control	40
84	comp.human-factors	38
85	alt.games.unreal	36
86	alt.stop.spamming	36
87	alt.sys.pc-clone.dell	36
88	sci.optics	36
89	comp.sys.acorn.programmer	34
90	comp.ai.doc-analysis.ocr	32
91	rec.games.miniatures.historical	32
92	rec.music.folk	32
93	rec.photo.misc	32
94	alt.music.saxophone	31
95	alt.winsock.trumpet	30
96	comp.ai.fuzzy	30
97	rec.aviation.soaring	30
98	rec.music.funky	30
99	alt.culture.jesse-garon	28
100	comp.lang.asm.x86	28
TOTAL: 430		26495

Daily Usenet report

May 13 03:00:02 -- May 14 03:00:01