Daily Usenet report: Dec 18 04:15:02

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Dec 18 15:59:28 newsfeed nnrpd[5824]: SERVER perl filter not defined
Dec 18 16:01:15 newsfeed nnrpd[5913]: SERVER perl filter not defined
Dec 18 16:01:47 newsfeed nnrpd[5914]: SERVER perl filter not defined
Dec 18 16:02:19 newsfeed nnrpd[5949]: SERVER perl filter not defined
Dec 18 20:22:53 newsfeed nnrpd[15776]: SERVER perl filter not defined
Dec 19 00:01:19 newsfeed nnrpd[24192]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2299618	97.3%	267.5 MB	97.0%
innfeed	44339	1.9%	5.3 MB	1.9%
innd	14312	0.6%	2.4 MB	0.9%
nnrpd	4878	0.2%	514.2 KB	0.2%
overchan	1	0.0%	0.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2363149	100.0%	275.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2283369	94.8%
Positive hits	76117	3.2%
Negative hits	46722	1.9%
Cache misses	1536	0.1%
TOTAL: 4	2407744	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.220	0.0%	67	0.000	48.060	131.000
article cleanup	00:00:30.121	0.0%	2298385	0.009	0.013	0.018
article logging	00:01:42.218	0.1%	2298940	0.036	0.044	0.061
article parse	00:44:15.501	3.1%	111786540	0.012	0.024	0.045
article write	00:17:08.185	1.2%	2289148	0.291	0.449	0.826
artlog/artcncl	00:00:00.003	0.0%	66	0.000	0.045	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:03.552	0.1%	125583481	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.708	0.0%	65	0.000	10.892	37.000
hishave/artcncl	00:00:00.147	0.0%	67	0.000	2.194	13.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:41:33.471	7.1%	2419293	0.982	2.519	5.159
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	04:16:10.155	17.8%	2290586	3.229	6.710	11.980
hiswrite/artcncl	00:00:00.001	0.0%	2	0.000	0.500	1.000
idle	11:13:58.164	46.8%	80785798	0.293	0.501	1.081
nntp read	01:09:21.967	4.8%	109930480	0.026	0.038	0.057
overview write	00:00:11.915	0.0%	2289148	0.004	0.005	0.008
perl filter	04:00:52.369	16.7%	2290577	4.230	6.309	9.584
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:05.270	0.4%	4731753	0.045	0.065	0.090
TOTAL: 24:00:03.961	23:32:56.987	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.992	0.0%	307716	0.000	0.003	0.010
article prepare	00:00:00.066	0.0%	221860	0.000	0.000	0.042
article read	00:03:03.434	0.2%	55444	0.000	3.308	101.000
backlog stats	00:00:00.861	0.0%	844620	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:08.956	0.0%	427609	0.005	0.021	0.046
data write	00:01:29.928	0.1%	420559	0.032	0.214	0.378
idle	23:47:14.558	99.6%	844620	9.275	101.388	1017.223
status file	00:00:21.443	0.0%	16081	0.609	1.333	17.000
TOTAL: 23:52:52.257	23:52:20.238	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:16.146	57.1%	3050	0.000	5.294	135.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.263	0.9%	6703	0.000	0.039	0.789
readart	00:00:00.002	0.0%	26	0.000	0.077	0.500
TOTAL: 00:00:28.255	00:00:16.411	58.1%	-	-	-	-

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	194	2275655	2272218	716	2721	99%	262:02:15
2	news.nask.pl	8	91397	17726	67172	6499	19%	26:00:52
3	news.icm.edu.pl	31	54	7	47	0	12%	08:06:36
4	localhost	7	6	6	0	0	100%	00:00:00
TOTAL: 4		240	2367112	2289957	67935	9220	96%	296:09:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	714.4 GB	67.1 MB	69.3 MB	714.6 GB	99%	329.4 KB
2	news.nask.pl	5.4 GB	1.2 GB	1.2 GB	7.8 GB	69%	336.2 KB
3	news.icm.edu.pl	121.3 KB	0.0 KB	0.0 KB	121.3 KB	100%	17.3 KB
4	localhost	8.9 KB	0.0 KB	0.0 KB	8.9 KB	100%	1.5 KB
TOTAL: 4		719.8 GB	1.2 GB	1.2 GB	722.3 GB	99%	329.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 18 04:15:02 - 04:59:59	52558	2.3%	19.48	19.2 GB	2.7%	7456.78
Dec 18 05:00:00 - 05:59:59	72660	3.2%	20.18	25.6 GB	3.6%	7449.43
Dec 18 06:00:00 - 06:59:59	85566	3.7%	23.77	31.0 GB	4.3%	9033.19
Dec 18 07:00:00 - 07:59:59	76485	3.3%	21.25	26.0 GB	3.6%	7570.22
Dec 18 08:00:00 - 08:59:59	72310	3.2%	20.09	23.4 GB	3.2%	6806.36
Dec 18 09:00:00 - 09:59:59	83719	3.7%	23.26	27.5 GB	3.8%	8014.88
Dec 18 10:00:00 - 10:59:59	67029	2.9%	18.62	24.6 GB	3.4%	7171.08
Dec 18 11:00:00 - 11:59:59	78054	3.4%	21.68	26.8 GB	3.7%	7804.21
Dec 18 12:00:00 - 12:59:59	95174	4.2%	26.44	30.7 GB	4.3%	8939.83
Dec 18 13:00:00 - 13:59:59	104459	4.6%	29.02	36.2 GB	5.0%	10539.32
Dec 18 14:00:00 - 14:59:59	110579	4.8%	30.72	38.4 GB	5.3%	11180.88
Dec 18 15:00:00 - 15:59:59	138148	6.0%	38.37	36.6 GB	5.1%	10648.74
Dec 18 16:00:00 - 16:59:59	124953	5.5%	34.71	34.4 GB	4.8%	10030.63
Dec 18 17:00:00 - 17:59:59	113001	4.9%	31.39	29.2 GB	4.1%	8516.18
Dec 18 18:00:00 - 18:59:59	109597	4.8%	30.44	32.6 GB	4.5%	9486.68
Dec 18 19:00:00 - 19:59:59	114849	5.0%	31.90	36.1 GB	5.0%	10511.74
Dec 18 20:00:00 - 20:59:59	121282	5.3%	33.69	34.9 GB	4.8%	10156.07
Dec 18 21:00:00 - 21:59:59	121687	5.3%	33.80	35.4 GB	4.9%	10296.99
Dec 18 22:00:00 - 22:59:59	125726	5.5%	34.92	37.1 GB	5.2%	10802.26
Dec 18 23:00:00 - 23:59:59	112462	4.9%	31.24	37.0 GB	5.1%	10774.04
Dec 19 00:00:00 - 00:59:59	81857	3.6%	22.74	23.9 GB	3.3%	6967.13
Dec 19 01:00:00 - 01:59:59	67547	2.9%	18.76	22.4 GB	3.1%	6528.60
Dec 19 02:00:00 - 02:59:59	74267	3.2%	20.63	20.9 GB	2.9%	6082.73
Dec 19 03:00:00 - 03:59:59	68175	3.0%	18.94	23.3 GB	3.2%	6800.20
Dec 19 04:00:00 - 04:15:02	17606	0.8%	19.52	6.3 GB	0.9%	7352.12
TOTAL: 24:00:00	2289750	100.0%	26.50	719.4 GB	100.0%	8730.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	6599	4	5380	6	1209
2	newsfeed.pionier.net.pl	3201	223	2428	6	544
3	news.icm.edu.pl	2	0	0	0	2
TOTAL: 3		9802	227	7808	12	1755

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	128
it.comp.os.win.windows10	44
it.tlc.cellulari.android	22
nl.comp.os.linux.techniek	11
comp.mobile.android	10
it.comp.os.win.windows7	4
de.comp.lang.vbclassic	3
uk.rec.cycling.moderated	2
it.comp.os.win.windows8	1
it.tlc.cellulari.iphone	1
it.comp.os.linux.ubuntu	1
TOTAL: 11	227

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

Groups	Count
pl.sci.matematyka	7
bit.listserv.catholic	3
pl.praca.oferowana	1
geometry.college	1
TOTAL: 4	12

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	978
Binary in non-binary group	83
EMP (phl)	59
Scoring filter (16)	22
Scoring filter (8)	20
Scoring filter (9)	10
Too many newsgroups	8
Scoring filter (10)	6
Scoring filter (18)	3
Scoring filter (13)	1
HTML post	1
TOTAL: 11	1191

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Article posted in the future
	newsfeed.pionier.net.pl	7
	TOTAL: 1	7
Including strange strings
	newsfeed.pionier.net.pl	480
	news.nask.pl	73
	news.icm.edu.pl	2
	TOTAL: 3	555
TOTAL: 2		562

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	nask	175283	52433	76404	1100	7569	29%	23:59:57
2	panorama	170155	1059	141719	856	158376	0%	23:59:57
3	poznan	4508	60	3119	30	0	1%	23:16:14
4	icm	10041	9	7887	363	145795	0%	23:59:57
5	okapi	0	0	0	0	1001	0%	23:57:50
TOTAL: 5		359987	53561	229129	2349	312741	14%	119:13:55

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	nask	19.5 GB	269.0 MB	19.7 GB	239.4 KB/s	386.4 KB	23:59:57
2	panorama	8.2 MB	4.3 MB	12.5 MB	0.1 KB/s	6.7 KB	23:59:57
3	icm	489.2 KB	135.5 MB	136.0 MB	1.6 KB/s	374.4 KB	23:59:57
4	poznan	182.3 KB	52.3 KB	234.6 KB	0.0 KB/s	2.6 KB	23:16:14
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		19.5 GB	408.9 MB	19.9 GB	48.5 KB/s	372.7 KB	119:13:55

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
TOTAL: 1	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	26	46.4 KB	11	0	00:00:20
2	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 2		294	26	46.4 KB	11	6	00:00:27

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	582	26	46.4 KB	11	6	00:00:25
2	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
3	unresolved	96	0	0.0 KB	0	0	00:00:01
TOTAL: 3		679	26	46.4 KB	11	6	00:00:27

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.216	4.480	00:00:20
mon.man.lodz.pl	1.236	4.984	00:00:03
isvcs01.man.lodz.pl	0.020	0.120	00:00:01
212.51.192.185	0.400	1.588	00:00:01
scan-141.security.ipip.net	0.008	0.004	00:00:00
TOTAL: 5	2.880	11.176	00:00:27

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
GET / HTTP/1.1	1
Host: 212.191.2.21:119	1
Connection: close	1
User-Agent: HTTP Banner Detection (https...	1
TOTAL: 4	4

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	26	100.0%
TOTAL: 1		26	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	22
2	pl.internet.polip	2
3	pl.comp.os.ms-windows.winnt	2
TOTAL: 3		26

Daily Usenet report

Dec 18 04:15:02 -- Dec 19 04:15:02