Daily Usenet report: Sep 12 04:15:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Sep 12 12:26:52 newsfeed nnrpd[21818]: SERVER perl filter not defined
Sep 12 13:35:55 newsfeed nnrpd[24208]: SERVER perl filter not defined
Sep 12 14:09:42 newsfeed nnrpd[25284]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2744069	98.3%	320.5 MB	98.1%
innd	38536	1.4%	5.2 MB	1.6%
nnrpd	4795	0.2%	501.1 KB	0.1%
innfeed	4792	0.2%	676.4 KB	0.2%
TOTAL: 4	2792192	100.0%	326.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2734912	95.9%
Negative hits	98675	3.5%
Positive hits	16752	0.6%
Cache misses	123	0.0%
TOTAL: 4	2850462	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.668	0.0%	165	0.000	22.230	751.000
article cleanup	00:00:40.315	0.0%	2742098	0.007	0.015	0.029
article logging	00:01:21.548	0.1%	2742679	0.014	0.030	1.446
article parse	01:13:16.746	5.1%	205414291	0.011	0.021	0.081
article write	00:26:00.731	1.8%	2718930	0.337	0.574	4.413
artlog/artcncl	00:00:00.005	0.0%	165	0.000	0.030	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:50.600	0.2%	222542125	0.000	0.001	0.004
hisgrep/artcncl	00:00:00.110	0.0%	150	0.000	0.733	16.000
hishave/artcncl	00:00:00.035	0.0%	165	0.000	0.212	1.333
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:28:27.865	2.0%	2851435	0.113	0.599	8.343
history sync	00:00:00.015	0.0%	291	0.000	0.052	2.000
history write	00:41:16.303	2.9%	2735856	0.202	0.905	9.602
hiswrite/artcncl	00:00:00.020	0.0%	15	0.000	1.333	10.000
idle	13:22:12.019	55.7%	149422054	0.155	0.322	0.584
nntp read	01:10:24.581	4.9%	203285675	0.013	0.021	0.057
overview write	00:00:15.504	0.0%	2718930	0.000	0.006	0.015
perl filter	05:35:40.167	23.3%	2735856	5.403	7.362	12.010
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:37.301	0.3%	5482066	0.035	0.051	0.115
TOTAL: 23:59:20.970	23:07:07.533	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.183	0.0%	55447	0.000	0.003	0.167
article prepare	00:00:00.018	0.0%	75180	0.000	0.000	0.028
article read	00:00:19.761	0.0%	18799	0.000	1.051	738.000
backlog stats	00:00:00.285	0.0%	289007	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	11	0.000	0.000	0.000
data read	00:00:01.914	0.0%	140904	0.000	0.014	0.120
data write	00:00:13.698	0.0%	147473	0.000	0.093	0.920
idle	23:59:17.661	99.9%	289007	20.955	298.808	15055.500
status file	00:00:00.701	0.0%	941	0.000	0.745	3.667
TOTAL: 24:00:01.251	23:59:54.221	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:34.000	99.3%	3058	0.000	599.738	451833.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.158	0.0%	6782	0.000	0.023	0.500
readart	00:00:00.009	0.0%	51	0.000	0.176	5.000
TOTAL: 00:30:46.069	00:30:34.167	99.4%	-	-	-	-

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	1201	2721172	2708799	5416	6957	99%	337:28:16
2	news.nask.pl	2	32279	11292	4767	16220	34%	23:56:19
3	news.icm.edu.pl	38	290	14	270	6	4%	15:01:27
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1245	2753744	2720108	10453	23183	98%	376:26:02

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	821.0 GB	38.4 MB	209.8 MB	821.2 GB	99%	317.1 KB
2	news.nask.pl	61.0 MB	2.1 MB	229.1 MB	292.2 MB	20%	10.9 KB
3	news.icm.edu.pl	712.0 KB	100.5 KB	182.8 KB	995.3 KB	71%	49.8 KB
4	localhost	3.9 KB	0.0 KB	0.0 KB	3.9 KB	100%	1.3 KB
TOTAL: 4		821.1 GB	40.6 MB	439.1 MB	821.5 GB	99%	314.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 12 04:15:02 - 04:59:59	94371	3.5%	34.98	28.1 GB	3.4%	10906.38
Sep 12 05:00:00 - 05:59:59	104402	3.8%	29.00	30.3 GB	3.7%	8823.37
Sep 12 06:00:00 - 06:59:59	118082	4.3%	32.80	36.8 GB	4.5%	10711.29
Sep 12 07:00:00 - 07:59:59	100048	3.7%	27.79	31.3 GB	3.8%	9119.68
Sep 12 08:00:00 - 08:59:59	124644	4.6%	34.62	38.3 GB	4.7%	11157.49
Sep 12 09:00:00 - 09:59:59	106950	3.9%	29.71	32.6 GB	4.0%	9505.72
Sep 12 10:00:00 - 10:59:59	129555	4.8%	35.99	40.2 GB	4.9%	11712.34
Sep 12 11:00:00 - 11:59:59	117681	4.3%	32.69	36.5 GB	4.4%	10626.01
Sep 12 12:00:00 - 12:59:59	112377	4.1%	31.22	34.0 GB	4.1%	9893.21
Sep 12 13:00:00 - 13:59:59	102685	3.8%	28.52	32.0 GB	3.9%	9312.91
Sep 12 14:00:00 - 14:59:59	117188	4.3%	32.55	39.6 GB	4.8%	11532.74
Sep 12 15:00:00 - 15:59:59	117167	4.3%	32.55	38.8 GB	4.7%	11288.95
Sep 12 16:00:00 - 16:59:59	122009	4.5%	33.89	37.4 GB	4.6%	10907.35
Sep 12 17:00:00 - 17:59:59	97124	3.6%	26.98	28.4 GB	3.5%	8275.15
Sep 12 18:00:00 - 18:59:59	127468	4.7%	35.41	36.6 GB	4.5%	10655.84
Sep 12 19:00:00 - 19:59:59	102422	3.8%	28.45	28.0 GB	3.4%	8166.04
Sep 12 20:00:00 - 20:59:59	111893	4.1%	31.08	32.4 GB	4.0%	9450.80
Sep 12 21:00:00 - 21:59:59	106338	3.9%	29.54	32.6 GB	4.0%	9481.55
Sep 12 22:00:00 - 22:59:59	115793	4.3%	32.16	34.7 GB	4.2%	10095.99
Sep 12 23:00:00 - 23:59:59	118815	4.4%	33.00	34.3 GB	4.2%	9990.50
Sep 13 00:00:00 - 00:59:59	111993	4.1%	31.11	31.2 GB	3.8%	9098.63
Sep 13 01:00:00 - 01:59:59	112505	4.1%	31.25	31.5 GB	3.8%	9178.36
Sep 13 02:00:00 - 02:59:59	107225	3.9%	29.78	32.0 GB	3.9%	9314.33
Sep 13 03:00:00 - 03:59:59	114290	4.2%	31.75	33.6 GB	4.1%	9781.94
Sep 13 04:00:00 - 04:15:02	27125	1.0%	30.07	9.1 GB	1.1%	10527.63
TOTAL: 24:00:00	2720150	100.0%	31.48	820.2 GB	100.0%	9953.82

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	16412	5	555	6	15846
2	newsfeed.pionier.net.pl	7335	151	5687	0	1497
3	news.icm.edu.pl	7	0	3	0	4
TOTAL: 3		23754	156	6245	6	17347

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	76
it.tlc.cellulari.android	41
it.comp.os.win.windows10	12
hr.alt.cellular.gsm.android	7
comp.mobile.ipad	6
uk.rec.cycling.moderated	4
it.comp.os.linux.ubuntu	3
comp.mobile.android	3
comp.sys.raspberry-pi	2
it.comp.os.win.windows7	1
hr.ponuda.hardver.komponente	1
TOTAL: 11	156

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

Groups	Count
pl.sci.matematyka	4
christnet.philosophy	1
newsguy.general	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	14989
Binary in non-binary group	1455
Scoring filter (8)	115
Poison newsgroup	102
EMP (phl)	30
Scoring filter (9)	24
Scoring filter (10)	14
Scoring filter (13)	12
Scoring filter (12)	11
Scoring filter (11)	9
HTML post	2
Scoring filter (19)	1
EMP (fsl)	1
TOTAL: 13	16765

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	52807	264	25286	18366	0	1157	0%	23:59:04
2	poznan	17656	97	11188	30	0	0	0%	23:57:15
3	panorama	13867	82	12271	27	22	41769	0%	23:55:59
4	nask	9275	16	6217	1	0	0	0%	24:08:28
5	okapi	0	0	0	0	0	2693	0%	24:00:02
TOTAL: 5		93605	459	54962	18424	22	45619	0%	120:00:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	2.7 MB	4.5 GB	4.5 GB	54.2 KB/s	251.3 KB	23:59:04
2	poznan	833.1 KB	103.4 KB	936.5 KB	0.0 KB/s	7.4 KB	23:57:15
3	panorama	295.9 KB	94.8 KB	390.7 KB	0.0 KB/s	3.6 KB	23:55:59
4	nask	67.7 KB	1.2 KB	68.8 KB	0.0 KB/s	4.0 KB	24:08:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:02
TOTAL: 5		3.8 MB	4.5 GB	4.5 GB	10.8 KB/s	248.0 KB	120:00:48

Backlog files shrunk by innfeed:

Server	Size
panorama	3.3 MB
TOTAL: 1	3.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	50	62.9 KB	20	0	00:00:21
2	dvc19.neoplus.adsl.tpnet.pl	1	1	1.1 KB	1	0	00:30:07
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		292	51	63.9 KB	21	3	00:30:45

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	675	50	62.9 KB	20	3	00:00:26
2	*.neoplus.adsl.tpnet.pl	1	1	1.1 KB	1	0	00:30:07
3	unresolved	4	0	0.0 KB	0	0	00:00:11
TOTAL: 3		680	51	63.9 KB	21	3	00:30:45

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
dvc19.neoplus.adsl.tpnet.pl	0.005	0.014	00:30:07
web.man.lodz.pl	1.073	4.545	00:00:21
185.48.193.73	0.011	0.016	00:00:10
mon.man.lodz.pl	0.895	3.947	00:00:03
71.94.21.184	0.012	0.029	00:00:01
david.man.lodz.pl	0.267	1.280	00:00:01
isvcs01.man.lodz.pl	0.018	0.059	00:00:00
TOTAL: 7	2.281	9.890	00:30:45

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
71.94.21.184	2
185.48.193.73	2
TOTAL: 4	388

NNRP client timeouts [Top 20]:

System	Conn	Peer
dvc19.neoplus.adsl.tpnet.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	50	98.0%
2	lodman	1	2.0%
TOTAL: 2		51	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	26
2	pl.comp.os.ms-windows.winnt	24
3	lodman.test	1
TOTAL: 3		51

Daily Usenet report

Sep 12 04:15:02 -- Sep 13 04:15:02