Daily Usenet report: Mar 16 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Mar 16 14:59:36 newsfeed nnrpd[30133]: SERVER perl filter not defined
Mar 16 21:25:10 newsfeed overchan[7757]: input line too long, skipping
Mar 17 01:51:41 newsfeed nnrpd[19503]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2227777	97.6%	253.3 MB	97.3%
innfeed	33647	1.5%	3.9 MB	1.5%
innd	15632	0.7%	2.5 MB	1.0%
nnrpd	4985	0.2%	539.0 KB	0.2%
overchan	2	0.0%	0.4 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2282044	100.0%	260.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2219080	98.9%
Negative hits	15266	0.7%
Positive hits	9362	0.4%
Cache misses	49	0.0%
TOTAL: 4	2243757	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.522	0.0%	140	0.000	10.871	272.000
article cleanup	00:00:25.259	0.0%	2226457	0.008	0.011	0.017
article logging	00:01:19.133	0.1%	2226870	0.025	0.036	0.050
article parse	00:30:17.632	2.1%	82837592	0.013	0.022	0.040
article write	00:11:44.540	0.8%	2222206	0.163	0.317	0.572
artlog/artcncl	00:00:00.005	0.0%	140	0.000	0.036	1.000
data move	00:01:13.112	0.1%	94995022	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.152	0.0%	133	0.000	1.143	22.000
hishave/artcncl	00:00:00.031	0.0%	140	0.000	0.221	2.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:26:46.596	1.9%	2248595	0.022	0.714	6.966
history sync	00:00:00.016	0.0%	291	0.000	0.055	1.000
history write	00:52:54.204	3.7%	2223574	0.115	1.428	8.280
hiswrite/artcncl	00:00:00.010	0.0%	7	0.000	1.429	9.000
idle	17:38:48.533	73.5%	67005160	0.582	0.948	2.360
nntp read	00:49:32.748	3.4%	80932090	0.025	0.037	0.063
overview write	00:00:09.649	0.0%	2222206	0.003	0.004	0.007
perl filter	03:00:29.045	12.5%	2223574	3.554	4.870	7.030
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:43.082	0.3%	4458727	0.034	0.050	0.068
TOTAL: 24:00:02.221	23:37:25.269	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.076	0.0%	42685	0.000	0.002	0.015
article prepare	00:00:00.000	0.0%	3422	0.000	0.000	0.000
article read	00:00:12.910	0.0%	850	0.000	15.188	169.143
backlog stats	00:00:00.114	0.0%	125499	0.000	0.001	0.008
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.983	0.0%	65521	0.002	0.015	0.046
data write	00:00:02.716	0.0%	51513	0.023	0.053	0.196
idle	23:53:54.156	99.9%	125499	353.163	685.537	1248.424
status file	00:00:16.728	0.0%	12903	0.467	1.296	61.377
TOTAL: 23:54:39.558	23:54:27.683	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:32:24.664	99.3%	3142	0.000	618.926	76547.625
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.716	0.0%	6960	0.000	0.103	6.039
readart	00:00:00.135	0.0%	29	0.000	4.655	19.000
TOTAL: 00:32:39.016	00:32:25.515	99.3%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	622	2222705	2221707	28	970	99%	213:33:24
2	news.nask.pl	2	10402	1038	6154	3210	9%	23:44:47
3	news.icm.edu.pl	38	229	38	168	23	16%	15:11:30
4	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 4		665	2233338	2222785	6350	4203	99%	252:29:41

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	556.7 GB	10.1 MB	10.5 MB	556.7 GB	99%	262.6 KB
2	news.nask.pl	10.2 MB	9.4 MB	22.5 MB	42.1 MB	24%	10.2 KB
3	news.icm.edu.pl	776.2 KB	19.5 KB	1.1 MB	1.8 MB	41%	30.9 KB
4	localhost	2.6 KB	0.0 KB	0.0 KB	2.6 KB	100%	1.3 KB
TOTAL: 4		556.7 GB	19.6 MB	34.0 MB	556.8 GB	99%	262.1 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 16 04:15:02 - 04:59:59	59159	2.7%	21.93	15.7 GB	2.8%	6096.68
Mar 16 05:00:00 - 05:59:59	93263	4.2%	25.91	23.7 GB	4.3%	6907.78
Mar 16 06:00:00 - 06:59:59	85710	3.9%	23.81	23.7 GB	4.3%	6911.46
Mar 16 07:00:00 - 07:59:59	91913	4.1%	25.53	22.8 GB	4.1%	6630.17
Mar 16 08:00:00 - 08:59:59	109920	4.9%	30.53	23.3 GB	4.2%	6779.16
Mar 16 09:00:00 - 09:59:59	91941	4.1%	25.54	23.1 GB	4.2%	6739.77
Mar 16 10:00:00 - 10:59:59	74506	3.4%	20.70	17.6 GB	3.2%	5120.21
Mar 16 11:00:00 - 11:59:59	48803	2.2%	13.56	13.1 GB	2.4%	3827.80
Mar 16 12:00:00 - 12:59:59	77851	3.5%	21.63	21.3 GB	3.8%	6203.65
Mar 16 13:00:00 - 13:59:59	95672	4.3%	26.58	24.3 GB	4.4%	7080.61
Mar 16 14:00:00 - 14:59:59	101915	4.6%	28.31	27.3 GB	4.9%	7937.23
Mar 16 15:00:00 - 15:59:59	108595	4.9%	30.17	27.4 GB	4.9%	7973.94
Mar 16 16:00:00 - 16:59:59	93606	4.2%	26.00	28.1 GB	5.0%	8173.85
Mar 16 17:00:00 - 17:59:59	100487	4.5%	27.91	25.7 GB	4.6%	7485.27
Mar 16 18:00:00 - 18:59:59	128127	5.8%	35.59	24.5 GB	4.4%	7145.95
Mar 16 19:00:00 - 19:59:59	110001	4.9%	30.56	24.6 GB	4.4%	7164.02
Mar 16 20:00:00 - 20:59:59	120953	5.4%	33.60	26.1 GB	4.7%	7615.98
Mar 16 21:00:00 - 21:59:59	97874	4.4%	27.19	27.7 GB	5.0%	8054.34
Mar 16 22:00:00 - 22:59:59	116184	5.2%	32.27	27.3 GB	4.9%	7939.83
Mar 16 23:00:00 - 23:59:59	103285	4.6%	28.69	28.2 GB	5.1%	8214.06
Mar 17 00:00:00 - 00:59:59	73712	3.3%	20.48	19.1 GB	3.4%	5574.53
Mar 17 01:00:00 - 01:59:59	87215	3.9%	24.23	21.2 GB	3.8%	6169.70
Mar 17 02:00:00 - 02:59:59	58715	2.6%	16.31	16.5 GB	3.0%	4796.27
Mar 17 03:00:00 - 03:59:59	74498	3.4%	20.69	18.8 GB	3.4%	5469.16
Mar 17 04:00:00 - 04:15:02	19062	0.9%	21.13	5.0 GB	0.9%	5851.19
TOTAL: 24:00:00	2222967	100.0%	25.73	556.0 GB	100.0%	6747.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3267	0	2033	1	1233
2	newsfeed.pionier.net.pl	1380	120	847	1	412
3	news.icm.edu.pl	23	0	2	0	21
TOTAL: 3		4670	120	2882	2	1666

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	49
comp.mobile.android	16
it.comp.os.win.windows10	15
de.comp.office-pakete.ms-office.outlook	14
it.tlc.cellulari.android	10
nl.comp.os.linux.techniek	6
it.comp.os.win.windows7	4
uk.rec.cycling.moderated	3
de.comm.software.mailreader	2
de.comp.os.os2	1
TOTAL: 10	120

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1052
Binary in non-binary group	105
Scoring filter (8)	40
EMP (phl)	33
Scoring filter (10)	11
Too many newsgroups	5
Scoring filter (18)	2
Scoring filter (9)	2
Scoring filter (14)	2
Scoring filter (12)	1
TOTAL: 10	1253

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	409
	news.nask.pl	4
	TOTAL: 2	413
TOTAL: 1		413

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	32753	239	11049	60	5	31452	0%	23:59:30
2	icm	21872	83	13853	159	0	0	0%	23:51:51
3	poznan	1564	9	1063	0	0	0	0%	23:46:19
4	nask	9554	2	8239	1	0	0	0%	23:58:45
5	okapi	0	0	0	0	0	620	0%	23:50:01
TOTAL: 5		65743	333	34204	220	5	32072	0%	119:26:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	746.3 KB	221.3 KB	967.6 KB	0.0 KB/s	3.2 KB	23:59:30
2	icm	685.0 KB	43.3 MB	43.9 MB	0.5 KB/s	185.9 KB	23:51:51
3	poznan	27.8 KB	0.0 KB	27.8 KB	0.0 KB/s	3.1 KB	23:46:19
4	nask	5.2 KB	1.6 KB	6.8 KB	0.0 KB/s	2.3 KB	23:58:45
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		1.4 MB	43.5 MB	44.9 MB	0.1 KB/s	83.1 KB	119:26:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	20	20.5 KB	9	0	00:00:19
2	212.51.192.171	11	8	270.4 KB	7	0	00:31:48
3	az1-ss13.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
4	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 4		303	29	291.7 KB	17	2	00:32:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	20	20.5 KB	9	2	00:00:22
2	unresolved	117	8	270.4 KB	7	0	00:32:03
3	*.a2hosting.com	2	1	0.7 KB	1	0	00:00:12
TOTAL: 3		697	29	291.7 KB	17	2	00:32:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.51.192.171	0.092	0.140	00:31:48
web.man.lodz.pl	1.168	3.048	00:00:19
az1-ss13.a2hosting.com	0.008	0.032	00:00:12
107.170.251.213	0.004	0.016	00:00:09
80.111.142.213	0.032	0.104	00:00:04
mon.man.lodz.pl	0.992	2.604	00:00:03
212.51.192.185	0.320	0.900	00:00:01
isvcs01.man.lodz.pl	0.012	0.036	00:00:00
212.28.71.227	0.004	0.004	00:00:00
TOTAL: 9	2.632	6.884	00:32:38

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	8
107.170.251.213	1
212.28.71.227	1
TOTAL: 5	394

NNRP unrecognized commands (by host):

System	Conn
212.28.71.227	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
User-Agent: Opera/9.80 (X11; Linux x86_6...	1
GET / HTTP/1.0	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
212.51.192.171	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	26	89.7%
2	lodzman	1	3.4%
3	lodman	1	3.4%
4	alt	1	3.4%
TOTAL: 4		29	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	21
2	pl.rec.mineraly	2
3	pl.rec.kino-domowe	1
4	pl.internet.polip	1
5	alt.binaries.pictures.erotica.amateur	1
6	lodman.info	1
7	pl.rec.foto	1
8	lodzman.test	1
TOTAL: 8		29

Daily Usenet report

Mar 16 04:15:02 -- Mar 17 04:15:02