Daily Usenet report: Dec 3 04:16:10

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Dec 3 09:30:20 newsfeed overchan[20511]: input line too long, skipping
Dec 3 09:37:37 newsfeed overchan[20511]: input line too long, skipping
Dec 3 11:24:44 newsfeed nnrpd[2656]: SERVER perl filter not defined
Dec 3 11:45:54 newsfeed nnrpd[3364]: SERVER perl filter not defined
Dec 3 11:49:58 newsfeed nnrpd[3414]: SERVER perl filter not defined
Dec 3 12:09:27 newsfeed nnrpd[4216]: SERVER perl filter not defined
Dec 3 15:02:52 newsfeed overchan[20511]: input line too long, skipping
Dec 3 15:03:08 newsfeed overchan[20511]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2331435	96.9%	272.4 MB	96.6%
innfeed	50582	2.1%	6.1 MB	2.2%
innd	17979	0.7%	3.0 MB	1.0%
nnrpd	4894	0.2%	523.0 KB	0.2%
overchan	5	0.0%	0.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2404896	100.0%	281.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2316539	90.8%
Positive hits	118886	4.7%
Negative hits	114619	4.5%
Cache misses	1278	0.1%
TOTAL: 4	2551322	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.517	0.0%	75	0.000	73.560	1305.000
article cleanup	00:00:27.918	0.0%	2328778	0.008	0.012	0.019
article logging	00:01:45.929	0.1%	2329228	0.034	0.045	0.064
article parse	00:35:40.335	2.5%	75906883	0.011	0.028	0.059
article write	00:16:46.403	1.2%	2318507	0.203	0.434	19.886
artlog/artcncl	00:00:00.003	0.0%	74	0.000	0.041	1.000
artlog/artparse	00:00:00.002	0.0%	43	0.000	0.047	0.071
data move	00:02:30.313	0.2%	87562551	0.001	0.002	0.009
hisgrep/artcncl	00:00:01.630	0.0%	75	0.000	21.733	616.000
hishave/artcncl	00:00:00.530	0.0%	75	0.000	7.067	60.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	04:06:10.836	17.1%	2563349	0.725	5.762	33.611
history sync	00:00:00.020	0.0%	290	0.000	0.069	0.500
history write	06:00:38.989	25.0%	2321353	1.696	9.322	494.382
hiswrite/artcncl	00:00:00.000	0.0%	0	0.000	0.000	0.000
idle	07:43:46.352	32.2%	50314340	0.072	0.553	1.663
nntp read	00:49:33.140	3.4%	74114942	0.025	0.040	0.059
overview write	00:00:11.716	0.0%	2318507	0.003	0.005	0.010
perl filter	03:53:35.074	16.2%	2321352	4.182	6.037	8.808
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:14.000	0.4%	4898704	0.047	0.064	0.084
TOTAL: 24:00:13.078	23:36:28.707	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.690	0.0%	522665	0.001	0.003	0.007
article prepare	00:00:00.132	0.0%	481584	0.000	0.000	0.010
article read	00:02:11.934	0.2%	113252	0.256	1.165	206.861
backlog stats	00:00:01.727	0.0%	2021665	0.000	0.001	0.002
callbacks	00:00:00.000	0.0%	2	0.000	0.000	0.000
data read	00:00:20.367	0.0%	995314	0.013	0.020	0.029
data write	00:03:30.528	0.2%	1089007	0.057	0.193	0.520
idle	23:39:34.080	99.2%	2021665	17.565	42.131	268.696
status file	00:03:44.829	0.3%	17116	0.706	13.136	268.452
TOTAL: 23:50:33.705	23:49:25.287	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:18:39.908	98.3%	3077	0.000	363.961	46435.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.688	0.3%	6800	0.000	0.542	72.979
readart	00:00:00.769	0.1%	30	0.000	25.633	53.750
TOTAL: 00:18:58.858	00:18:44.365	98.7%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	467	2303020	2295717	4457	2846	99%	529:24:03
2	news.nask.pl	120	138810	24445	106976	7389	17%	45:29:08
3	news.icm.edu.pl	49	300	160	103	37	53%	18:05:31
4	localhost	5	4	4	0	0	100%	00:00:02
TOTAL: 4		641	2442134	2320326	111536	10272	95%	592:58:44

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	588.9 GB	309.4 MB	311.6 MB	589.5 GB	99%	268.9 KB
2	news.nask.pl	6.8 GB	1.4 GB	1.4 GB	9.6 GB	70%	317.8 KB
3	news.icm.edu.pl	3.8 MB	74.5 KB	1.5 MB	5.4 MB	71%	27.9 KB
4	localhost	6.8 KB	0.0 KB	0.0 KB	6.8 KB	100%	1.7 KB
TOTAL: 4		595.6 GB	1.7 GB	1.7 GB	599.1 GB	99%	269.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 3 04:16:10 - 04:59:59	15225	0.7%	5.79	4.2 GB	0.7%	1658.46
Dec 3 05:00:00 - 05:59:59	71690	3.1%	19.91	21.8 GB	3.7%	6352.03
Dec 3 06:00:00 - 06:59:59	91140	3.9%	25.32	29.5 GB	5.0%	8592.41
Dec 3 07:00:00 - 07:59:59	105268	4.5%	29.24	23.2 GB	3.9%	6758.49
Dec 3 08:00:00 - 08:59:59	72974	3.1%	20.27	23.4 GB	3.9%	6828.18
Dec 3 09:00:00 - 09:59:59	96438	4.2%	26.79	33.5 GB	5.6%	9745.16
Dec 3 10:00:00 - 10:59:59	124992	5.4%	34.72	19.7 GB	3.3%	5751.70
Dec 3 11:00:00 - 11:59:59	82952	3.6%	23.04	26.4 GB	4.4%	7678.29
Dec 3 12:00:00 - 12:59:59	87231	3.8%	24.23	26.2 GB	4.4%	7644.11
Dec 3 13:00:00 - 13:59:59	90190	3.9%	25.05	28.9 GB	4.8%	8411.25
Dec 3 14:00:00 - 14:59:59	105226	4.5%	29.23	36.9 GB	6.2%	10738.56
Dec 3 15:00:00 - 15:59:59	96783	4.2%	26.88	31.3 GB	5.2%	9106.92
Dec 3 16:00:00 - 16:59:59	69296	3.0%	19.25	21.7 GB	3.6%	6332.75
Dec 3 17:00:00 - 17:59:59	65514	2.8%	18.20	19.8 GB	3.3%	5758.41
Dec 3 18:00:00 - 18:59:59	106319	4.6%	29.53	31.9 GB	5.3%	9277.85
Dec 3 19:00:00 - 19:59:59	71112	3.1%	19.75	20.7 GB	3.5%	6034.07
Dec 3 20:00:00 - 20:59:59	91762	4.0%	25.49	26.4 GB	4.4%	7686.74
Dec 3 21:00:00 - 21:59:59	114917	5.0%	31.92	17.4 GB	2.9%	5073.09
Dec 3 22:00:00 - 22:59:59	164872	7.1%	45.80	19.7 GB	3.3%	5741.97
Dec 3 23:00:00 - 23:59:59	143928	6.2%	39.98	23.8 GB	4.0%	6936.41
Dec 4 00:00:00 - 00:59:59	74296	3.2%	20.64	19.8 GB	3.3%	5771.75
Dec 4 01:00:00 - 01:59:59	153289	6.6%	42.58	24.2 GB	4.1%	7061.60
Dec 4 02:00:00 - 02:59:59	112386	4.8%	31.22	28.6 GB	4.8%	8318.77
Dec 4 03:00:00 - 03:59:59	88764	3.8%	24.66	28.9 GB	4.8%	8416.80
Dec 4 04:00:00 - 04:15:03	24035	1.0%	26.62	8.0 GB	1.3%	9277.40
TOTAL: 23:58:53	2320599	100.0%	26.88	595.9 GB	100.0%	7237.11

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	7572	7	4928	3	2634
2	newsfeed.pionier.net.pl	3149	293	2491	6	359
3	news.icm.edu.pl	41	0	8	0	33
TOTAL: 3		10762	300	7427	9	3026

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	209
it.comp.os.win.windows10	49
it.tlc.cellulari.android	15
it.comp.os.win.windows7	14
comp.mobile.android	4
de.rec.sf.startrek	3
it.comp.os.linux.ubuntu	3
de.comp.lang.vbclassic	2
comp.sys.raspberry-pi	1
TOTAL: 9	300

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

Groups	Count
pl.sci.matematyka	6
pl.praca.oferowana	2
bit.listserv.catholic	1
TOTAL: 3	9

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2282
Binary in non-binary group	109
Scoring filter (16)	40
EMP (phl)	33
Scoring filter (8)	22
Scoring filter (9)	9
Scoring filter (10)	8
Excessively crossposted test article	7
Too many newsgroups	6
Scoring filter (11)	5
HTML post	5
Scoring filter (18)	2
Scoring filter (17)	1
Too many test groups in crosspost	1
Attached HTML file	1
Scoring filter (12)	1
Scoring filter (13)	1
TOTAL: 17	2533

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	1
	TOTAL: 1	1
Bad command received
	news.nask.pl	730
	TOTAL: 1	730
Including strange strings
	newsfeed.pionier.net.pl	302
	news.nask.pl	143
	news.icm.edu.pl	4
	TOTAL: 3	449
TOTAL: 3		1180

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	nask	307227	111133	119681	325	0	5437	36%	23:59:24
2	panorama	273534	1594	243954	355	4	274186	0%	23:59:18
3	poznan	8566	102	6333	40	0	0	1%	22:53:11
4	icm	274429	9	253642	7707	0	120	0%	23:59:26
5	okapi	0	0	0	0	0	1244	0%	23:52:48
TOTAL: 5		863756	112838	623610	8427	4	280987	13%	118:44:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	41.3 GB	121.7 MB	41.4 GB	502.6 KB/s	389.5 KB	23:59:24
2	panorama	193.8 MB	5.7 MB	199.6 MB	2.4 KB/s	104.8 KB	23:59:18
3	icm	467.4 KB	2.9 GB	2.9 GB	34.8 KB/s	389.0 KB	23:59:26
4	poznan	436.8 KB	126.0 KB	562.8 KB	0.0 KB/s	4.0 KB	22:53:11
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:52:48
TOTAL: 5		41.5 GB	3.0 GB	44.5 GB	109.1 KB/s	384.4 KB	118:44:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	185.92.251.157	4	18	47.5 KB	5	0	00:18:22
2	web.man.lodz.pl	288	12	9.0 KB	5	0	00:00:27
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:01
TOTAL: 3		296	30	56.5 KB	10	4	00:18:58

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	unresolved	100	18	47.5 KB	5	0	00:18:24
2	*.man.lodz.pl	580	12	9.0 KB	5	4	00:00:32
3	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:01
TOTAL: 3		681	30	56.5 KB	10	4	00:18:58

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.92.251.157	0.080	0.124	00:18:22
web.man.lodz.pl	1.440	5.572	00:00:27
mon.man.lodz.pl	1.304	5.652	00:00:04
isvcs01.man.lodz.pl	0.028	0.068	00:00:01
212.51.192.185	0.444	1.784	00:00:01
scan-141.security.ipip.net	0.004	0.028	00:00:01
TOTAL: 6	3.300	13.228	00:18:58

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
scan-141.security.ipip.net	1
TOTAL: 3	385

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	12	40.0%
2	pl	12	40.0%
3	lodz	5	16.7%
4	lodzman	1	3.3%
TOTAL: 4		30	100.0%

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

Newsgroup		Count
1	lodman.info	12
2	pl.comp.os.ms-windows.winnt	6
3	pl.comp.pecet	6
4	lodz.test	5
5	lodzman.test	1
TOTAL: 5		30

Daily Usenet report

Dec 3 04:16:10 -- Dec 4 04:15:03