Daily Usenet report: Oct 6 04:15:01

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Oct 6 09:30:46 newsfeed nnrpd[8831]: SERVER perl filter not defined
Oct 6 11:51:07 newsfeed nnrpd[13787]: SERVER perl filter not defined
Oct 6 11:59:44 newsfeed nnrpd[14067]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2653608	97.7%	307.5 MB	97.4%
innd	53016	2.0%	6.9 MB	2.2%
nnrpd	4907	0.2%	507.7 KB	0.2%
innfeed	4806	0.2%	677.1 KB	0.2%
TOTAL: 4	2716337	100.0%	315.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2648904	95.4%
Negative hits	111550	4.0%
Positive hits	15207	0.5%
Cache misses	120	0.0%
TOTAL: 4	2775781	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.593	0.0%	183	0.000	14.169	732.000
article cleanup	00:00:38.929	0.0%	2653251	0.005	0.015	0.032
article logging	00:01:13.654	0.1%	2653883	0.016	0.028	0.307
article parse	01:11:12.046	4.9%	198206867	0.011	0.022	0.053
article write	00:20:42.928	1.4%	2617336	0.316	0.475	3.178
artlog/artcncl	00:00:00.002	0.0%	179	0.000	0.011	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:05:55.187	0.4%	214619566	0.001	0.002	0.002
hisgrep/artcncl	00:00:00.108	0.0%	162	0.000	0.667	10.500
hishave/artcncl	00:00:00.066	0.0%	183	0.000	0.361	9.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:23:55.642	1.7%	2777120	0.074	0.517	10.508
history sync	00:00:00.021	0.0%	293	0.000	0.072	1.000
history write	00:33:38.904	2.3%	2649215	0.184	0.762	10.555
hiswrite/artcncl	00:00:00.067	0.0%	21	0.000	3.190	47.000
idle	13:12:25.995	55.0%	150758531	0.195	0.315	0.912
nntp read	01:34:13.208	6.5%	196121377	0.016	0.029	0.042
overview write	00:00:14.462	0.0%	2617336	0.000	0.006	0.014
perl filter	05:40:31.565	23.6%	2649214	5.162	7.712	11.106
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:19.374	0.3%	5264132	0.034	0.049	0.072
TOTAL: 24:00:18.125	23:09:04.751	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.146	0.0%	41189	0.000	0.004	0.125
article prepare	00:00:00.007	0.0%	32502	0.000	0.000	0.031
article read	00:00:46.206	0.1%	8139	0.000	5.677	480.750
backlog stats	00:00:00.181	0.0%	180386	0.000	0.001	0.029
callbacks	00:00:00.000	0.0%	18	0.000	0.000	0.000
data read	00:00:01.259	0.0%	99461	0.000	0.013	0.263
data write	00:00:04.753	0.0%	78064	0.000	0.061	0.316
idle	23:59:00.590	99.9%	180386	20.808	478.644	2790.087
status file	00:00:00.645	0.0%	837	0.000	0.771	9.500
TOTAL: 23:59:58.563	23:59:53.787	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:49:10.028	99.6%	3220	0.000	916.158	60782.057
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.168	0.0%	7310	0.000	0.023	0.500
readart	00:00:00.333	0.0%	154	0.000	2.162	9.045
TOTAL: 00:49:22.511	00:49:10.529	99.6%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1188	2617861	2606758	6078	5025	99%	309:58:52
2	news.nask.pl	2	46066	10279	4826	30961	22%	24:09:17
3	news.icm.edu.pl	43	283	26	249	8	9%	17:38:25
4	localhost	4	3	3	0	0	100%	00:00:00
TOTAL: 4		1237	2664213	2617066	11153	35994	98%	351:46:34

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	767.8 GB	66.1 MB	283.0 MB	768.2 GB	99%	308.4 KB
2	news.nask.pl	179.5 MB	9.3 MB	5.3 GB	5.5 GB	3%	140.6 KB
3	news.icm.edu.pl	931.5 KB	0.0 KB	178.4 KB	1.1 MB	83%	32.6 KB
4	localhost	4.1 KB	0.0 KB	0.0 KB	4.1 KB	100%	1.4 KB
TOTAL: 4		768.0 GB	75.5 MB	5.6 GB	773.7 GB	99%	305.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 6 04:15:01 - 04:59:59	61357	2.3%	22.73	19.7 GB	2.6%	7644.12
Oct 6 05:00:00 - 05:59:59	74967	2.9%	20.82	24.3 GB	3.2%	7065.26
Oct 6 06:00:00 - 06:59:59	59297	2.3%	16.47	22.7 GB	3.0%	6625.31
Oct 6 07:00:00 - 07:59:59	104057	4.0%	28.90	32.6 GB	4.3%	9507.74
Oct 6 08:00:00 - 08:59:59	83849	3.2%	23.29	24.7 GB	3.2%	7206.38
Oct 6 09:00:00 - 09:59:59	120201	4.6%	33.39	33.2 GB	4.3%	9656.03
Oct 6 10:00:00 - 10:59:59	120951	4.6%	33.60	35.4 GB	4.6%	10325.25
Oct 6 11:00:00 - 11:59:59	107720	4.1%	29.92	33.0 GB	4.3%	9606.80
Oct 6 12:00:00 - 12:59:59	113237	4.3%	31.45	34.7 GB	4.5%	10111.90
Oct 6 13:00:00 - 13:59:59	107921	4.1%	29.98	33.5 GB	4.4%	9766.15
Oct 6 14:00:00 - 14:59:59	102595	3.9%	28.50	33.0 GB	4.3%	9609.17
Oct 6 15:00:00 - 15:59:59	103184	3.9%	28.66	33.0 GB	4.3%	9609.28
Oct 6 16:00:00 - 16:59:59	96136	3.7%	26.70	32.3 GB	4.2%	9402.24
Oct 6 17:00:00 - 17:59:59	96360	3.7%	26.77	32.1 GB	4.2%	9343.39
Oct 6 18:00:00 - 18:59:59	114036	4.4%	31.68	34.7 GB	4.5%	10102.19
Oct 6 19:00:00 - 19:59:59	133095	5.1%	36.97	37.3 GB	4.9%	10855.19
Oct 6 20:00:00 - 20:59:59	147144	5.6%	40.87	38.6 GB	5.0%	11239.52
Oct 6 21:00:00 - 21:59:59	145660	5.6%	40.46	37.8 GB	5.0%	11020.90
Oct 6 22:00:00 - 22:59:59	149752	5.7%	41.60	38.6 GB	5.1%	11247.31
Oct 6 23:00:00 - 23:59:59	140067	5.4%	38.91	37.1 GB	4.9%	10812.71
Oct 7 00:00:00 - 00:59:59	98946	3.8%	27.48	27.4 GB	3.6%	7971.10
Oct 7 01:00:00 - 01:59:59	118795	4.5%	33.00	32.5 GB	4.3%	9464.09
Oct 7 02:00:00 - 02:59:59	114997	4.4%	31.94	30.2 GB	3.9%	8792.13
Oct 7 03:00:00 - 03:59:59	85519	3.3%	23.76	21.6 GB	2.8%	6290.64
Oct 7 04:00:00 - 04:15:02	17037	0.7%	18.89	4.4 GB	0.6%	5084.82
TOTAL: 24:00:01	2616880	100.0%	30.29	764.4 GB	100.0%	9276.38

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	31115	59	494	3	30559
2	newsfeed.pionier.net.pl	5421	135	3540	0	1746
3	news.icm.edu.pl	13	0	0	0	13
TOTAL: 3		36549	194	4034	3	32318

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	61
comp.sys.mac.vintage	35
it.tlc.cellulari.android	23
comp.mobile.android	23
comp.sys.raspberry-pi	14
de.comp.lang.vbclassic	10
de.comm.provider.telefonie	8
comp.mobile.ipad	7
it.comp.os.win.windows10	4
it.comp.os.win.windows7	3
uk.rec.cycling.moderated	2
it.comp.os.linux.ubuntu	2
it.tlc.cellulari.iphone	2
TOTAL: 13	194

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (phl)	21779
EMP (md5)	8243
Binary in non-binary group	1515
Scoring filter (8)	70
Scoring filter (9)	40
Poison newsgroup	12
Scoring filter (10)	8
Scoring filter (11)	6
Scoring filter (13)	6
HTML post	4
Too many newsgroups	1
Scoring filter (16)	1
TOTAL: 12	31685

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	36267	3144	23424	1972	0	76	8%	24:02:45
2	panorama	11714	1901	7783	678	39	30709	16%	24:02:42
3	poznan	14800	182	9896	21	0	0	1%	24:00:01
4	nask	9231	40	6176	0	0	0	0%	24:04:36
5	okapi	0	0	0	0	0	2403	0%	24:00:03
TOTAL: 5		72012	5267	47279	2671	39	33188	7%	120:10:07

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	443.4 MB	426.8 MB	870.1 MB	10.3 KB/s	174.2 KB	24:02:45
2	panorama	6.5 MB	2.0 MB	8.5 MB	0.1 KB/s	3.4 KB	24:02:42
3	poznan	1.1 MB	77.9 KB	1.2 MB	0.0 KB/s	6.1 KB	24:00:01
4	nask	1.1 MB	0.0 KB	1.1 MB	0.0 KB/s	27.4 KB	24:04:36
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		452.1 MB	428.8 MB	880.9 MB	2.1 KB/s	113.6 KB	120:10:07

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	100	146.9 KB	43	0	00:00:21
2	lze200.lze.lodz.pl	6	47	124.9 KB	9	0	00:48:41
3	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 3		297	147	271.7 KB	52	3	00:49:22

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	100	146.9 KB	43	3	00:00:34
2	*.lze.lodz.pl	6	47	124.9 KB	9	0	00:48:41
3	*.tktelekom.pl	3	0	0.0 KB	0	0	00:00:00
4	unresolved	2	0	0.0 KB	0	0	00:00:05
TOTAL: 4		687	147	271.7 KB	52	3	00:49:22

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.010	0.116	00:48:41
web.man.lodz.pl	1.139	4.915	00:00:21
mbox.man.lodz.pl	0.000	0.018	00:00:07
mon.man.lodz.pl	0.872	4.670	00:00:03
89.248.172.16	0.000	0.023	00:00:03
196.52.16.28	0.000	0.017	00:00:02
david.man.lodz.pl	0.253	1.453	00:00:01
94-40-21-67.tktelekom.pl	0.017	0.058	00:00:00
isvcs01.man.lodz.pl	0.017	0.055	00:00:00
TOTAL: 9	2.308	11.325	00:49:22

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
94-40-21-67.tktelekom.pl	3
mbox.man.lodz.pl	1
196.52.16.28	1
89.248.172.16	1
TOTAL: 6	390

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	98
2	pl.comp.pecet	24
3	pl.misc.telefonia.gsm	13
4	pl.comp.os.linux	5
5	pl.internet.polip	4
6	pl.rec.dom	3
TOTAL: 6		147

Daily Usenet report

Oct 6 04:15:01 -- Oct 7 04:15:02