Daily Usenet report: Jul 21 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Jul 21 07:47:35 newsfeed nnrpd[23510]: SERVER perl filter not defined
Jul 21 07:48:17 newsfeed nnrpd[23517]: SERVER perl filter not defined
Jul 21 08:38:09 newsfeed nnrpd[25245]: SERVER perl filter not defined
Jul 21 09:42:26 newsfeed nnrpd[27539]: SERVER perl filter not defined
Jul 21 12:32:55 newsfeed nnrpd[959]: SERVER perl filter not defined
Jul 21 12:35:19 newsfeed nnrpd[1041]: SERVER perl filter not defined
Jul 21 16:51:05 newsfeed nnrpd[9981]: SERVER perl filter not defined
Jul 21 16:51:36 newsfeed nnrpd[9988]: SERVER perl filter not defined
Jul 21 16:55:49 newsfeed nnrpd[10075]: SERVER perl filter not defined
Jul 21 19:16:22 newsfeed nnrpd[14937]: SERVER perl filter not defined
Jul 21 22:00:02 newsfeed pgpverify[20673]: pgpverify: verification failed
Jul 22 04:15:01 newsfeed nnrpd[1209]: web.man.lodz.pl rejected Flushing log and syslog files
Jul 22 04:15:02 newsfeed nnrpd[1217]: web.man.lodz.pl rejected Flushing log and syslog files
Jul 22 04:15:02 newsfeed nnrpd[1220]: web.man.lodz.pl rejected Flushing log and syslog files
Jul 22 04:15:02 newsfeed nnrpd[1222]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2437280	97.6%	283.5 MB	97.2%
innfeed	35929	1.4%	4.3 MB	1.5%
innd	19875	0.8%	3.4 MB	1.2%
nnrpd	5143	0.2%	552.6 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.0 KB	0.0%
TOTAL: 6	2498229	100.0%	291.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2432968	98.5%
Negative hits	19581	0.8%
Positive hits	17071	0.7%
Cache misses	100	0.0%
TOTAL: 4	2469720	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.952	0.0%	237	0.000	4.017	369.000
article cleanup	00:00:29.626	0.0%	2436941	0.004	0.012	0.025
article logging	00:01:09.057	0.1%	2437298	0.013	0.028	0.260
article parse	00:58:04.359	4.0%	112560008	0.023	0.031	0.053
article write	00:35:59.053	2.5%	2432956	0.370	0.887	6.240
artlog/artcncl	00:00:00.004	0.0%	235	0.000	0.017	1.000
artlog/artparse	00:00:00.000	0.0%	3	0.000	0.000	0.000
data move	00:01:38.848	0.1%	127584649	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.247	0.0%	214	0.000	1.154	69.000
hishave/artcncl	00:00:00.020	0.0%	237	0.000	0.084	0.833
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:09:08.929	0.6%	2470138	0.072	0.222	2.268
history sync	00:00:00.012	0.0%	291	0.000	0.041	3.000
history write	00:19:43.146	1.4%	2433561	0.194	0.486	7.307
hiswrite/artcncl	00:00:00.015	0.0%	23	0.000	0.652	6.000
idle	15:05:01.908	62.8%	87683566	0.445	0.619	2.080
nntp read	00:48:09.974	3.3%	110648888	0.016	0.026	0.033
overview write	00:00:14.442	0.0%	2432956	0.000	0.006	0.015
perl filter	05:21:17.241	22.3%	2433558	6.452	7.921	10.106
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:37.235	0.3%	5032144	0.029	0.055	0.091
TOTAL: 24:00:11.939	23:25:35.068	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.798	0.0%	354613	0.000	0.002	0.042
article prepare	00:00:00.031	0.0%	102826	0.000	0.000	0.050
article read	00:00:37.196	0.0%	25663	0.000	1.449	144.333
backlog stats	00:00:00.360	0.0%	550823	0.000	0.001	0.017
callbacks	00:00:00.000	0.0%	3	0.000	0.000	0.000
data read	00:00:04.114	0.0%	349312	0.000	0.012	0.066
data write	00:00:17.302	0.0%	187405	0.000	0.092	2.585
idle	23:58:56.305	99.9%	550823	36.962	156.741	989.559
status file	00:00:10.051	0.0%	12785	0.167	0.786	7.833
TOTAL: 24:00:23.664	24:00:06.157	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.001	0.0%	24	0.042	0.042	0.042
idle	07:43:57.215	99.9%	219233	0.000	126.975	450027.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:08.067	0.0%	439232	0.000	0.018	0.526
readart	00:00:00.053	0.0%	61	0.000	0.869	25.000
TOTAL: 07:44:28.480	07:44:05.336	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

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
group-admin@isc.org	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	986	2432934	2429195	353	3386	99%	253:12:34
2	news.nask.pl	2	16811	3720	12682	409	22%	23:20:01
3	news.icm.edu.pl	43	380	31	167	182	8%	17:26:25
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		1042	2450135	2432956	13202	3977	99%	293:59:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	747.3 GB	14.5 MB	30.7 MB	747.3 GB	99%	322.1 KB
2	news.nask.pl	25.0 MB	1.1 MB	1.2 MB	27.2 MB	91%	6.8 KB
3	news.icm.edu.pl	609.2 KB	48.0 KB	5.1 MB	5.7 MB	10%	27.6 KB
4	localhost	14.8 KB	0.0 KB	0.0 KB	14.8 KB	100%	1.5 KB
TOTAL: 4		747.3 GB	15.6 MB	37.0 MB	747.4 GB	99%	321.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jul 21 04:15:02 - 04:59:59	70407	2.9%	26.10	23.6 GB	3.2%	9178.79
Jul 21 05:00:00 - 05:59:59	94244	3.9%	26.18	32.0 GB	4.3%	9311.16
Jul 21 06:00:00 - 06:59:59	98359	4.0%	27.32	32.0 GB	4.3%	9311.35
Jul 21 07:00:00 - 07:59:59	102532	4.2%	28.48	33.5 GB	4.5%	9756.09
Jul 21 08:00:00 - 08:59:59	99647	4.1%	27.68	31.5 GB	4.2%	9183.48
Jul 21 09:00:00 - 09:59:59	93946	3.9%	26.10	28.3 GB	3.8%	8247.13
Jul 21 10:00:00 - 10:59:59	107510	4.4%	29.86	29.9 GB	4.0%	8708.18
Jul 21 11:00:00 - 11:59:59	115261	4.7%	32.02	32.5 GB	4.4%	9465.64
Jul 21 12:00:00 - 12:59:59	112362	4.6%	31.21	33.2 GB	4.4%	9661.65
Jul 21 13:00:00 - 13:59:59	97690	4.0%	27.14	31.5 GB	4.2%	9163.06
Jul 21 14:00:00 - 14:59:59	81860	3.4%	22.74	27.4 GB	3.7%	7969.01
Jul 21 15:00:00 - 15:59:59	60057	2.5%	16.68	18.7 GB	2.5%	5437.53
Jul 21 16:00:00 - 16:59:59	103299	4.2%	28.69	28.5 GB	3.8%	8297.34
Jul 21 17:00:00 - 17:59:59	115069	4.7%	31.96	30.5 GB	4.1%	8883.54
Jul 21 18:00:00 - 18:59:59	96486	4.0%	26.80	30.7 GB	4.1%	8946.47
Jul 21 19:00:00 - 19:59:59	125661	5.2%	34.91	36.0 GB	4.8%	10472.67
Jul 21 20:00:00 - 20:59:59	120212	4.9%	33.39	37.0 GB	5.0%	10790.26
Jul 21 21:00:00 - 21:59:59	117365	4.8%	32.60	36.4 GB	4.9%	10595.18
Jul 21 22:00:00 - 22:59:59	128760	5.3%	35.77	37.5 GB	5.0%	10909.39
Jul 21 23:00:00 - 23:59:59	120177	4.9%	33.38	36.1 GB	4.8%	10522.84
Jul 22 00:00:00 - 00:59:59	102288	4.2%	28.41	28.2 GB	3.8%	8222.77
Jul 22 01:00:00 - 01:59:59	87770	3.6%	24.38	28.5 GB	3.8%	8298.84
Jul 22 02:00:00 - 02:59:59	73547	3.0%	20.43	26.7 GB	3.6%	7776.96
Jul 22 03:00:00 - 03:59:59	87883	3.6%	24.41	29.3 GB	3.9%	8537.22
Jul 22 04:00:00 - 04:15:02	20308	0.8%	22.51	6.9 GB	0.9%	8058.64
TOTAL: 24:00:00	2432700	100.0%	28.16	746.3 GB	100.0%	9056.91

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3692	205	2988	0	499
2	news.nask.pl	471	6	394	15	56
3	news.icm.edu.pl	182	0	1	0	181
TOTAL: 3		4345	211	3383	15	736

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	95
it.comp.os.win.windows10	39
comp.sys.raspberry-pi	18
it.comp.os.win.windows7	17
it.tlc.cellulari.android	14
it.discussioni.energia	6
it.comp.os.win.windows8	5
de.comp.lang.php	5
comp.mobile.android	4
hr.ponuda.hardver.komponente	2
it.comp.virtualizzazione	2
uk.rec.cycling.moderated	1
de.comp.lang.vbclassic	1
de.comp.office-pakete.ms-office.excel	1
alt.sex.telephones	1
TOTAL: 15	211

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	170
Binary in non-binary group	104
EMP (fsl)	98
HTML post	2
Scoring filter (10)	1
Attached HTML file	1
TOTAL: 6	376

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	197875	2190	173493	921	5	177700	1%	23:59:48
2	icm	62871	1891	25680	20211	0	11	3%	24:02:53
3	poznan	5900	101	3641	48	0	0	1%	24:04:28
4	nask	0	0	0	0	0	13677	0%	24:00:01
5	okapi	0	0	0	0	0	2363	0%	24:00:01
TOTAL: 5		266646	4182	202814	21180	5	193751	1%	120:07:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	19.9 MB	4.5 GB	4.6 GB	55.2 KB/s	216.2 KB	24:02:53
2	poznan	9.4 MB	867.4 KB	10.2 MB	0.1 KB/s	70.2 KB	24:04:28
3	panorama	8.9 MB	6.2 MB	15.1 MB	0.2 KB/s	5.0 KB	23:59:48
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		38.2 MB	4.5 GB	4.6 GB	11.1 KB/s	189.5 KB	120:07:11

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	291	54	48.4 KB	27	0	00:00:26
2	lze200.lze.lodz.pl	2	6	11.4 KB	2	0	00:04:45
3	pc-vicki.man.lodz.pl	2	1	1.7 KB	1	0	01:00:00
4	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	00:00:00
TOTAL: 4		305	61	61.5 KB	30	10	07:44:28

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	687	55	50.1 KB	28	10	01:00:31
2	*.lze.lodz.pl	2	6	11.4 KB	2	0	00:04:45
3	unresolved	7	0	0.0 KB	0	0	06:39:01
4	*.adsl.inetia.pl	2	0	0.0 KB	0	0	00:00:05
5	*.toya.net.pl	53	0	0.0 KB	0	0	00:00:03
TOTAL: 5		751	61	61.5 KB	30	10	07:44:28

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89.38.41.79	11.177	16.564	06:38:48
pc-vicki.man.lodz.pl	0.006	0.032	01:00:00
lze200.lze.lodz.pl	0.007	0.035	00:04:45
web.man.lodz.pl	1.090	4.070	00:00:26
185.48.192.58	0.004	0.013	00:00:10
87-205-230-192.adsl.inetia.pl	0.006	0.023	00:00:05
staticline41435.toya.net.pl	0.208	0.793	00:00:03
mon.man.lodz.pl	1.105	4.521	00:00:03
71.94.21.184	0.016	0.039	00:00:02
david.man.lodz.pl	0.320	1.380	00:00:00
isvcs01.man.lodz.pl	0.088	0.148	00:00:00
TOTAL: 11	14.027	27.618	07:44:28

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	53
71.94.21.184	3
185.48.192.58	2
87-205-230-192.adsl.inetia.pl	2
89.38.41.79	2
TOTAL: 7	446

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-vicki.man.lodz.pl	2	0
89.38.41.79	1	0
TOTAL: 2	3	0

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	34
2	pl.comp.os.ms-windows.winnt	24
3	pl.misc.telefonia.gsm	2
4	pl.sci.medycyna	1
TOTAL: 4		61

Daily Usenet report

Jul 21 04:15:02 -- Jul 22 04:15:02