Daily Usenet report: Oct 10 04:15:02

Log entries by program:

Program name	Lines	%Lines	Size	%Size
nnrpd	48308	63.9%	4.2 MB	63.8%
inn	17708	23.4%	1.5 MB	21.8%
innd	8452	11.2%	845.9 KB	12.4%
innfeed	1062	1.4%	124.4 KB	1.8%
nocem	45	0.1%	4.1 KB	0.1%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	75576	100.0%	6.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	16790	62.4%
Positive hits	5654	21.0%
Negative hits	4420	16.4%
Cache misses	22	0.1%
TOTAL: 4	26886	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.050	0.0%	81	0.000	0.617	2.000
article cleanup	00:00:00.149	0.0%	17223	0.000	0.009	0.060
article logging	00:00:00.406	0.0%	17685	0.000	0.023	0.068
article parse	00:00:00.258	0.0%	19595	0.000	0.013	0.055
article write	00:00:07.677	0.0%	15447	0.189	0.497	1.303
artlog/artcncl	00:00:00.000	0.0%	68	0.000	0.000	0.000
data move	00:00:00.046	0.0%	29013	0.000	0.002	0.014
hisgrep/artcncl	00:00:00.006	0.0%	78	0.000	0.077	1.000
hishave/artcncl	00:00:00.003	0.0%	81	0.000	0.037	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:00:04.555	0.0%	27038	0.007	0.168	0.557
history sync	00:00:00.026	0.0%	413	0.000	0.063	0.500
history write	00:00:04.990	0.0%	16882	0.064	0.296	1.136
hiswrite/artcncl	00:00:00.001	0.0%	3	0.000	0.333	1.000
idle	22:59:28.190	95.7%	31423	689.568	2634.000	8858.448
nntp read	00:00:00.477	0.0%	28755	0.000	0.017	0.049
overview write	00:00:02.640	0.0%	15447	0.043	0.171	3.528
perl filter	01:01:09.648	4.2%	16882	90.500	217.370	486.575
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:00.288	0.0%	15487	0.000	0.019	0.078
TOTAL: 24:01:05.878	24:00:59.410	100.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.032	0.0%	15413	0.000	0.002	0.032
article prepare	00:00:00.004	0.0%	39748	0.000	0.000	0.006
article read	00:00:00.457	0.0%	9937	0.000	0.046	1.158
backlog stats	00:00:00.027	0.0%	82098	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.305	0.0%	44627	0.000	0.007	0.033
data write	00:00:00.500	0.0%	29398	0.000	0.017	0.047
idle	23:58:57.935	100.0%	82098	630.673	1051.645	3131.979
status file	00:00:00.163	0.0%	336	0.000	0.485	6.750
TOTAL: 23:59:00.306	23:58:59.423	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	90:17:32.725	99.9%	66792	0.750	4866.642	120066.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.034	0.0%	168817	0.000	0.012	0.500
readart	00:00:08.150	0.0%	3028	0.048	2.692	38.000
TOTAL: 90:24:04.604	90:17:42.909	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	i2pn	9	15823	15163	44	616	95%	24:52:43
2	novabbs	1458	7168	262	5682	1224	3%	27:34:10
3	localhost	30	0	0	0	0	0%	00:00:03
TOTAL: 3		1497	22991	15425	5726	1840	67%	52:26:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	i2pn	42.4 MB	342.9 KB	2.1 MB	44.8 MB	94%	2.9 KB
2	novabbs	733.1 KB	802.7 KB	2.7 MB	4.2 MB	17%	2.9 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		43.1 MB	1.1 MB	4.7 MB	49.0 MB	88%	2.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 10 04:15:02 - 04:59:59	238	1.5%	0.09	769.9 KB	1.7%	0.29
Oct 10 05:00:00 - 05:59:59	309	2.0%	0.09	803.9 KB	1.8%	0.22
Oct 10 06:00:00 - 06:59:59	448	2.9%	0.12	1.3 MB	3.0%	0.37
Oct 10 07:00:00 - 07:59:59	626	4.1%	0.17	1.6 MB	3.8%	0.47
Oct 10 08:00:00 - 08:59:59	736	4.8%	0.20	1.9 MB	4.5%	0.55
Oct 10 09:00:00 - 09:59:59	774	5.0%	0.21	2.1 MB	4.9%	0.60
Oct 10 10:00:00 - 10:59:59	897	5.8%	0.25	2.3 MB	5.3%	0.65
Oct 10 11:00:00 - 11:59:59	702	4.6%	0.20	1.8 MB	4.2%	0.51
Oct 10 12:00:00 - 12:59:59	843	5.5%	0.23	2.2 MB	5.1%	0.62
Oct 10 13:00:00 - 13:59:59	880	5.7%	0.24	2.2 MB	5.1%	0.63
Oct 10 14:00:00 - 14:59:59	900	5.8%	0.25	2.3 MB	5.4%	0.66
Oct 10 15:00:00 - 15:59:59	869	5.6%	0.24	2.2 MB	5.2%	0.63
Oct 10 16:00:00 - 16:59:59	937	6.1%	0.26	2.7 MB	6.3%	0.78
Oct 10 17:00:00 - 17:59:59	857	5.6%	0.24	2.2 MB	5.1%	0.63
Oct 10 18:00:00 - 18:59:59	765	5.0%	0.21	2.2 MB	5.0%	0.62
Oct 10 19:00:00 - 19:59:59	816	5.3%	0.23	2.2 MB	5.1%	0.62
Oct 10 20:00:00 - 20:59:59	740	4.8%	0.21	2.8 MB	6.6%	0.81
Oct 10 21:00:00 - 21:59:59	662	4.3%	0.18	2.0 MB	4.7%	0.57
Oct 10 22:00:00 - 22:59:59	582	3.8%	0.16	1.8 MB	4.2%	0.51
Oct 10 23:00:00 - 23:59:59	439	2.8%	0.12	1.3 MB	3.0%	0.36
Oct 11 00:00:00 - 00:59:59	342	2.2%	0.10	863.3 KB	2.0%	0.24
Oct 11 01:00:00 - 01:59:59	265	1.7%	0.07	895.7 KB	2.0%	0.25
Oct 11 02:00:00 - 02:59:59	404	2.6%	0.11	1.2 MB	2.9%	0.35
Oct 11 03:00:00 - 03:59:59	304	2.0%	0.08	1.0 MB	2.3%	0.29
Oct 11 04:00:00 - 04:15:01	77	0.5%	0.09	300.6 KB	0.7%	0.33
TOTAL: 23:59:59	15412	100.0%	0.18	43.1 MB	100.0%	0.51

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	novabbs	1154	0	236	918
2	i2pn	1075	1	105	969
TOTAL: 2		2229	1	341	1887

Unwanted newsgroups [Top 20]:

Newsgroup	Count
24hoursupport.helpdesk	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (phn path)	775
Exceeds Spam Level	471
EMP (phn nph)	136
Poison newsgroup	38
EMP (md5)	4
Scoring filter (8)	2
HTML Image Tags	1
TOTAL: 7	1427

NoCeM on Spool:

Id	Good	Bad	Unique	Total
bleachbot@httrack.com	22	0	26	26
TOTAL: 1	22	17	26	26

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	i2pn	459
	novabbs	1
	TOTAL: 2	460
TOTAL: 1		460

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	novabbs	19225	9863	5239	74	1	51%	23:59:57
2	i2pn	243	0	238	0	0	0%	12:48:40
TOTAL: 2		19468	9863	5477	74	1	50%	36:48:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	novabbs	25.1 MB	216.8 KB	25.3 MB	0.3 KB/s	2.6 KB	23:59:57
2	i2pn	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	12:48:40
TOTAL: 2		25.1 MB	216.8 KB	25.3 MB	0.2 KB/s	2.6 KB	36:48:37

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	novabbs	6787	671	1.8 MB	670	0	10:55:31
2	ip68-2-45-86.ph.ph.cox.net	1	46	230.0 KB	8	5	79:27:18
3	i2pn2.org	26	1	1.0 KB	1	0	00:00:10
TOTAL: 3		6814	718	2.0 MB	679	5	90:23:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	?	6787	671	1.8 MB	670	0	10:55:31
2	*.ph.ph.cox.net	1	46	230.0 KB	8	5	79:27:18
3	*.org	26	1	1.0 KB	1	0	00:00:10
4	*.hinet-ip.hinet.net	1	0	0.0 KB	0	0	00:00:10
5	*.sfj.corp.censys.io	1	0	0.0 KB	0	0	00:00:00
6	unresolved	1	0	0.0 KB	0	0	00:00:32
TOTAL: 6		6817	718	2.0 MB	679	5	90:23:45

NNRP auth users [Top 20]:

User	Conn
retrobbs1	1483
def.i2p	246
TOTAL: 2	1729

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Idle(ms)	Elapsed
ip68-2-45-86.ph.ph.cox.net	1.024	1.384	0.002	79:27:18
novabbs	323.632	39.444	3.707	10:55:31
185.218.245.20	0.044	0.000	0.000	00:00:32
59-126-168-100.hinet-ip.hinet.net	0.048	0.000	0.000	00:00:10
i2pn2.org	1.668	0.168	0.061	00:00:10
worker-18.sfj.corp.censys.io	0.036	0.008	0.000	00:00:00
TOTAL: 6	326.452	41.004	3.770	90:23:45

Curious NNRP server explorers [Top 20]:

System	Conn
59-126-168-100.hinet-ip.hinet.net	1
worker-18.sfj.corp.censys.io	1
185.218.245.20	1
TOTAL: 3	3

NNRP unrecognized commands (by host):

System	Conn
novabbs	53
185.218.245.20	10
TOTAL: 2	63

NNRP unrecognized commands (by command):

Command	Count
Host: wwcqkwfo5yhe6uribv5tzylk25j5hkdk6g...	12
GET / HTTP/1.1	9
Connection: close	7
User-Agent: MYOB/6.66 (AN/ON)	6
X-Accept-Encoding: x-i2p-gzip;q=1.0, ide...	5
Accept: text/html,application/xhtml+xml,...	5
Accept-Encoding: gzip,deflate	5
GET /?_escaped_fragment_=sig%3D32JwUI8P6...	3
Accept-Encoding: gzip, deflate	1
User-Agent: ApiTool	1
Content-Length: 586	1
Host: 195.177.112.126	1
sentry-trace: 00-2866520e7a404dfda019543...	1
Content-Type: text/xml	1
Accept: /	1
Cache-Control: max-age=0	1
Accept-Encoding: identity	1
Authorization: Basic YWRtaW46ezEyMjEzQkQ...	1
Accept-Language: en-us	1
TOTAL: 19	63

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	rec	276	39.8%
2	alt	257	37.0%
3	comp	145	20.9%
4	rocksolid	13	1.9%
5	news	3	0.4%
TOTAL: 5		694	100.0%

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

Newsgroup		Count
1	comp.lang.python	133
2	alt.test	112
3	rec.food.cooking	86
4	alt.usage.english	46
5	rec.outdoors.rv-travel	30
6	rec.photo.digital	30
7	rec.games.backgammon	26
8	rec.games.trivia	19
9	alt.humor.puns	17
10	rec.music.classical.recordings	16
11	rec.music.rock-pop-r+b.1950s	14
12	alt.astronomy	14
13	alt.dreams.castaneda	13
14	rec.bicycles.tech	12
15	rec.aviation.soaring	12
16	alt.arts.poetry.comments	10
17	rec.crafts.metalworking	10
18	alt.obituaries	9
19	alt.horror	9
20	alt.privacy.anon-server	8
21	alt.food.fast-food	7
22	alt.tv.survivor	5
23	rec.woodworking	5
24	alt.language.latin	4
25	rec.arts.tv.soaps.cbs	4
26	comp.databases.ms-access	4
27	rocksolid.shared.test	4
28	rocksolid.shared.hacking	4
29	rec.gambling.lottery	3
30	rec.games.bridge	3
31	comp.lang.fortran	3
32	news.admin.net-abuse.email	3
33	comp.lang.lisp	2
34	comp.lang.forth	2
35	rocksolid.nodes	2
36	rec.arts.sf.science	2
37	rec.music.classical	1
38	alt.os.linux.suse	1
39	rec.games.pinball	1
40	rocksolid.shared.linux	1
41	alt.food.wine	1
42	comp.dsp	1
43	rocksolid.shared.dancingelephants	1
44	rec.humor	1
45	rec.gardens	1
46	rocksolid.shared.news	1
47	alt.english.usage	1
TOTAL: 47		694

Daily Usenet report

Oct 10 04:15:02 -- Oct 11 04:15:01