Daily Usenet report: Dec 10 04:15:02

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Dec 10 04:15:02 newsfeed nnrpd[29037]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 10 04:15:02 newsfeed nnrpd[29042]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 10 10:12:24 newsfeed nnrpd[9507]: SERVER perl filter not defined
Dec 10 10:15:05 newsfeed nnrpd[9604]: SERVER perl filter not defined
Dec 10 10:57:15 newsfeed nnrpd[11182]: SERVER perl filter not defined
Dec 10 10:57:56 newsfeed nnrpd[11183]: SERVER perl filter not defined
Dec 10 11:08:49 newsfeed nnrpd[11598]: SERVER perl filter not defined
Dec 10 12:08:58 newsfeed nnrpd[13687]: SERVER perl filter not defined
Dec 10 12:16:27 newsfeed nnrpd[13838]: SERVER perl filter not defined
Dec 10 13:35:42 newsfeed nnrpd[16615]: SERVER perl filter not defined
Dec 10 15:19:02 newsfeed nnrpd[20302]: SERVER perl filter not defined
Dec 10 16:47:05 newsfeed nnrpd[23231]: SERVER perl filter not defined
Dec 10 16:47:24 newsfeed nnrpd[23379]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2755062	97.3%	318.6 MB	96.9%
innfeed	46714	1.7%	5.6 MB	1.7%
innd	22223	0.8%	3.8 MB	1.2%
nnrpd	6646	0.2%	735.6 KB	0.2%
TOTAL: 4	2830645	100.0%	328.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2748599	98.4%
Negative hits	28191	1.0%
Positive hits	16832	0.6%
Cache misses	112	0.0%
TOTAL: 4	2793734	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.150	0.0%	256	0.000	16.211	186.500
article cleanup	00:00:45.255	0.1%	2753059	0.007	0.016	0.029
article logging	00:01:19.290	0.1%	2753784	0.015	0.029	0.537
article parse	01:23:06.617	5.8%	260777988	0.007	0.019	0.090
article write	00:31:40.272	2.2%	2748678	0.315	0.691	3.054
artlog/artcncl	00:00:00.004	0.0%	248	0.000	0.016	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:05:22.473	0.4%	278536141	0.000	0.001	0.010
hisgrep/artcncl	00:00:00.261	0.0%	220	0.000	1.186	15.000
hishave/artcncl	00:00:00.063	0.0%	256	0.000	0.246	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:43:10.041	3.0%	2794264	0.129	0.927	16.658
history sync	00:00:00.019	0.0%	291	0.000	0.065	1.000
history write	00:56:22.628	3.9%	2749274	0.191	1.230	32.788
hiswrite/artcncl	00:00:00.047	0.0%	36	0.000	1.306	19.000
idle	12:02:47.075	50.2%	173906454	0.105	0.249	0.377
nntp read	01:32:28.185	6.4%	258572429	0.009	0.021	0.046
overview write	00:00:16.296	0.0%	2748678	0.001	0.006	0.014
perl filter	05:35:18.820	23.3%	2749274	4.568	7.318	10.913
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:17.258	0.4%	5557404	0.016	0.057	0.091
TOTAL: 23:59:17.647	22:57:58.754	95.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.367	0.0%	117447	0.000	0.003	0.143
article prepare	00:00:00.012	0.0%	46686	0.000	0.000	0.083
article read	00:01:22.692	0.1%	11669	0.000	7.086	1158.000
backlog stats	00:00:00.212	0.0%	274916	0.000	0.001	0.025
callbacks	00:00:00.000	0.0%	10	0.000	0.000	0.000
data read	00:00:01.770	0.0%	154471	0.000	0.011	0.094
data write	00:00:08.763	0.0%	102147	0.000	0.086	1.274
idle	23:57:39.123	99.9%	274916	26.684	313.765	1289.929
status file	00:00:13.269	0.0%	16049	0.143	0.827	74.800
TOTAL: 23:59:38.434	23:59:26.208	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.409	0.0%	5812	0.070	0.070	0.070
idle	10:46:59.527	99.9%	257385	0.000	150.823	10049.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.190	0.0%	516212	0.000	0.018	0.667
readart	00:00:08.566	0.0%	5645	0.000	1.517	91.000
TOTAL: 10:47:54.088	10:47:17.692	99.9%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1214	2750097	2746131	409	3557	99%	353:54:45
2	news.nask.pl	2	16671	3805	12083	783	22%	23:17:55
3	news.icm.edu.pl	40	308	75	194	39	24%	17:54:25
4	localhost	12	11	11	0	0	100%	00:00:00
TOTAL: 4		1268	2767087	2750022	12686	4379	99%	395:07:05

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	837.4 GB	12.9 MB	34.6 MB	837.4 GB	99%	319.3 KB
2	news.nask.pl	12.3 MB	2.2 MB	2.3 MB	16.9 MB	73%	3.8 KB
3	news.icm.edu.pl	2.9 MB	54.1 KB	1.5 MB	4.5 MB	64%	40.0 KB
4	localhost	19.8 KB	0.0 KB	0.0 KB	19.8 KB	100%	1.8 KB
TOTAL: 4		837.4 GB	15.2 MB	38.5 MB	837.4 GB	99%	318.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 10 04:15:02 - 04:59:59	76809	2.8%	28.47	25.3 GB	3.0%	9832.64
Dec 10 05:00:00 - 05:59:59	103278	3.8%	28.69	34.9 GB	4.2%	10156.87
Dec 10 06:00:00 - 06:59:59	115293	4.2%	32.03	36.2 GB	4.3%	10536.30
Dec 10 07:00:00 - 07:59:59	117462	4.3%	32.63	36.6 GB	4.4%	10658.08
Dec 10 08:00:00 - 08:59:59	124874	4.5%	34.69	37.3 GB	4.5%	10875.06
Dec 10 09:00:00 - 09:59:59	108805	4.0%	30.22	34.3 GB	4.1%	9992.65
Dec 10 10:00:00 - 10:59:59	118037	4.3%	32.79	35.9 GB	4.3%	10462.00
Dec 10 11:00:00 - 11:59:59	123493	4.5%	34.30	36.8 GB	4.4%	10725.31
Dec 10 12:00:00 - 12:59:59	121996	4.4%	33.89	36.0 GB	4.3%	10492.71
Dec 10 13:00:00 - 13:59:59	121092	4.4%	33.64	34.8 GB	4.2%	10123.95
Dec 10 14:00:00 - 14:59:59	119182	4.3%	33.11	31.3 GB	3.7%	9102.88
Dec 10 15:00:00 - 15:59:59	129669	4.7%	36.02	35.2 GB	4.2%	10254.47
Dec 10 16:00:00 - 16:59:59	121350	4.4%	33.71	36.1 GB	4.3%	10507.13
Dec 10 17:00:00 - 17:59:59	112100	4.1%	31.14	35.0 GB	4.2%	10200.00
Dec 10 18:00:00 - 18:59:59	103149	3.8%	28.65	33.8 GB	4.0%	9851.72
Dec 10 19:00:00 - 19:59:59	117863	4.3%	32.74	35.3 GB	4.2%	10270.26
Dec 10 20:00:00 - 20:59:59	109033	4.0%	30.29	32.6 GB	3.9%	9481.06
Dec 10 21:00:00 - 21:59:59	101034	3.7%	28.07	32.6 GB	3.9%	9494.61
Dec 10 22:00:00 - 22:59:59	117109	4.3%	32.53	35.9 GB	4.3%	10464.63
Dec 10 23:00:00 - 23:59:59	111180	4.0%	30.88	35.9 GB	4.3%	10458.63
Dec 11 00:00:00 - 00:59:59	84486	3.1%	23.47	29.3 GB	3.5%	8522.63
Dec 11 01:00:00 - 01:59:59	114383	4.2%	31.77	37.2 GB	4.4%	10834.80
Dec 11 02:00:00 - 02:59:59	134406	4.9%	37.34	37.8 GB	4.5%	11012.47
Dec 11 03:00:00 - 03:59:59	121721	4.4%	33.81	34.2 GB	4.1%	9964.74
Dec 11 04:00:00 - 04:15:03	21903	0.8%	24.26	6.3 GB	0.8%	7285.39
TOTAL: 24:00:01	2749707	100.0%	31.82	836.5 GB	100.0%	10151.52

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4206	283	3012	3	908
2	news.nask.pl	861	6	770	3	82
3	news.icm.edu.pl	40	0	3	0	37
TOTAL: 3		5107	289	3785	6	1027

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	122
it.comp.os.win.windows10	72
it.tlc.cellulari.android	32
comp.mobile.android	14
it.comp.os.linux.ubuntu	12
it.comp.os.win.windows7	8
de.comp.lang.vbclassic	7
de.comp.lang.php	7
uk.rec.cycling.moderated	5
de.comp.office-pakete.ms-office.word	3
hr.ponuda.hardver.konfiguracije	2
alt.sex.telephones	2
de.comp.office-pakete.ms-office.excel	2
it.comp.os.win.windows8	1
TOTAL: 14	289

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	246
EMP (md5)	33
Angle-bracket bot	13
HTML post	8
Attached HTML file	1
Scoring filter (8)	1
TOTAL: 6	302

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	46948	1981	24650	6752	0	452	4%	23:59:16
2	panorama	66046	1331	37551	562	22	77545	2%	24:00:01
3	poznan	5599	101	3668	54	1	0	1%	23:59:33
4	okapi	0	0	0	0	0	2662	0%	24:00:01
5	nask	0	0	0	0	0	13428	0%	24:00:01
TOTAL: 5		118593	3413	65869	7368	23	94087	2%	119:58:52

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	15.1 MB	1.9 GB	2.0 GB	23.8 KB/s	235.3 KB	23:59:16
2	panorama	4.7 MB	4.2 MB	8.9 MB	0.1 KB/s	4.8 KB	24:00:01
3	poznan	336.3 KB	147.2 KB	483.6 KB	0.0 KB/s	3.1 KB	23:59:33
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		20.1 MB	1.9 GB	2.0 GB	4.8 KB/s	191.5 KB	119:58:52

Backlog files shrunk by innfeed:

Server	Size
panorama	976.8 KB
okapi	488.4 KB
TOTAL: 2	1.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	290	88	87.1 KB	41	0	00:00:29
2	bursztyn.p.lodz.pl	1	79	12.2 MB	7	0	00:15:06
3	lze200.lze.lodz.pl	4	11	22.6 KB	3	0	00:04:57
4	isvcs01.man.lodz.pl	11	0	0.0 KB	0	11	00:00:00
TOTAL: 4		306	178	12.3 MB	51	11	10:47:53

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	973	88	87.1 KB	41	11	00:00:37
2	*.p.lodz.pl	1	79	12.2 MB	7	0	00:15:06
3	*.lze.lodz.pl	4	11	22.6 KB	3	0	00:04:57
4	*.elsnet.pl	8	0	0.0 KB	0	0	00:01:00
5	*.toya.net.pl	112	0	0.0 KB	0	0	00:00:19
6	unresolved	4	0	0.0 KB	0	0	10:25:53
TOTAL: 6		1102	178	12.3 MB	51	11	10:47:53

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.195.155	13.463	19.066	10:25:53
bursztyn.p.lodz.pl	0.047	0.189	00:15:06
lze200.lze.lodz.pl	0.010	0.044	00:04:57
host227.elsnet.pl	0.025	0.068	00:01:00
web.man.lodz.pl	0.928	3.356	00:00:29
staticline17008.toya.net.pl	0.004	0.019	00:00:12
staticline41435.toya.net.pl	0.348	1.252	00:00:07
mon.man.lodz.pl	1.710	6.402	00:00:06
david.man.lodz.pl	0.284	1.032	00:00:00
isvcs01.man.lodz.pl	0.057	0.143	00:00:00
TOTAL: 10	16.876	31.571	10:47:53

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	576
staticline41435.toya.net.pl	110
david.man.lodz.pl	96
host227.elsnet.pl	8
185.48.195.155	4
staticline17008.toya.net.pl	2
TOTAL: 6	796

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.195.155	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	99	55.6%
2	alt	79	44.4%
TOTAL: 2		178	100.0%

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

Newsgroup		Count
1	alt.binaries.erotica.pornstar	51
2	pl.comp.pecet	48
3	pl.comp.os.ms-windows.winnt	40
4	alt.sex.pictures.female	12
5	alt.binaries.sex.pictures.female	8
6	pl.comp.os.linux	6
7	alt.binaries.pornstar	5
8	pl.rec.kuchnia	3
9	pl.rec.dom	2
10	alt.binaries.pictures.erotica.pornstars	1
11	alt.binaries.pictures.erotica.pornstar	1
12	alt.binaries.erotica.pornstars.80s	1
TOTAL: 12		178

Daily Usenet report

Dec 10 04:15:02 -- Dec 11 04:15:03