Daily Usenet report: Jan 2 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jan 2 08:47:21 newsfeed nnrpd[2764]: SERVER perl filter not defined
Jan 2 10:16:33 newsfeed pgpverify[5889]: pgpverify: verification failed
Jan 2 10:18:18 newsfeed nnrpd[5934]: SERVER perl filter not defined
Jan 2 11:40:44 newsfeed nnrpd[8778]: SERVER perl filter not defined
Jan 2 11:41:23 newsfeed nnrpd[8786]: SERVER perl filter not defined
Jan 2 13:15:10 newsfeed nnrpd[11887]: SERVER perl filter not defined
Jan 2 16:01:34 newsfeed nnrpd[17470]: SERVER perl filter not defined
Jan 2 17:42:07 newsfeed overchan[25225]: input line too long, skipping
Jan 2 20:50:35 newsfeed overchan[25225]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1991959	98.0%	232.8 MB	97.7%
innfeed	21224	1.0%	2.5 MB	1.1%
innd	14486	0.7%	2.3 MB	1.0%
nnrpd	4926	0.2%	520.2 KB	0.2%
controlchan	4	0.0%	0.5 KB	0.0%
overchan	2	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	2032602	100.0%	238.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1974854	98.4%
Negative hits	26700	1.3%
Positive hits	4305	0.2%
Cache misses	124	0.0%
TOTAL: 4	2005983	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.644	0.0%	138	0.000	33.652	466.000
article cleanup	00:00:25.947	0.0%	1987850	0.009	0.013	0.018
article logging	00:01:32.809	0.1%	1988330	0.033	0.047	0.074
article parse	00:30:36.643	2.1%	65197115	0.019	0.028	0.042
article write	00:13:12.270	0.9%	1984601	0.222	0.399	1.026
artlog/artcncl	00:00:00.006	0.0%	135	0.000	0.044	1.000
data move	00:01:33.922	0.1%	75892827	0.001	0.001	0.003
hisgrep/artcncl	00:00:01.571	0.0%	134	0.000	11.724	21.529
hishave/artcncl	00:00:00.240	0.0%	138	0.000	1.739	25.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:22:59.867	5.8%	2017507	0.377	2.468	6.784
history sync	00:00:00.016	0.0%	290	0.000	0.055	1.000
history write	03:05:07.234	12.9%	1986250	1.792	5.592	12.168
hiswrite/artcncl	00:00:00.014	0.0%	4	0.000	3.500	14.000
idle	14:36:27.127	60.9%	45092345	0.357	1.166	2.963
nntp read	00:49:03.499	3.4%	63432706	0.035	0.046	0.072
overview write	00:00:10.593	0.0%	1984601	0.003	0.005	0.008
perl filter	02:58:31.021	12.4%	1986250	3.740	5.393	8.222
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:24.278	0.3%	3981317	0.048	0.066	0.092
TOTAL: 24:00:02.602	23:44:11.701	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.102	0.0%	33205	0.000	0.003	0.016
article prepare	00:00:00.006	0.0%	30002	0.000	0.000	0.006
article read	00:01:44.102	0.1%	7405	0.100	14.058	90.333
backlog stats	00:00:00.086	0.0%	94570	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	9	0.000	0.000	0.000
data read	00:00:01.065	0.0%	53559	0.005	0.020	0.041
data write	00:00:02.597	0.0%	41562	0.025	0.062	0.114
idle	23:54:40.732	99.9%	94570	568.237	910.233	1664.839
status file	00:00:06.795	0.0%	7353	0.333	0.924	1.810
TOTAL: 23:56:44.731	23:56:35.485	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:18.061	56.6%	3099	0.000	5.828	139.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.270	0.8%	6888	0.000	0.039	1.000
readart	00:00:00.002	0.0%	66	0.000	0.030	0.167
TOTAL: 00:00:31.895	00:00:18.333	57.5%	-	-	-	-

Control commands to INND:

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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
hun-mnt@news.sztaki.hu	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	544	1981942	1981386	16	540	99%	295:20:11
2	news.nask.pl	2	11975	6801	2483	2691	56%	23:52:54
3	news.icm.edu.pl	44	279	62	202	15	22%	17:03:38
4	localhost	7	6	6	0	0	100%	00:00:01
TOTAL: 4		597	1994202	1988255	2701	3246	99%	336:16:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	530.7 GB	9.6 MB	9.8 MB	530.7 GB	99%	280.8 KB
2	news.nask.pl	28.3 MB	7.3 MB	17.6 MB	53.1 MB	53%	5.7 KB
3	news.icm.edu.pl	1.6 MB	106.2 KB	201.3 KB	1.9 MB	83%	24.9 KB
4	localhost	10.2 KB	0.0 KB	0.0 KB	10.2 KB	100%	1.7 KB
TOTAL: 4		530.7 GB	17.0 MB	27.6 MB	530.7 GB	99%	279.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 2 04:15:02 - 04:59:59	51524	2.6%	19.10	16.1 GB	3.0%	6274.81
Jan 2 05:00:00 - 05:59:59	52354	2.6%	14.54	17.2 GB	3.2%	5008.18
Jan 2 06:00:00 - 06:59:59	59459	3.0%	16.52	19.3 GB	3.6%	5624.18
Jan 2 07:00:00 - 07:59:59	73456	3.7%	20.40	23.3 GB	4.4%	6775.74
Jan 2 08:00:00 - 08:59:59	103215	5.2%	28.67	27.2 GB	5.1%	7919.35
Jan 2 09:00:00 - 09:59:59	174692	8.8%	48.53	30.0 GB	5.7%	8737.15
Jan 2 10:00:00 - 10:59:59	151057	7.6%	41.96	30.8 GB	5.8%	8981.50
Jan 2 11:00:00 - 11:59:59	123107	6.2%	34.20	29.0 GB	5.5%	8446.85
Jan 2 12:00:00 - 12:59:59	76018	3.8%	21.12	23.0 GB	4.3%	6704.84
Jan 2 13:00:00 - 13:59:59	81894	4.1%	22.75	23.7 GB	4.5%	6891.57
Jan 2 14:00:00 - 14:59:59	83572	4.2%	23.21	24.8 GB	4.7%	7228.57
Jan 2 15:00:00 - 15:59:59	85137	4.3%	23.65	23.3 GB	4.4%	6774.86
Jan 2 16:00:00 - 16:59:59	103515	5.2%	28.75	23.2 GB	4.4%	6768.57
Jan 2 17:00:00 - 17:59:59	80567	4.1%	22.38	15.2 GB	2.9%	4431.25
Jan 2 18:00:00 - 18:59:59	69315	3.5%	19.25	21.8 GB	4.1%	6348.12
Jan 2 19:00:00 - 19:59:59	61184	3.1%	17.00	20.7 GB	3.9%	6034.19
Jan 2 20:00:00 - 20:59:59	72513	3.6%	20.14	22.3 GB	4.2%	6489.14
Jan 2 21:00:00 - 21:59:59	76544	3.9%	21.26	23.2 GB	4.4%	6763.04
Jan 2 22:00:00 - 22:59:59	93718	4.7%	26.03	26.0 GB	4.9%	7562.56
Jan 2 23:00:00 - 23:59:59	79115	4.0%	21.98	22.7 GB	4.3%	6605.58
Jan 3 00:00:00 - 00:59:59	42980	2.2%	11.94	12.8 GB	2.4%	3716.86
Jan 3 01:00:00 - 01:59:59	55305	2.8%	15.36	14.4 GB	2.7%	4197.18
Jan 3 02:00:00 - 02:59:59	46529	2.3%	12.92	13.2 GB	2.5%	3842.83
Jan 3 03:00:00 - 03:59:59	73050	3.7%	20.29	21.3 GB	4.0%	6192.28
Jan 3 04:00:00 - 04:15:01	18255	0.9%	20.26	5.3 GB	1.0%	6216.03
TOTAL: 23:59:59	1988075	100.0%	23.01	529.8 GB	100.0%	6429.36

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	2727	24	1135	3	1565
2	newsfeed.pionier.net.pl	1005	80	459	0	466
3	news.icm.edu.pl	17	0	7	0	10
TOTAL: 3		3749	104	1601	3	2041

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	65
comp.mobile.ipad	16
comp.sys.raspberry-pi	8
comp.mobile.android	7
it.tlc.cellulari.android	7
de.comm.software.mailreader	1
TOTAL: 6	104

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

Groups	Count
bit.listserv.catholic	2
pl.praca.oferowana	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1469
Binary in non-binary group	25
Scoring filter (8)	19
Scoring filter (16)	17
Scoring filter (9)	17
Scoring filter (18)	3
Scoring filter (10)	2
HTML post	2
Scoring filter (12)	1
Too many newsgroups	1
TOTAL: 10	1556

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	463
	news.nask.pl	20
	news.icm.edu.pl	2
	TOTAL: 3	485
TOTAL: 1		485

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	23399	4579	6099	567	29	21950	19%	24:00:02
2	icm	13838	38	11915	2	0	0	0%	23:59:56
3	poznan	7278	8	6915	1	0	0	0%	24:08:02
4	nask	3903	1	3354	0	0	0	0%	23:57:52
5	okapi	0	0	0	0	0	1312	0%	24:05:40
TOTAL: 5		48418	4626	28283	570	29	23262	9%	120:11:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	13.5 MB	1.9 MB	15.3 MB	0.2 KB/s	3.0 KB	24:00:02
2	icm	309.0 KB	148.0 KB	457.0 KB	0.0 KB/s	11.4 KB	23:59:56
3	poznan	29.6 KB	20.8 KB	50.4 KB	0.0 KB/s	5.6 KB	24:08:02
4	nask	4.7 KB	0.0 KB	4.7 KB	0.0 KB/s	4.7 KB	23:57:52
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:05:40
TOTAL: 5		13.8 MB	2.0 MB	15.8 MB	0.0 KB/s	3.1 KB	120:11:32

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	66	65.1 KB	24	0	00:00:22
2	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 2		294	66	65.1 KB	24	6	00:00:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	582	66	65.1 KB	24	6	00:00:27
2	*.us-west-2.compute.amazonaws.com	1	0	0.0 KB	0	0	00:00:00
3	unresolved	96	0	0.0 KB	0	0	00:00:01
4	*.security.ipip.net	3	0	0.0 KB	0	0	00:00:02
TOTAL: 4		682	66	65.1 KB	24	6	00:00:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.364	3.432	00:00:22
mon.man.lodz.pl	1.376	3.336	00:00:03
scan-141.security.ipip.net	0.016	0.052	00:00:02
212.51.192.185	0.372	1.000	00:00:01
isvcs01.man.lodz.pl	0.048	0.092	00:00:01
ec2-35-164-218-99.us-west-2.compute.amazonaws.com	0.004	0.004	00:00:00
TOTAL: 6	3.180	7.916	00:00:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
scan-141.security.ipip.net	3
ec2-35-164-218-99.us-west-2.compute.amazonaws.com	1
TOTAL: 4	388

NNRP unrecognized commands (by host):

System	Conn
scan-141.security.ipip.net	12
TOTAL: 1	12

NNRP unrecognized commands (by command):

Command	Count
User-Agent: HTTP Banner Detection (https...	3
Connection: close	3
Host: 212.191.2.21:119	3
GET / HTTP/1.1	3
TOTAL: 4	12

NNRP client timeouts [Top 20]:

System	Conn	Peer
scan-141.security.ipip.net	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	66	100.0%
TOTAL: 1		66	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	44
2	pl.comp.pecet	22
TOTAL: 2		66

Daily Usenet report

Jan 2 04:15:02 -- Jan 3 04:15:01