Daily Usenet report: Oct 19 04:15:01

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	60191	64.2%	5.4 MB	65.8%
innd	16494	17.6%	1.5 MB	18.4%
inn	16481	17.6%	1.3 MB	15.2%
innfeed	527	0.6%	43.6 KB	0.5%
nocem	47	0.1%	3.7 KB	0.0%
controlchan	2	0.0%	0.1 KB	0.0%
TOTAL: 6	93742	100.0%	8.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	15872	90.9%
Negative hits	1519	8.7%
Positive hits	45	0.3%
Cache misses	19	0.1%
TOTAL: 4	17455	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.085	0.0%	89	0.000	0.955	27.000
article cleanup	00:00:00.113	0.0%	15900	0.000	0.007	0.026
article logging	00:00:00.349	0.0%	16407	0.000	0.021	0.080
article parse	00:00:00.214	0.0%	17955	0.000	0.012	0.044
article write	00:00:08.321	0.0%	15396	0.282	0.540	2.158
artlog/artcncl	00:00:00.000	0.0%	82	0.000	0.000	0.000
data move	00:00:00.035	0.0%	20485	0.000	0.002	0.022
hisgrep/artcncl	00:00:00.007	0.0%	57	0.000	0.123	1.000
hishave/artcncl	00:00:00.008	0.0%	89	0.000	0.090	0.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.827	0.0%	17517	0.027	0.276	1.705
history sync	00:00:00.024	0.0%	408	0.000	0.059	0.667
history write	00:00:04.935	0.0%	15900	0.059	0.310	2.238
hiswrite/artcncl	00:00:00.009	0.0%	32	0.000	0.281	1.000
idle	23:02:15.011	95.9%	30620	1735.149	2708.524	6307.521
nntp read	00:00:00.352	0.0%	22646	0.000	0.016	0.048
overview write	00:00:02.009	0.0%	15396	0.026	0.130	2.591
perl filter	00:58:23.575	4.1%	15900	105.478	220.351	662.540
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.006	0.0%	167	0.000	0.036	0.200
TOTAL: 24:01:08.300	24:00:59.880	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.001	0.0%	93	0.000	0.011	0.022
article prepare	00:00:00.000	0.0%	260	0.000	0.000	0.000
article read	00:00:00.051	0.0%	65	0.000	0.785	28.000
backlog stats	00:00:00.008	0.0%	4374	0.000	0.002	0.037
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.002	0.0%	293	0.000	0.007	0.056
data write	00:00:00.003	0.0%	202	0.000	0.015	0.021
idle	23:46:01.326	100.0%	4374	2143.100	19561.346	23140.778
status file	00:00:00.016	0.0%	45	0.000	0.356	0.500
TOTAL: 23:46:01.471	23:46:01.407	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	30:44:03.544	94.1%	53796	1.200	2056.724	161965.800
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:36.360	0.0%	203384	0.000	0.179	12.357
readart	00:00:06.064	0.0%	1398	0.000	4.338	53.000
TOTAL: 32:40:30.580	30:44:45.968	94.1%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	3
flushlogs	2
go	2
logmode	2
mode	150
name	2
pause	2
paused	2
reload	2
reserve	2
shutdown	3
TOTAL: 12	173

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	i2pn	14	15939	15449	6	484	96%	25:35:29
2	novabbs	3720	211	10	160	41	4%	01:32:38
3	localhost	34	3	3	0	0	100%	00:00:01
TOTAL: 3		3768	16153	15462	166	525	95%	27:08:08

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn	42.8 MB	0.0 KB	1.5 MB	44.3 MB	96%	2.8 KB
2	novabbs	20.8 KB	10.0 KB	67.4 KB	98.3 KB	21%	1.9 KB
3	localhost	3.5 KB	0.0 KB	0.0 KB	3.5 KB	100%	1.2 KB
TOTAL: 3		42.8 MB	10.0 KB	1.6 MB	44.4 MB	96%	2.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 19 04:15:01 - 04:59:59	206	1.3%	0.08	735.2 KB	1.7%	0.27
Oct 19 05:00:00 - 05:59:59	325	2.1%	0.09	926.2 KB	2.1%	0.26
Oct 19 06:00:00 - 06:59:59	488	3.2%	0.14	1.2 MB	2.9%	0.35
Oct 19 07:00:00 - 07:59:59	611	4.0%	0.17	1.8 MB	4.3%	0.52
Oct 19 08:00:00 - 08:59:59	675	4.4%	0.19	2.1 MB	5.0%	0.60
Oct 19 09:00:00 - 09:59:59	837	5.4%	0.23	2.0 MB	4.7%	0.57
Oct 19 10:00:00 - 10:59:59	721	4.7%	0.20	1.8 MB	4.3%	0.52
Oct 19 11:00:00 - 11:59:59	710	4.6%	0.20	2.0 MB	4.6%	0.56
Oct 19 12:00:00 - 12:59:59	894	5.8%	0.25	2.5 MB	5.8%	0.71
Oct 19 13:00:00 - 13:59:59	893	5.8%	0.25	2.4 MB	5.7%	0.70
Oct 19 14:00:00 - 14:59:59	978	6.3%	0.27	2.5 MB	5.8%	0.71
Oct 19 15:00:00 - 15:59:59	887	5.8%	0.25	2.3 MB	5.4%	0.66
Oct 19 16:00:00 - 16:59:59	848	5.5%	0.24	2.1 MB	4.9%	0.59
Oct 19 17:00:00 - 17:59:59	754	4.9%	0.21	1.8 MB	4.3%	0.52
Oct 19 18:00:00 - 18:59:59	815	5.3%	0.23	2.4 MB	5.6%	0.68
Oct 19 19:00:00 - 19:59:59	737	4.8%	0.20	2.3 MB	5.3%	0.64
Oct 19 20:00:00 - 20:59:59	769	5.0%	0.21	2.4 MB	5.6%	0.67
Oct 19 21:00:00 - 21:59:59	610	4.0%	0.17	1.6 MB	3.7%	0.45
Oct 19 22:00:00 - 22:59:59	635	4.1%	0.18	2.0 MB	4.6%	0.56
Oct 19 23:00:00 - 23:59:59	531	3.4%	0.15	1.5 MB	3.5%	0.43
Oct 20 00:00:00 - 00:59:59	433	2.8%	0.12	1.2 MB	2.9%	0.35
Oct 20 01:00:00 - 01:59:59	417	2.7%	0.12	1.1 MB	2.6%	0.32
Oct 20 02:00:00 - 02:59:59	276	1.8%	0.08	962.5 KB	2.2%	0.27
Oct 20 03:00:00 - 03:59:59	284	1.8%	0.08	756.1 KB	1.7%	0.21
Oct 20 04:00:00 - 04:15:01	71	0.5%	0.08	310.1 KB	0.7%	0.34
TOTAL: 24:00:00	15405	100.0%	0.18	42.6 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Other
1	i2pn	984	2	982
2	novabbs	15	0	15
TOTAL: 2		999	2	997

Unwanted newsgroups [Top 20]:

Newsgroup	Count
hk.politics	2
TOTAL: 1	2

INND Perl filter [Top 20]:

Reason	Count
Exceeds Spam Level	421
Poison newsgroup	55
EMP (phn nph)	9
EMP (phn path)	6
TOTAL: 4	491

NoCeM on Spool:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Spool	%Took	Elapsed
1	novabbs	135	82	27	5	60%	00:36:15
2	i2pn	9	3	6	0	33%	00:49:23
TOTAL: 2		144	85	33	5	59%	01:25:38

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	novabbs	240.2 KB	240.2 KB	0.1 KB/s	2.9 KB	00:36:15
2	i2pn	3.5 KB	3.5 KB	0.0 KB/s	1.2 KB	00:49:23
TOTAL: 2		243.7 KB	243.7 KB	0.0 KB/s	2.9 KB	01:25:38

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	novabbs	9775	358	1017.1 KB	358	3	3	07:41:26
2	ip68-2-45-86.ph.ph.cox.net	10	4	5.4 KB	4	0	0	24:56:50
3	i2pn2.org	168	1	0.9 KB	1	0	0	00:00:24
TOTAL: 3		9953	363	1023.4 KB	363	3	3	32:40:02

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	?	9775	358	1017.1 KB	358	3	3	07:41:26
2	*.ph.ph.cox.net	10	4	5.4 KB	4	0	0	24:56:50
3	*.org	168	1	0.9 KB	1	0	0	00:00:24
4	*.sfj.corp.censys.io	1	0	0.0 KB	0	0	0	00:00:00
5	*.internetdsl.tpnet.pl	1	0	0.0 KB	0	0	0	00:00:32
6	*.hinet-ip.hinet.net	1	0	0.0 KB	0	0	0	00:00:31
7	*.com	1	0	0.0 KB	0	0	0	00:00:04
8	unresolved	3	0	0.0 KB	0	0	0	00:00:11
TOTAL: 8		9960	363	1023.4 KB	363	3	3	32:40:02

NNRP auth users [Top 20]:

User	Conn
retrobbs1	3889
def.i2p	3
TOTAL: 2	3892

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip68-2-45-86.ph.ph.cox.net	0.808	0.220	0.010	24:56:50
novabbs	441.292	55.396	7.969	07:41:26
emc141.internetdsl.tpnet.pl	0.036	0.008	0.000	00:00:32
122-116-223-77.hinet-ip.hinet.net	0.036	0.008	0.000	00:00:31
i2pn2.org	7.340	1.016	0.396	00:00:24
159.203.201.125	0.044	0.004	0.000	00:00:09
novabbs.com	0.044	0.000	0.000	00:00:04
43.245.222.176	0.036	0.008	0.000	00:00:01
163.172.111.59	0.044	0.000	0.000	00:00:00
worker-18.sfj.corp.censys.io	0.036	0.008	0.000	00:00:00
TOTAL: 10	449.716	56.668	8.375	32:40:02

Curious NNRP server explorers [Top 20]:

System	Conn
novabbs.com	1
122-116-223-77.hinet-ip.hinet.net	1
emc141.internetdsl.tpnet.pl	1
159.203.201.125	1
43.245.222.176	1
worker-18.sfj.corp.censys.io	1
163.172.111.59	1
TOTAL: 7	7

NNRP unrecognized commands (by host):

System	Conn
novabbs	22
122-116-223-77.hinet-ip.hinet.net	10
emc141.internetdsl.tpnet.pl	10
163.172.111.59	1
TOTAL: 4	43

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	8
Host: wwcqkwfo5yhe6uribv5tzylk25j5hkdk6g...	7
Accept-Encoding: identity	3
Connection: close	3
Content-Type: text/xml	2
User-Agent: MYOB/6.66 (AN/ON)	2
User-Agent: ApiTool	2
Accept-Language: en-us	2
Cache-Control: max-age=0	2
Accept: /	2
Authorization: Basic YWRtaW46ezEyMjEzQkQ...	2
Content-Length: 586	2
Accept-Encoding: gzip, deflate	1
#003	1
Host: 172.107.56.92	1
sentry-trace: 00-6deac0b2c0ab43a8885cf89...	1
Host: news-i2pn.i2p	1
Host: 84.190.16.197	1
TOTAL: 18	43

NNRP client timeouts [Top 20]:

System	Conn	Peer
ip68-2-45-86.ph.ph.cox.net	6	1
worker-18.sfj.corp.censys.io	1	1
163.172.111.59	1	1
TOTAL: 3	8	3

Newsgroup request counts (by category):

Category		Count	Pct
1	rec	210	57.9%
2	alt	88	24.2%
3	comp	57	15.7%
4	rocksolid	8	2.2%
TOTAL: 4		363	100.0%

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

Newsgroup		Count
1	rec.bicycles.tech	89
2	rec.food.cooking	72
3	alt.arts.poetry.comments	62
4	comp.lang.c	25
5	comp.lang.forth	24
6	alt.usage.english	20
7	rec.puzzles.crosswords	11
8	rec.music.rock-pop-r+b.1950s	10
9	rec.outdoors.rv-travel	10
10	rocksolid.shared.offtopic	7
11	rec.music.classical.recordings	5
12	comp.dsp	5
13	rec.arts.drwho	4
14	rec.arts.tv	3
15	alt.humor.puns	3
16	comp.mobile.android	3
17	rec.bicycles.misc	2
18	rec.music.classical	2
19	rec.arts.sf.written	1
20	alt.dreams.castaneda	1
21	rocksolid.shared.test	1
22	alt.english.usage	1
23	rec.crafts.metalworking	1
24	alt.comp.os.windows-10	1
TOTAL: 24		363

Daily Usenet report

Oct 19 04:15:01 -- Oct 20 04:15:01