Daily Usenet report: Jan 8 04:15:01

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Jan 8 06:03:04 newsfeed nnrpd[27564]: SERVER perl filter not defined
Jan 8 09:11:57 newsfeed nnrpd[2070]: SERVER perl filter not defined
Jan 8 09:12:41 newsfeed nnrpd[2070]: message repeated 2 times: [ pc-212-191-92-111.p.lodz.pl group pl.test 0]
Jan 8 11:38:45 newsfeed nnrpd[7268]: SERVER perl filter not defined
Jan 8 12:52:46 newsfeed nnrpd[9754]: SERVER perl filter not defined
Jan 8 14:11:06 newsfeed nnrpd[12541]: SERVER perl filter not defined
Jan 8 15:38:22 newsfeed nnrpd[15568]: SERVER perl filter not defined
Jan 8 19:56:57 newsfeed nnrpd[24631]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3036739	98.2%	355.7 MB	97.9%
innfeed	25039	0.8%	3.0 MB	0.8%
innd	23981	0.8%	4.1 MB	1.1%
nnrpd	6140	0.2%	655.2 KB	0.2%
TOTAL: 4	3091899	100.0%	363.5 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3025924	97.4%
Negative hits	61022	2.0%
Positive hits	20896	0.7%
Cache misses	111	0.0%
TOTAL: 4	3107953	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.893	0.0%	165	0.000	23.594	801.000
article cleanup	00:00:41.037	0.0%	3036889	0.003	0.014	0.025
article logging	00:01:20.006	0.1%	3037342	0.011	0.026	0.460
article parse	01:26:01.327	6.0%	266284520	0.005	0.019	0.080
article write	00:30:06.405	2.1%	3027560	0.266	0.597	2.530
artlog/artcncl	00:00:00.003	0.0%	164	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:03:39.148	0.3%	285281726	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.473	0.0%	140	0.000	3.379	183.000
hishave/artcncl	00:00:00.074	0.0%	165	0.000	0.448	15.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:32:17.631	2.2%	3110329	0.074	0.623	21.667
history sync	00:00:00.012	0.0%	291	0.000	0.041	1.000
history write	00:46:17.876	3.2%	3028334	0.167	0.917	36.884
hiswrite/artcncl	00:00:00.142	0.0%	25	0.000	5.680	98.000
idle	12:01:26.172	50.1%	168334192	0.098	0.257	0.481
nntp read	01:25:23.421	5.9%	263805333	0.008	0.019	0.041
overview write	00:00:16.730	0.0%	3027560	0.001	0.006	0.013
perl filter	06:05:22.440	25.4%	3028333	4.008	7.239	10.110
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:54.948	0.4%	6111478	0.026	0.058	0.097
TOTAL: 24:00:17.639	22:58:51.738	95.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.307	0.0%	124575	0.000	0.002	0.087
article prepare	00:00:00.012	0.0%	72548	0.000	0.000	0.125
article read	00:00:57.296	0.1%	18131	0.000	3.160	433.167
backlog stats	00:00:00.204	0.0%	269821	0.000	0.001	0.015
callbacks	00:00:00.000	0.0%	5	0.000	0.000	0.000
data read	00:00:01.803	0.0%	156790	0.000	0.011	0.088
data write	00:00:09.366	0.0%	103274	0.000	0.091	2.710
idle	23:58:47.576	99.9%	269821	3.188	319.944	2940.190
status file	00:00:05.963	0.0%	7707	0.000	0.774	11.167
TOTAL: 24:00:12.175	24:00:02.527	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:31:50.101	99.7%	8167	0.000	1115.477	202680.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.338	0.0%	77999	0.000	0.017	3.000
readart	00:00:02.640	0.0%	150	0.000	17.600	41.391
TOTAL: 02:32:18.422	02:31:54.079	99.7%	-	-	-	-

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	1227	3033406	3022382	2056	8968	99%	351:56:45
2	news.nask.pl	2	15217	4581	10290	346	30%	24:37:23
3	news.icm.edu.pl	49	188	39	129	20	20%	15:35:43
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		1286	3048818	3027009	12475	9334	99%	392:09:51

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	876.0 GB	1.1 GB	1.2 GB	878.3 GB	99%	303.8 KB
2	news.nask.pl	18.4 MB	970.0 KB	1.0 MB	20.3 MB	90%	4.2 KB
3	news.icm.edu.pl	1.1 MB	12.8 KB	649.9 KB	1.7 MB	62%	30.3 KB
4	localhost	11.2 KB	0.0 KB	0.0 KB	11.2 KB	100%	1.6 KB
TOTAL: 4		876.0 GB	1.1 GB	1.2 GB	878.3 GB	99%	303.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 8 04:15:01 - 04:59:59	78391	2.6%	29.04	23.1 GB	2.6%	8985.13
Jan 8 05:00:00 - 05:59:59	125351	4.1%	34.82	36.3 GB	4.2%	10578.11
Jan 8 06:00:00 - 06:59:59	135081	4.5%	37.52	38.0 GB	4.3%	11060.73
Jan 8 07:00:00 - 07:59:59	136605	4.5%	37.95	38.5 GB	4.4%	11205.49
Jan 8 08:00:00 - 08:59:59	138562	4.6%	38.49	38.8 GB	4.4%	11303.21
Jan 8 09:00:00 - 09:59:59	129335	4.3%	35.93	36.6 GB	4.2%	10665.29
Jan 8 10:00:00 - 10:59:59	134185	4.4%	37.27	38.2 GB	4.4%	11116.30
Jan 8 11:00:00 - 11:59:59	131691	4.4%	36.58	36.5 GB	4.2%	10640.46
Jan 8 12:00:00 - 12:59:59	133912	4.4%	37.20	37.5 GB	4.3%	10925.41
Jan 8 13:00:00 - 13:59:59	135713	4.5%	37.70	37.5 GB	4.3%	10911.19
Jan 8 14:00:00 - 14:59:59	126163	4.2%	35.05	37.0 GB	4.2%	10769.66
Jan 8 15:00:00 - 15:59:59	131010	4.3%	36.39	37.2 GB	4.3%	10828.80
Jan 8 16:00:00 - 16:59:59	127725	4.2%	35.48	37.0 GB	4.2%	10783.17
Jan 8 17:00:00 - 17:59:59	128593	4.2%	35.72	37.9 GB	4.3%	11041.35
Jan 8 18:00:00 - 18:59:59	121650	4.0%	33.79	36.4 GB	4.2%	10605.91
Jan 8 19:00:00 - 19:59:59	127260	4.2%	35.35	37.2 GB	4.3%	10832.25
Jan 8 20:00:00 - 20:59:59	129256	4.3%	35.90	37.3 GB	4.3%	10866.08
Jan 8 21:00:00 - 21:59:59	133761	4.4%	37.16	37.8 GB	4.3%	11022.25
Jan 8 22:00:00 - 22:59:59	131737	4.4%	36.59	37.5 GB	4.3%	10919.50
Jan 8 23:00:00 - 23:59:59	132783	4.4%	36.88	37.8 GB	4.3%	11007.35
Jan 9 00:00:00 - 00:59:59	104459	3.5%	29.02	30.3 GB	3.5%	8812.10
Jan 9 01:00:00 - 01:59:59	108209	3.6%	30.06	30.9 GB	3.5%	8986.14
Jan 9 02:00:00 - 02:59:59	124018	4.1%	34.45	36.1 GB	4.1%	10514.82
Jan 9 03:00:00 - 03:59:59	98747	3.3%	27.43	34.4 GB	3.9%	10033.36
Jan 9 04:00:00 - 04:15:02	22596	0.7%	25.05	8.4 GB	1.0%	9782.80
TOTAL: 24:00:01	3026793	100.0%	35.03	874.2 GB	100.0%	10608.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9350	190	8236	4	920
2	news.nask.pl	412	2	319	12	79
3	news.icm.edu.pl	20	0	1	0	19
TOTAL: 3		9782	192	8556	16	1018

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	91
it.comp.os.win.windows10	43
it.tlc.cellulari.android	20
hr.alt.cellular.gsm.iphone	9
comp.sys.raspberry-pi	6
it.comp.os.win.windows7	5
comp.mobile.android	3
hr.ponuda.hardver.konfiguracije	3
hr.ponuda.hardver.komponente	3
de.comp.lang.php	2
nl.comp.os.linux.techniek	2
comp.mobile.ipad	1
hr.ponuda.hardver.periferije	1
it.discussioni.energia	1
uk.rec.cycling.moderated	1
de.comp.office-pakete.ms-office.outlook	1
TOTAL: 16	192

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

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

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	501
EMP (fsl)	40
Too many newsgroups	15
EMP (md5)	7
Scoring filter (9)	1
TOTAL: 5	564

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	49076	7162	23454	6630	0	17	14%	23:57:32
2	panorama	69424	2010	52867	1742	10	67834	2%	23:51:43
3	poznan	6767	88	4373	32	0	0	1%	23:54:25
4	nask	0	0	0	0	0	12009	0%	24:00:02
5	okapi	0	0	0	0	0	2770	0%	24:00:02
TOTAL: 5		125267	9260	80694	8404	10	82630	7%	119:43:44

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	1.3 GB	1.3 GB	2.5 GB	30.6 KB/s	191.2 KB	23:57:32
2	panorama	9.0 MB	8.9 MB	17.8 MB	0.2 KB/s	4.9 KB	23:51:43
3	poznan	335.1 KB	89.0 KB	424.2 KB	0.0 KB/s	3.5 KB	23:54:25
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		1.3 GB	1.3 GB	2.5 GB	6.2 KB/s	150.3 KB	119:43:44

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	lze200.lze.lodz.pl	12	86	168.6 KB	16	0	02:03:53
2	web.man.lodz.pl	288	62	54.5 KB	29	0	00:00:36
3	212.51.203.199	3	1	1.1 KB	1	0	00:00:23
4	pc-212-191-92-111.p.lodz.pl	2	1	0.8 KB	1	1	00:18:30
5	isvcs01.man.lodz.pl	6	0	0.0 KB	0	6	00:00:01
TOTAL: 5		311	150	225.0 KB	47	7	02:32:18

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lze.lodz.pl	12	86	168.6 KB	16	0	02:03:53
2	*.man.lodz.pl	678	62	54.5 KB	29	6	00:00:43
3	unresolved	8	1	1.1 KB	1	0	00:08:05
4	*.p.lodz.pl	2	1	0.8 KB	1	1	00:18:30
5	*.elsnet.pl	6	0	0.0 KB	0	0	00:00:24
6	*.ics.p.lodz.pl	1	0	0.0 KB	0	0	00:00:03
7	*.pool.ukrtel.net	1	0	0.0 KB	0	0	00:00:04
8	*.versanet.de	178	0	0.0 KB	0	0	00:00:24
9	*.toya.net.pl	96	0	0.0 KB	0	0	00:00:06
TOTAL: 9		982	150	225.0 KB	47	7	02:32:18

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.041	0.231	02:03:53
pc-212-191-92-111.p.lodz.pl	0.302	0.122	00:18:30
185.48.195.19	0.203	0.416	00:07:38
web.man.lodz.pl	0.928	4.430	00:00:36
i59f5ec1e.versanet.de	0.589	2.459	00:00:24
host227.elsnet.pl	0.016	0.091	00:00:24
212.51.203.199	0.022	0.039	00:00:23
staticline41435.toya.net.pl	0.311	1.425	00:00:06
237-232-53-37.pool.ukrtel.net	0.000	0.018	00:00:04
mon.man.lodz.pl	0.846	4.325	00:00:04
wireless.ics.p.lodz.pl	0.000	0.021	00:00:03
107.150.98.129	0.005	0.015	00:00:03
isvcs01.man.lodz.pl	0.046	0.092	00:00:01
david.man.lodz.pl	0.323	1.373	00:00:00
TOTAL: 14	3.632	15.057	02:32:18

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
i59f5ec1e.versanet.de	178
staticline41435.toya.net.pl	96
david.man.lodz.pl	96
host227.elsnet.pl	6
185.48.195.19	4
107.150.98.129	1
wireless.ics.p.lodz.pl	1
237-232-53-37.pool.ukrtel.net	1
TOTAL: 9	671

NNRP unrecognized commands (by host):

System	Conn
107.150.98.129	2
TOTAL: 1	2

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	1
Host: 212.191.2.21:119	1
TOTAL: 2	2

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	77
2	pl.comp.os.ms-windows.winnt	35
3	pl.rec.dom	10
4	pl.rec.kuchnia	10
5	pl.misc.telefonia.gsm	9
6	pl.comp.os.linux	6
7	pl.internet.polip	2
8	pl.test	1
TOTAL: 8		150

Daily Usenet report

Jan 8 04:15:01 -- Jan 9 04:15:02