Daily Usenet report: Dec 4 04:15:02

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Dec 4 08:47:51 newsfeed nnrpd[32642]: SERVER perl filter not defined
Dec 4 09:14:37 newsfeed nnrpd[1203]: SERVER perl filter not defined
Dec 4 11:50:03 newsfeed pgpverify[6622]: pgpverify: verification failed
Dec 4 14:50:38 newsfeed nnrpd[12887]: SERVER perl filter not defined
Dec 4 14:52:42 newsfeed nnrpd[13032]: SERVER perl filter not defined
Dec 4 14:54:08 newsfeed nnrpd[13079]: SERVER perl filter not defined
Dec 4 14:58:49 newsfeed nnrpd[13179]: SERVER perl filter not defined
Dec 4 14:59:51 newsfeed nnrpd[13185]: SERVER perl filter not defined
Dec 4 15:26:09 newsfeed nnrpd[14190]: SERVER perl filter not defined
Dec 4 15:50:21 newsfeed nnrpd[14972]: SERVER perl filter not defined
Dec 4 16:32:48 newsfeed nnrpd[16518]: SERVER perl filter not defined
Dec 4 18:32:37 newsfeed nnrpd[20700]: message repeated 4 times: [ host-89-231-82-88.opoczno.mm.pl group lodzman.test 0]
Dec 4 18:32:44 newsfeed nnrpd[20700]: message repeated 7 times: [ host-89-231-82-88.opoczno.mm.pl group lodz.test 0]
Dec 4 18:32:51 newsfeed nnrpd[20700]: message repeated 5 times: [ host-89-231-82-88.opoczno.mm.pl group lodman.test 0]
Dec 4 18:37:45 newsfeed nnrpd[20799]: message repeated 2 times: [ host-89-231-82-88.opoczno.mm.pl group lodzman.test 0]
Dec 4 18:40:10 newsfeed nnrpd[20799]: message repeated 7 times: [ host-89-231-82-88.opoczno.mm.pl group lodzman.test 0]
Dec 4 19:58:58 newsfeed nnrpd[23624]: message repeated 2 times: [ staticline-31-183-82-28.toya.net.pl group lodman.test 0]
Dec 4 19:59:59 newsfeed nnrpd[23624]: message repeated 2 times: [ staticline-31-183-82-28.toya.net.pl group lodman.test 0]

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2900567	97.4%	339.1 MB	97.1%
innfeed	47721	1.6%	5.7 MB	1.6%
innd	22725	0.8%	3.9 MB	1.1%
nnrpd	5684	0.2%	625.3 KB	0.2%
controlchan	4	0.0%	0.6 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2976702	100.0%	349.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2895998	98.6%
Negative hits	25645	0.9%
Positive hits	15570	0.5%
Cache misses	90	0.0%
TOTAL: 4	2937303	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.606	0.0%	228	0.000	11.430	487.000
article cleanup	00:00:46.121	0.1%	2900356	0.006	0.016	0.028
article logging	00:01:20.678	0.1%	2900816	0.013	0.028	0.628
article parse	01:20:39.061	5.6%	274779766	0.006	0.018	0.077
article write	00:28:39.101	2.0%	2897234	0.232	0.593	2.795
artlog/artcncl	00:00:00.007	0.0%	226	0.000	0.031	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:05:42.837	0.4%	293121369	0.000	0.001	0.009
hisgrep/artcncl	00:00:00.123	0.0%	208	0.000	0.591	26.000
hishave/artcncl	00:00:00.100	0.0%	228	0.000	0.439	11.500
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:27.982	2.3%	2938819	0.090	0.683	28.802
history sync	00:00:00.013	0.0%	290	0.000	0.045	1.000
history write	00:47:10.823	3.3%	2897645	0.194	0.977	37.785
hiswrite/artcncl	00:00:00.038	0.0%	20	0.000	1.900	10.000
idle	12:19:45.832	51.4%	187132715	0.114	0.237	0.397
nntp read	01:30:28.319	6.3%	272435722	0.009	0.020	0.043
overview write	00:00:16.732	0.0%	2897234	0.001	0.006	0.016
perl filter	05:59:28.750	25.0%	2897643	4.036	7.444	9.951
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:27.585	0.4%	5853503	0.022	0.056	0.085
TOTAL: 24:00:12.122	23:13:16.708	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.297	0.0%	136556	0.000	0.002	0.125
article prepare	00:00:00.009	0.0%	47576	0.000	0.000	0.036
article read	00:00:26.301	0.0%	11868	0.000	2.216	161.500
backlog stats	00:00:00.212	0.0%	294373	0.000	0.001	0.013
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.901	0.0%	166209	0.000	0.011	0.075
data write	00:00:08.094	0.0%	109226	0.000	0.074	0.955
idle	23:59:05.566	99.9%	294373	82.782	293.320	938.672
status file	00:00:12.408	0.0%	16349	0.167	0.759	6.000
TOTAL: 24:00:08.512	23:59:54.788	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.047	0.0%	52	0.904	0.904	0.904
idle	07:22:29.160	99.9%	56054	0.000	473.635	602031.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:02.207	0.0%	113111	0.000	0.020	5.032
readart	00:00:01.362	0.0%	103	0.000	13.223	64.500
TOTAL: 07:22:58.113	07:22:32.776	99.9%	-	-	-	-

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

Control Channel:

Sender	newgroup	Bad PGP	OK
admin@openwatcom.news-admin.org	0	1	0
ymer@mulakaj.com	1	0	1
TOTAL	1	1	1

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1189	2896424	2893282	368	2774	99%	327:03:48
2	news.nask.pl	2	15822	3248	12270	304	20%	23:46:05
3	news.icm.edu.pl	46	248	65	142	41	26%	16:01:08
4	localhost	11	10	10	0	0	100%	00:00:00
TOTAL: 4		1248	2912504	2896605	12780	3119	99%	366:51:01

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	836.4 GB	10.2 MB	11.4 MB	836.4 GB	99%	302.8 KB
2	news.nask.pl	10.2 MB	1.0 MB	1.1 MB	12.3 MB	83%	3.5 KB
3	news.icm.edu.pl	2.5 MB	71.4 KB	640.8 KB	3.2 MB	78%	30.9 KB
4	localhost	15.6 KB	0.0 KB	0.0 KB	15.6 KB	100%	1.6 KB
TOTAL: 4		836.4 GB	11.3 MB	13.1 MB	836.4 GB	99%	302.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 4 04:15:02 - 04:59:59	84699	2.9%	31.39	25.1 GB	3.0%	9752.06
Dec 4 05:00:00 - 05:59:59	126294	4.4%	35.08	34.4 GB	4.1%	10007.10
Dec 4 06:00:00 - 06:59:59	116977	4.0%	32.49	32.9 GB	3.9%	9597.14
Dec 4 07:00:00 - 07:59:59	133184	4.6%	37.00	38.1 GB	4.6%	11106.75
Dec 4 08:00:00 - 08:59:59	110959	3.8%	30.82	38.4 GB	4.6%	11184.07
Dec 4 09:00:00 - 09:59:59	111827	3.9%	31.06	37.8 GB	4.5%	11016.90
Dec 4 10:00:00 - 10:59:59	112434	3.9%	31.23	37.3 GB	4.5%	10851.23
Dec 4 11:00:00 - 11:59:59	141352	4.9%	39.26	38.3 GB	4.6%	11155.42
Dec 4 12:00:00 - 12:59:59	130101	4.5%	36.14	37.3 GB	4.5%	10854.70
Dec 4 13:00:00 - 13:59:59	115497	4.0%	32.08	35.6 GB	4.3%	10366.96
Dec 4 14:00:00 - 14:59:59	101405	3.5%	28.17	35.1 GB	4.2%	10217.38
Dec 4 15:00:00 - 15:59:59	99509	3.4%	27.64	31.6 GB	3.8%	9200.45
Dec 4 16:00:00 - 16:59:59	140661	4.9%	39.07	38.0 GB	4.5%	11054.97
Dec 4 17:00:00 - 17:59:59	134310	4.6%	37.31	37.5 GB	4.5%	10930.63
Dec 4 18:00:00 - 18:59:59	132871	4.6%	36.91	34.4 GB	4.1%	10013.65
Dec 4 19:00:00 - 19:59:59	145997	5.0%	40.55	38.1 GB	4.6%	11104.55
Dec 4 20:00:00 - 20:59:59	97223	3.4%	27.01	28.7 GB	3.4%	8368.44
Dec 4 21:00:00 - 21:59:59	102223	3.5%	28.40	29.0 GB	3.5%	8461.19
Dec 4 22:00:00 - 22:59:59	128486	4.4%	35.69	36.7 GB	4.4%	10682.19
Dec 4 23:00:00 - 23:59:59	195086	6.7%	54.19	39.6 GB	4.7%	11545.99
Dec 5 00:00:00 - 00:59:59	101048	3.5%	28.07	29.8 GB	3.6%	8680.59
Dec 5 01:00:00 - 01:59:59	116026	4.0%	32.23	34.2 GB	4.1%	9949.03
Dec 5 02:00:00 - 02:59:59	97547	3.4%	27.10	30.1 GB	3.6%	8773.43
Dec 5 03:00:00 - 03:59:59	95508	3.3%	26.53	30.2 GB	3.6%	8784.01
Dec 5 04:00:00 - 04:15:04	25533	0.9%	28.24	7.9 GB	0.9%	9125.76
TOTAL: 24:00:02	2896757	100.0%	33.53	836.0 GB	100.0%	10145.89

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3184	240	2420	5	519
2	news.nask.pl	359	6	291	4	58
3	news.icm.edu.pl	41	0	2	0	39
TOTAL: 3		3584	246	2713	9	616

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	104
it.comp.os.win.windows10	63
it.tlc.cellulari.android	28
it.comp.os.win.windows7	8
comp.sys.raspberry-pi	7
uk.rec.cycling.moderated	7
comp.mobile.ipad	5
de.comp.lang.php	5
comp.mobile.android	4
hr.ponuda.hardver.konfiguracije	3
it.tlc.cellulari.iphone	3
de.comp.lang.perl	3
alt.sex.telephones	1
hr.ponuda.hardver.komponente	1
de.comp.lang.vbclassic	1
comp.sys.mac.vintage	1
hr.ponuda.hardver.periferije	1
it.comp.os.win.windows8	1
TOTAL: 18	246

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

Groups	Count
pl.praca.oferowana	4
pl.sci.matematyka	2
pl.sci.historia	2
bit.listserv.catholic	1
TOTAL: 4	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	106
EMP (md5)	44
HTML post	2
Scoring filter (10)	1
Too many newsgroups	1
TOTAL: 5	154

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	52090	2042	28328	8424	3	16	3%	23:55:24
2	panorama	84350	703	56348	212	5	79211	0%	24:00:20
3	poznan	4785	114	3062	35	0	0	2%	24:04:53
4	nask	0	0	0	0	0	17774	0%	24:00:01
5	okapi	0	0	0	0	0	2379	0%	24:00:01
TOTAL: 5		141225	2859	87738	8671	8	99380	2%	120:00:39

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	15.2 MB	1.6 GB	1.6 GB	19.4 KB/s	159.8 KB	23:55:24
2	panorama	2.3 MB	1.1 MB	3.4 MB	0.0 KB/s	3.8 KB	24:00:20
3	poznan	438.3 KB	95.3 KB	533.5 KB	0.0 KB/s	3.6 KB	24:04:53
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		17.9 MB	1.6 GB	1.6 GB	3.9 KB/s	145.4 KB	120:00:39

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	Rej	Elapsed
1	web.man.lodz.pl	288	32	28.8 KB	15	0	0	00:00:29
2	user-46-112-3-247.play-internet.pl	3	16	14.5 KB	2	0	2	00:02:51
3	2-152-196-109.itvmedia.pl	8	14	12.7 KB	3	0	4	00:37:51
4	staticline-31-183-205-230.toya.net.pl	4	11	10.9 KB	4	0	1	00:08:48
5	public-gprs361193.centertel.pl	14	9	8.1 KB	5	0	5	01:22:47
6	user-94-254-129-137.play-internet.pl	40	8	8.4 KB	6	0	12	02:46:32
7	staticline-31-183-82-28.toya.net.pl	3	6	9.6 KB	3	0	2	00:04:42
8	host-89-231-82-88.opoczno.mm.pl	6	2	1.6 KB	2	0	4	00:27:20
9	aeqc153.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	2	00:02:54
10	isvcs01.man.lodz.pl	10	0	0.0 KB	0	10	0	00:00:01
TOTAL: 10		378	98	94.7 KB	40	10	32	07:22:57

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.man.lodz.pl	682	32	28.8 KB	15	10	0	00:00:35
2	*.play-internet.pl	43	24	22.9 KB	8	0	14	02:49:23
3	*.toya.net.pl	64	17	20.5 KB	7	0	3	00:13:34
4	*.itvmedia.pl	8	14	12.7 KB	3	0	4	00:37:51
5	*.centertel.pl	14	9	8.1 KB	5	0	5	01:22:47
6	*.opoczno.mm.pl	6	2	1.6 KB	2	0	4	00:27:20
7	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	2	00:02:54
8	*.elsnet.pl	6	0	0.0 KB	0	0	0	00:00:33
9	unresolved	7	0	0.0 KB	0	0	0	01:47:56
TOTAL: 9		832	98	94.7 KB	40	10	32	07:22:57

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
user-94-254-129-137.play-internet.pl	0.131	0.396	02:46:32
185.48.195.155	2.937	3.566	01:47:38
public-gprs361193.centertel.pl	0.076	0.166	01:22:47
2-152-196-109.itvmedia.pl	0.060	0.126	00:37:51
host-89-231-82-88.opoczno.mm.pl	0.029	0.090	00:27:20
staticline-31-183-205-230.toya.net.pl	0.017	0.066	00:08:48
staticline-31-183-82-28.toya.net.pl	0.048	0.056	00:04:42
aeqc153.neoplus.adsl.tpnet.pl	0.012	0.025	00:02:54
user-46-112-3-247.play-internet.pl	0.042	0.042	00:02:51
host227.elsnet.pl	0.039	0.035	00:00:33
web.man.lodz.pl	0.974	3.480	00:00:29
212.51.203.199	0.009	0.024	00:00:18
staticline41435.toya.net.pl	0.197	0.617	00:00:03
mon.man.lodz.pl	0.949	3.270	00:00:02
isvcs01.man.lodz.pl	0.044	0.154	00:00:01
david.man.lodz.pl	0.318	1.029	00:00:01
TOTAL: 16	5.882	13.142	07:22:57

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	57
host227.elsnet.pl	6
185.48.195.155	5
212.51.203.199	2
TOTAL: 6	454

NNRP client timeouts [Top 20]:

System	Conn	Peer
user-94-254-129-137.play-internet.pl	4	0
185.48.195.155	1	1
public-gprs361193.centertel.pl	1	0
2-152-196-109.itvmedia.pl	1	0
TOTAL: 4	7	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodz	50	51.0%
2	pl	32	32.7%
3	lodman	10	10.2%
4	lodzman	6	6.1%
TOTAL: 4		98	100.0%

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

Newsgroup		Count
1	lodz.test	50
2	pl.comp.pecet	32
3	lodman.test	10
4	lodzman.test	6
TOTAL: 4		98

Daily Usenet report

Dec 4 04:15:02 -- Dec 5 04:15:04