Daily Usenet report: Sep 20 04:15:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	16254	56.4%	1.3 MB	53.1%
nnrpd	8520	29.6%	700.2 KB	27.5%
innd	3067	10.6%	375.3 KB	14.7%
innfeed	944	3.3%	114.7 KB	4.5%
nocem	37	0.1%	3.9 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	28823	100.0%	2.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	15563	65.5%
Positive hits	4969	20.9%
Negative hits	3206	13.5%
Cache misses	21	0.1%
TOTAL: 4	23759	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.046	0.0%	72	0.000	0.639	3.000
article cleanup	00:00:00.136	0.0%	15873	0.000	0.009	0.054
article logging	00:00:00.451	0.0%	16188	0.000	0.028	0.143
article parse	00:00:00.236	0.0%	17928	0.000	0.013	0.051
article write	00:00:09.811	0.0%	14554	0.250	0.674	3.054
artlog/artcncl	00:00:00.000	0.0%	71	0.000	0.000	0.000
data move	00:00:00.035	0.0%	25329	0.000	0.001	0.013
hisgrep/artcncl	00:00:00.006	0.0%	55	0.000	0.109	1.000
hishave/artcncl	00:00:00.007	0.0%	72	0.000	0.097	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:06.059	0.0%	23730	0.020	0.255	1.373
history sync	00:00:00.030	0.0%	307	0.000	0.098	1.000
history write	00:00:05.502	0.0%	15563	0.069	0.354	2.079
hiswrite/artcncl	00:00:00.003	0.0%	17	0.000	0.176	0.500
idle	22:58:11.269	95.7%	24123	1831.059	3427.902	12880.196
nntp read	00:00:00.447	0.0%	24042	0.000	0.019	0.068
overview write	00:00:02.383	0.0%	14554	0.027	0.164	2.756
perl filter	01:01:48.996	4.3%	15563	120.073	238.321	610.588
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.321	0.0%	14584	0.000	0.022	0.059
TOTAL: 24:00:30.430	24:00:25.738	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.028	0.0%	14542	0.000	0.002	0.029
article prepare	00:00:00.006	0.0%	38044	0.000	0.000	0.009
article read	00:00:00.476	0.0%	9511	0.000	0.050	1.174
backlog stats	00:00:00.016	0.0%	76745	0.000	0.000	0.004
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.330	0.0%	41766	0.000	0.008	0.030
data write	00:00:00.571	0.0%	27457	0.000	0.021	0.059
idle	23:58:17.594	100.0%	76745	662.372	1124.472	3811.417
status file	00:00:00.137	0.0%	276	0.000	0.496	2.000
TOTAL: 23:58:20.036	23:58:19.158	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	135:40:55.254	100.0%	37348	0.500	13078.485	57096.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.401	0.0%	97048	0.000	0.014	0.500
readart	00:00:01.039	0.0%	6510	0.040	0.160	35.000
TOTAL: 135:41:50.260	135:40:57.694	100.0%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	3
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	169

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	i2pn	14	14823	14252	69	502	96%	25:27:11
2	novabbs	212	5712	274	4605	833	4%	24:41:50
3	localhost	30	0	0	0	0	0%	00:00:01
TOTAL: 3		256	20535	14526	4674	1335	70%	50:09:02

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn	39.0 MB	404.9 KB	1.6 MB	41.0 MB	95%	2.8 KB
2	novabbs	1.3 MB	611.9 KB	1.7 MB	3.6 MB	35%	3.3 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		40.2 MB	1016.7 KB	3.3 MB	44.6 MB	90%	2.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 20 04:15:02 - 04:59:59	209	1.4%	0.08	645.0 KB	1.6%	0.24
Sep 20 05:00:00 - 05:59:59	333	2.3%	0.09	1022.3 KB	2.5%	0.28
Sep 20 06:00:00 - 06:59:59	433	3.0%	0.12	1.3 MB	3.2%	0.37
Sep 20 07:00:00 - 07:59:59	694	4.8%	0.19	1.6 MB	4.1%	0.46
Sep 20 08:00:00 - 08:59:59	745	5.1%	0.21	1.8 MB	4.5%	0.52
Sep 20 09:00:00 - 09:59:59	673	4.6%	0.19	1.7 MB	4.3%	0.49
Sep 20 10:00:00 - 10:59:59	649	4.5%	0.18	1.9 MB	4.8%	0.55
Sep 20 11:00:00 - 11:59:59	698	4.8%	0.19	1.6 MB	4.0%	0.46
Sep 20 12:00:00 - 12:59:59	771	5.3%	0.21	1.8 MB	4.4%	0.50
Sep 20 13:00:00 - 13:59:59	729	5.0%	0.20	1.8 MB	4.5%	0.52
Sep 20 14:00:00 - 14:59:59	949	6.5%	0.26	2.4 MB	5.9%	0.67
Sep 20 15:00:00 - 15:59:59	686	4.7%	0.19	2.3 MB	5.7%	0.65
Sep 20 16:00:00 - 16:59:59	790	5.4%	0.22	2.2 MB	5.5%	0.63
Sep 20 17:00:00 - 17:59:59	892	6.1%	0.25	2.3 MB	5.7%	0.65
Sep 20 18:00:00 - 18:59:59	811	5.6%	0.23	2.2 MB	5.6%	0.64
Sep 20 19:00:00 - 19:59:59	820	5.6%	0.23	2.4 MB	5.9%	0.68
Sep 20 20:00:00 - 20:59:59	801	5.5%	0.22	2.5 MB	6.1%	0.70
Sep 20 21:00:00 - 21:59:59	589	4.0%	0.16	1.6 MB	4.0%	0.45
Sep 20 22:00:00 - 22:59:59	612	4.2%	0.17	2.0 MB	5.0%	0.57
Sep 20 23:00:00 - 23:59:59	453	3.1%	0.13	1.4 MB	3.5%	0.40
Sep 21 00:00:00 - 00:59:59	316	2.2%	0.09	893.1 KB	2.2%	0.25
Sep 21 01:00:00 - 01:59:59	300	2.1%	0.08	836.6 KB	2.0%	0.23
Sep 21 02:00:00 - 02:59:59	274	1.9%	0.08	869.2 KB	2.1%	0.24
Sep 21 03:00:00 - 03:59:59	253	1.7%	0.07	1015.4 KB	2.5%	0.28
Sep 21 04:00:00 - 04:15:01	70	0.5%	0.08	304.9 KB	0.7%	0.34
TOTAL: 23:59:59	14550	100.0%	0.17	40.3 MB	100.0%	0.48

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	novabbs	824	0	192	632
2	i2pn	809	2	117	690
TOTAL: 2		1633	2	309	1322

Unwanted newsgroups [Top 20]:

Newsgroup	Count
24hoursupport.helpdesk	1
hk.politics	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	624
Exceeds Spam Level	318
Poison newsgroup	57
Scoring filter (9)	5
Scoring filter (8)	2
Scoring filter (10)	1
TOTAL: 6	1007

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	i2pn	315
	TOTAL: 1	315
TOTAL: 1		315

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	novabbs	17783	9472	4757	46	4	53%	23:59:49
2	i2pn	276	0	273	0	0	0%	12:06:30
TOTAL: 2		18059	9472	5030	46	4	52%	36:06:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	novabbs	23.7 MB	114.8 KB	23.8 MB	0.3 KB/s	2.6 KB	23:59:49
2	i2pn	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	12:06:30
TOTAL: 2		23.7 MB	114.8 KB	23.8 MB	0.2 KB/s	2.6 KB	36:06:19

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	novabbs	222	135	377.1 KB	135	0	00:12:45
2	ip68-2-45-86.ph.ph.cox.net	2	66	220.1 KB	2	1	135:29:00
TOTAL: 2		224	201	597.1 KB	137	1	135:41:49

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	?	222	135	377.1 KB	135	0	00:12:45
2	*.ph.ph.cox.net	2	66	220.1 KB	2	1	135:29:00
3	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
4	*.org	2	0	0.0 KB	0	0	00:00:02
TOTAL: 4		227	201	597.1 KB	137	1	135:41:49

NNRP auth users [Top 20]:

User	Conn
retrobbs1	215
TOTAL: 1	215

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip68-2-45-86.ph.ph.cox.net	1.752	2.192	0.004	135:29:00
novabbs	40.316	5.644	0.558	00:12:45
i2pn2.org	0.208	0.040	0.005	00:00:02
scan-141.security.ipip.net	0.048	0.000	0.000	00:00:00
TOTAL: 4	42.324	7.876	0.567	135:41:49

Curious NNRP server explorers [Top 20]:

System	Conn
i2pn2.org	2
scan-141.security.ipip.net	1
TOTAL: 2	3

NNRP unrecognized commands (by host):

System	Conn
novabbs	33
scan-141.security.ipip.net	4
TOTAL: 2	37

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	11
Host: wwcqkwfo5yhe6uribv5tzylk25j5hkdk6g...	8
Connection: close	3
User-Agent: MYOB/6.66 (AN/ON)	3
Accept-Encoding: gzip, deflate	2
Host: 157.230.63.40:119	1
Host: asq5mo52aghemn2i.onion:119	1
sentry-trace: 00-fb5e1a8a41644d7ab061bac...	1
Accept-Encoding: deflate, gzip	1
Accept-Encoding: identity	1
User-Agent: Mozilla/5.0 (Windows NT 6.1;...	1
Accept: /	1
Host: news-i2pn.i2p	1
User-Agent: HTTP Banner Detection (https...	1
sentry-trace: 00-2871c32692e8426385bcd68...	1
TOTAL: 15	37

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip68-2-45-86.ph.ph.cox.net	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	comp	134	97.8%
2	rocksolid	2	1.5%
3	alt	1	0.7%
TOTAL: 3		137	100.0%

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

Newsgroup		Count
1	comp.lang.c	134
2	rocksolid.shared.linux	1
3	rocksolid.nodes.announce	1
4	alt.usage.english	1
TOTAL: 4		137

Daily Usenet report

Sep 20 04:15:02 -- Sep 21 04:15:01