Daily Usenet report: Jul 5 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Jul 5 09:08:20 newsfeed nnrpd[23780]: SERVER perl filter not defined
Jul 5 09:41:58 newsfeed nnrpd[24885]: SERVER perl filter not defined
Jul 5 09:43:26 newsfeed nnrpd[24902]: SERVER perl filter not defined
Jul 5 09:44:39 newsfeed nnrpd[24914]: SERVER perl filter not defined
Jul 5 09:45:58 newsfeed nnrpd[24963]: SERVER perl filter not defined
Jul 5 09:46:57 newsfeed nnrpd[25129]: SERVER perl filter not defined
Jul 5 09:47:43 newsfeed nnrpd[25130]: SERVER perl filter not defined
Jul 5 18:41:29 newsfeed nnrpd[11175]: SERVER perl filter not defined
Jul 6 04:15:02 newsfeed nnrpd[31142]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2530620	98.7%	298.9 MB	98.4%
innd	24098	0.9%	3.8 MB	1.2%
innfeed	5108	0.2%	718.1 KB	0.2%
nnrpd	4867	0.2%	507.3 KB	0.2%
TOTAL: 4	2564693	100.0%	303.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2522024	95.6%
Negative hits	99622	3.8%
Positive hits	15176	0.6%
Cache misses	121	0.0%
TOTAL: 4	2636943	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.350	0.0%	177	0.000	24.576	609.000
article cleanup	00:00:35.087	0.0%	2528985	0.006	0.014	0.027
article logging	00:01:18.913	0.1%	2529731	0.016	0.031	0.680
article parse	01:00:28.861	4.2%	185525519	0.010	0.020	0.043
article write	01:31:21.496	6.3%	2518858	0.317	2.176	5.649
artlog/artcncl	00:00:00.002	0.0%	174	0.000	0.011	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:02:28.326	0.2%	201180236	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.850	0.0%	155	0.000	5.484	125.500
hishave/artcncl	00:00:00.273	0.0%	177	0.000	1.542	42.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:34:18.087	2.4%	2638064	0.139	0.780	9.853
history sync	00:00:00.010	0.0%	293	0.000	0.034	1.000
history write	00:43:16.727	3.0%	2523146	0.223	1.029	19.435
hiswrite/artcncl	00:00:00.021	0.0%	22	0.000	0.955	9.000
idle	13:10:00.900	54.9%	158751375	0.206	0.299	0.644
nntp read	00:55:26.442	3.9%	183599288	0.012	0.018	0.029
overview write	00:00:14.831	0.0%	2518858	0.001	0.006	0.014
perl filter	05:03:23.370	21.1%	2523143	5.281	7.215	9.122
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:02.132	0.3%	5570297	0.035	0.054	0.091
TOTAL: 23:59:29.436	23:08:00.678	96.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:01.838	0.0%	619799	0.000	0.003	0.100
article prepare	00:00:00.442	0.0%	2100686	0.000	0.000	0.010
article read	00:10:06.651	0.7%	520655	0.000	1.165	570.500
backlog stats	00:00:02.491	0.0%	2872596	0.000	0.001	0.018
callbacks	00:00:00.000	0.0%	26	0.000	0.000	0.000
data read	00:00:12.576	0.0%	1193008	0.000	0.011	0.083
data write	00:03:33.278	0.2%	1751436	0.000	0.122	0.285
idle	23:45:09.083	99.0%	2872596	2.890	29.767	2506.179
status file	00:00:01.344	0.0%	1657	0.000	0.811	9.000
TOTAL: 24:00:02.413	23:59:07.703	99.9%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:56:48.580	99.8%	3162	0.000	3355.022	259319.857
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.194	0.0%	7013	0.000	0.028	0.947
readart	00:00:02.913	0.0%	73	0.000	39.904	65.550
TOTAL: 02:57:06.555	02:56:51.687	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	1011	2517532	2504980	6512	6040	99%	412:15:04
2	news.nask.pl	2	21498	14792	2605	4101	68%	24:01:09
3	news.icm.edu.pl	47	222	24	196	2	10%	15:31:08
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1069	2539260	2519804	9313	10143	99%	451:47:21

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	720.3 GB	116.7 MB	185.1 MB	720.6 GB	99%	300.9 KB
2	news.nask.pl	109.5 MB	2.6 MB	93.0 MB	205.0 MB	53%	11.1 KB
3	news.icm.edu.pl	1.0 MB	0.0 KB	196.2 KB	1.2 MB	84%	48.5 KB
4	localhost	15.6 KB	0.0 KB	0.0 KB	15.6 KB	100%	2.0 KB
TOTAL: 4		720.4 GB	119.3 MB	278.3 MB	720.8 GB	99%	298.7 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 5 04:15:02 - 04:59:59	70159	2.8%	26.00	21.4 GB	3.0%	8313.90
Jul 5 05:00:00 - 05:59:59	90622	3.6%	25.17	27.7 GB	3.9%	8073.13
Jul 5 06:00:00 - 06:59:59	92247	3.7%	25.62	27.9 GB	3.9%	8138.64
Jul 5 07:00:00 - 07:59:59	106417	4.2%	29.56	31.1 GB	4.3%	9057.70
Jul 5 08:00:00 - 08:59:59	85492	3.4%	23.75	24.4 GB	3.4%	7104.31
Jul 5 09:00:00 - 09:59:59	125253	5.0%	34.79	33.3 GB	4.6%	9700.57
Jul 5 10:00:00 - 10:59:59	119937	4.8%	33.32	34.5 GB	4.8%	10034.90
Jul 5 11:00:00 - 11:59:59	93097	3.7%	25.86	29.7 GB	4.1%	8660.49
Jul 5 12:00:00 - 12:59:59	102362	4.1%	28.43	30.8 GB	4.3%	8984.02
Jul 5 13:00:00 - 13:59:59	108757	4.3%	30.21	31.4 GB	4.4%	9143.92
Jul 5 14:00:00 - 14:59:59	110506	4.4%	30.70	31.4 GB	4.4%	9154.80
Jul 5 15:00:00 - 15:59:59	83281	3.3%	23.13	27.9 GB	3.9%	8126.17
Jul 5 16:00:00 - 16:59:59	110412	4.4%	30.67	31.9 GB	4.4%	9283.31
Jul 5 17:00:00 - 17:59:59	87681	3.5%	24.36	27.8 GB	3.9%	8087.37
Jul 5 18:00:00 - 18:59:59	107110	4.3%	29.75	29.6 GB	4.1%	8632.38
Jul 5 19:00:00 - 19:59:59	111399	4.4%	30.94	31.2 GB	4.3%	9096.06
Jul 5 20:00:00 - 20:59:59	106462	4.2%	29.57	30.1 GB	4.2%	8771.35
Jul 5 21:00:00 - 21:59:59	116931	4.6%	32.48	32.4 GB	4.5%	9439.61
Jul 5 22:00:00 - 22:59:59	116420	4.6%	32.34	31.0 GB	4.3%	9027.33
Jul 5 23:00:00 - 23:59:59	112444	4.5%	31.23	30.0 GB	4.2%	8729.52
Jul 6 00:00:00 - 00:59:59	102946	4.1%	28.60	24.6 GB	3.4%	7163.28
Jul 6 01:00:00 - 01:59:59	120250	4.8%	33.40	31.2 GB	4.3%	9082.17
Jul 6 02:00:00 - 02:59:59	110862	4.4%	30.80	31.6 GB	4.4%	9192.87
Jul 6 03:00:00 - 03:59:59	108401	4.3%	30.11	29.2 GB	4.1%	8519.06
Jul 6 04:00:00 - 04:15:02	20107	0.8%	22.29	6.5 GB	0.9%	7499.48
TOTAL: 24:00:00	2519555	100.0%	29.16	718.6 GB	100.0%	8721.37

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6522	159	5442	0	921
2	news.nask.pl	4366	41	405	4	3916
3	news.icm.edu.pl	3	0	0	0	3
TOTAL: 3		10891	200	5847	4	4840

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	104
comp.mobile.android	33
it.comp.os.win.windows10	24
uk.rec.cycling.moderated	10
it.tlc.cellulari.android	9
comp.sys.raspberry-pi	7
de.comm.software.mailreader	2
hr.ponuda.hardver.komponente	2
comp.mobile.ipad	2
de.comp.lang.perl	2
nl.comp.os.linux.techniek	1
hr.ponuda.hardver.konfiguracije	1
it.comp.os.linux.ubuntu	1
de.rec.sf.startrek	1
alt.sex.telephones	1
TOTAL: 15	200

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	2785
Binary in non-binary group	889
Scoring filter (8)	213
Scoring filter (9)	50
Scoring filter (10)	35
EMP (phl)	30
Angle-bracket bot	20
Scoring filter (12)	19
Scoring filter (13)	15
Scoring filter (11)	12
Scoring filter (14)	7
HTML post	6
Scoring filter (15)	3
Scoring filter (21)	2
Scoring filter (23)	2
Scoring filter (18)	1
Scoring filter (22)	1
Scoring filter (16)	1
TOTAL: 18	4091

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	533867	491607	14114	25630	20	74801	92%	23:59:41
2	panorama	79773	2914	67782	4658	95	469343	3%	23:59:04
3	poznan	22276	82	14588	22	0	0	0%	24:01:16
4	nask	5489	28	3951	0	0	0	0%	23:57:09
5	okapi	0	0	0	0	0	2284	0%	24:00:00
TOTAL: 5		641405	494631	100435	30310	115	546428	77%	119:57:10

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	88.2 GB	3.4 GB	91.6 GB	1.1 MB/s	185.6 KB	23:59:41
2	panorama	18.9 MB	761.1 MB	779.9 MB	9.2 KB/s	105.5 KB	23:59:04
3	poznan	370.6 KB	173.3 KB	543.9 KB	0.0 KB/s	5.2 KB	24:01:16
4	nask	244.7 KB	0.0 KB	244.7 KB	0.0 KB/s	8.7 KB	23:57:09
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		88.2 GB	4.1 GB	92.3 GB	224.2 KB/s	184.4 KB	119:57:10

Backlog files shrunk by innfeed:

Server	Size
panorama	41.0 MB
okapi	488.3 KB
TOTAL: 2	41.5 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	7	53	88.9 KB	5	0	01:29:07
2	web.man.lodz.pl	288	12	9.7 KB	6	0	00:00:24
3	dmcs2.p.lodz.pl	3	7	13.5 KB	4	0	01:26:59
4	212.51.203.199	2	1	1.5 KB	1	0	00:00:14
5	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:01
TOTAL: 5		308	73	113.6 KB	16	8	02:57:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	10	60	102.4 KB	9	0	02:56:06
2	*.man.lodz.pl	680	12	9.7 KB	6	8	00:00:30
3	unresolved	2	1	1.5 KB	1	0	00:00:14
4	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:14
TOTAL: 4		694	73	113.6 KB	16	8	02:57:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.034	0.108	01:29:07
dmcs2.p.lodz.pl	0.011	0.044	01:26:59
web.man.lodz.pl	0.994	5.129	00:00:24
212.51.203.199	0.004	0.025	00:00:14
host227.elsnet.pl	0.007	0.033	00:00:14
mon.man.lodz.pl	0.903	4.052	00:00:03
isvcs01.man.lodz.pl	0.036	0.146	00:00:01
david.man.lodz.pl	0.256	1.349	00:00:01
TOTAL: 8	2.245	10.886	02:57:06

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
host227.elsnet.pl	2
TOTAL: 3	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
dmcs2.p.lodz.pl	2	0
pc-212-51-218-225.p.lodz.pl	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.misc.elektronika	54
2	pl.comp.pecet	8
3	pl.misc.kolej	6
4	pl.comp.os.ms-windows.winnt	4
5	pl.rec.radio.amatorskie	1
TOTAL: 5		73

Daily Usenet report

Jul 5 04:15:02 -- Jul 6 04:15:02