Daily Usenet report: Mar 31 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Mar 31 17:44:08 newsfeed nnrpd[14447]: SERVER perl filter not defined
Mar 31 17:44:17 newsfeed nnrpd[14449]: SERVER perl filter not defined
Mar 31 17:44:26 newsfeed nnrpd[14450]: SERVER perl filter not defined
Mar 31 20:26:07 newsfeed overchan[2371]: input line too long, skipping
Mar 31 21:21:21 newsfeed nnrpd[21756]: SERVER perl filter not defined
Mar 31 21:21:52 newsfeed nnrpd[21758]: SERVER perl filter not defined
Mar 31 21:24:11 newsfeed nnrpd[21786]: SERVER perl filter not defined
Mar 31 23:10:36 newsfeed nnrpd[25469]: SERVER perl filter not defined
Apr 1 01:06:25 newsfeed pgpverify[29240]: pgpverify: verification failed
Apr 1 01:35:08 newsfeed overchan[2371]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1306457	97.9%	150.1 MB	97.5%
innd	13181	1.0%	2.1 MB	1.4%
nnrpd	7279	0.5%	771.8 KB	0.5%
innfeed	7017	0.5%	1019.1 KB	0.6%
controlchan	3	0.0%	0.4 KB	0.0%
overchan	2	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 7	1333940	100.0%	153.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1301415	96.4%
Negative hits	31685	2.3%
Positive hits	17171	1.3%
Cache misses	125	0.0%
TOTAL: 4	1350396	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.017	0.0%	342	0.000	20.518	528.000
article cleanup	00:00:27.331	0.0%	1305696	0.000	0.021	0.052
article logging	00:00:51.662	0.1%	1305812	0.006	0.040	0.195
article parse	00:50:35.335	3.5%	116383265	0.007	0.026	0.050
article write	00:14:51.570	1.0%	1300983	0.204	0.685	2.351
artlog/artcncl	00:00:00.009	0.0%	334	0.000	0.027	1.000
data move	00:02:08.728	0.1%	124650788	0.000	0.001	0.006
hisgrep/artcncl	00:00:00.927	0.0%	261	0.000	3.552	73.000
hishave/artcncl	00:00:00.176	0.0%	342	0.000	0.515	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:40:25.485	2.8%	1351157	0.012	1.795	5.987
history sync	00:00:00.026	0.0%	336	0.000	0.077	4.000
history write	02:41:36.094	11.2%	1302339	0.077	7.445	27.929
hiswrite/artcncl	00:00:00.620	0.0%	81	0.000	7.654	198.000
idle	15:00:26.761	62.5%	79421250	0.324	0.680	6.391
nntp read	00:47:14.293	3.3%	115337052	0.012	0.025	0.052
overview write	00:00:10.301	0.0%	1300983	0.000	0.008	0.035
perl filter	03:03:26.841	12.7%	1302339	4.235	8.452	11.076
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:10.926	0.2%	2636270	0.030	0.072	0.149
TOTAL: 24:00:05.062	23:25:34.102	97.6%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.133	0.0%	34312	0.000	0.004	1.000
article prepare	00:00:00.013	0.0%	30168	0.000	0.000	0.050
article read	00:00:01.050	0.0%	7386	0.000	0.142	4.000
backlog stats	00:00:00.142	0.0%	217925	0.000	0.001	1.000
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.766	0.0%	143218	0.000	0.012	3.000
data write	00:00:08.551	0.0%	109759	0.000	0.078	16.000
idle	23:59:05.660	100.0%	217925	0.000	396.217	25937.000
status file	00:00:01.498	0.0%	194	0.000	7.722	183.500
TOTAL: 23:59:26.229	23:59:18.813	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	06:38:02.253	99.9%	4733	0.000	5045.902	450131.750
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.560	0.0%	11313	0.000	0.050	2.444
readart	00:00:00.461	0.0%	417	0.000	1.106	17.000
TOTAL: 06:38:26.697	06:38:03.274	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
control@usenet.org.uk	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	517	1301755	1297210	748	3797	99%	241:30:40
2	news.nask.pl	2	17956	4011	13049	896	22%	24:53:56
3	news.icm.edu.pl	41	305	27	207	71	8%	17:19:01
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		568	1320023	1301255	14004	4764	98%	283:43:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	413.4 GB	10.7 MB	154.7 MB	413.5 GB	99%	333.3 KB
2	news.nask.pl	14.4 MB	2.5 MB	2.6 MB	19.5 MB	74%	4.1 KB
3	news.icm.edu.pl	573.2 KB	68.2 KB	1.4 MB	2.0 MB	27%	21.4 KB
4	localhost	13.0 KB	0.0 KB	0.0 KB	13.0 KB	100%	1.9 KB
TOTAL: 4		413.4 GB	13.2 MB	158.7 MB	413.6 GB	99%	332.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 31 04:15:02 - 04:59:59	41920	3.2%	15.54	13.6 GB	3.3%	5290.59
Mar 31 05:00:00 - 05:59:59	53768	4.1%	14.94	17.1 GB	4.1%	4983.75
Mar 31 06:00:00 - 06:59:59	51686	4.0%	14.36	17.1 GB	4.1%	4993.31
Mar 31 07:00:00 - 07:59:59	58978	4.5%	16.38	19.2 GB	4.6%	5590.85
Mar 31 08:00:00 - 08:59:59	37647	2.9%	10.46	12.5 GB	3.0%	3631.17
Mar 31 09:00:00 - 09:59:59	57808	4.4%	16.06	20.8 GB	5.0%	6045.87
Mar 31 10:00:00 - 10:59:59	66457	5.1%	18.46	23.2 GB	5.6%	6765.17
Mar 31 11:00:00 - 11:59:59	15731	1.2%	4.37	2.0 GB	0.5%	583.06
Mar 31 12:00:00 - 12:59:59	12775	1.0%	3.55	1.9 GB	0.5%	557.69
Mar 31 13:00:00 - 13:59:59	12475	1.0%	3.47	2.3 GB	0.6%	672.61
Mar 31 14:00:00 - 14:59:59	15029	1.2%	4.17	2.4 GB	0.6%	690.35
Mar 31 15:00:00 - 15:59:59	47018	3.6%	13.06	15.0 GB	3.6%	4380.33
Mar 31 16:00:00 - 16:59:59	67656	5.2%	18.79	23.1 GB	5.6%	6736.05
Mar 31 17:00:00 - 17:59:59	80464	6.2%	22.35	29.1 GB	7.0%	8464.46
Mar 31 18:00:00 - 18:59:59	66894	5.1%	18.58	22.9 GB	5.5%	6673.92
Mar 31 19:00:00 - 19:59:59	74247	5.7%	20.62	22.5 GB	5.5%	6562.03
Mar 31 20:00:00 - 20:59:59	81810	6.3%	22.73	25.8 GB	6.2%	7507.64
Mar 31 21:00:00 - 21:59:59	96797	7.4%	26.89	26.8 GB	6.5%	7805.72
Mar 31 22:00:00 - 22:59:59	67698	5.2%	18.80	19.1 GB	4.6%	5564.95
Mar 31 23:00:00 - 23:59:59	57794	4.4%	16.05	18.7 GB	4.5%	5448.62
Apr 1 00:00:00 - 00:59:59	41296	3.2%	11.47	12.1 GB	2.9%	3537.69
Apr 1 01:00:00 - 01:59:59	39951	3.1%	11.10	11.8 GB	2.9%	3450.83
Apr 1 02:00:00 - 02:59:59	68015	5.2%	18.89	23.2 GB	5.6%	6767.46
Apr 1 03:00:00 - 03:59:59	70795	5.4%	19.67	25.1 GB	6.1%	7305.75
Apr 1 04:00:00 - 04:15:03	16583	1.3%	18.36	5.9 GB	1.4%	6798.52
TOTAL: 24:00:01	1301292	100.0%	15.06	413.3 GB	100.0%	5016.17

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3907	274	2545	6	1082
2	news.nask.pl	854	12	809	18	15
3	news.icm.edu.pl	70	0	5	0	65
TOTAL: 3		4831	286	3359	24	1162

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	168
it.tlc.cellulari.android	37
comp.mobile.ipad	30
comp.mobile.android	14
uk.rec.cycling.moderated	8
it.comp.os.win.windows8	5
it.comp.os.linux.ubuntu	4
it.comp.os.win.windows7	4
hr.ponuda.hardver.komponente	4
comp.sys.raspberry-pi	2
alt.sex.telephones	2
de.comp.lang.perl	2
it.comp.virtualizzazione	1
de.rec.sf.startrek	1
de.comp.office-pakete.ms-office.word	1
it.politica.m5s	1
hr.ponuda.hardver.konfiguracije	1
hr.alt.cellular.gsm.iphone	1
TOTAL: 18	286

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

Groups	Count
pl.sci.matematyka	15
pl.praca.oferowana	7
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 4	24

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	974
EMP (md5)	69
Too many newsgroups	2
Attached HTML file	1
TOTAL: 4	1046

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	35072	1749	32356	223	0	4%	24:00:59
2	icm	41171	436	27849	4941	15	1%	23:53:53
3	poznan	5077	82	3515	44	0	1%	23:59:26
4	nask	21944	40	14885	7	0	0%	24:08:14
5	okapi	0	0	0	0	2952	0%	24:00:01
TOTAL: 5		103264	2307	78605	5215	2967	2%	120:02:33

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	24.6 MB	927.1 MB	951.7 MB	11.3 KB/s	181.2 KB	23:53:53
2	panorama	6.7 MB	665.4 KB	7.3 MB	0.1 KB/s	3.8 KB	24:00:59
3	nask	281.3 KB	109.6 KB	390.9 KB	0.0 KB/s	8.3 KB	24:08:14
4	poznan	255.1 KB	88.3 KB	343.4 KB	0.0 KB/s	2.7 KB	23:59:26
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		31.8 MB	928.0 MB	959.8 MB	2.3 KB/s	130.7 KB	120:02:33

Backlog files shrunk by innfeed:

Server	Size
okapi	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	332	583.3 KB	288	0	00:00:07
2	web.man.lodz.pl	288	64	60.0 KB	31	0	00:00:31
3	lze200.lze.lodz.pl	5	21	39.7 KB	2	0	00:35:36
4	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 4		588	417	683.0 KB	321	7	06:38:26

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	332	583.3 KB	288	0	00:00:07
2	*.man.lodz.pl	680	64	60.0 KB	31	7	00:30:37
3	*.lze.lodz.pl	5	21	39.7 KB	2	0	00:35:36
4	*.dynamic.gprs.plus.pl	2	0	0.0 KB	0	0	03:14:46
5	*.elsnet.pl	6	0	0.0 KB	0	0	00:00:40
6	unresolved	3	0	0.0 KB	0	0	02:16:34
7	*.toya.net.pl	52	0	0.0 KB	0	0	00:00:04
TOTAL: 7		1036	417	683.0 KB	321	7	06:38:26

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
apn-46-215-21-90.dynamic.gprs.plus.pl	0.119	0.114	03:14:46
212.191.87.58	0.039	0.042	02:15:04
lze200.lze.lodz.pl	0.042	0.103	00:35:36
pc-vicki.man.lodz.pl	0.003	0.019	00:30:00
88.220.77.236	0.036	0.016	00:01:29
host227.elsnet.pl	0.017	0.109	00:00:40
web.man.lodz.pl	1.213	5.685	00:00:31
pc-skrzep2.cm.p.lodz.pl	1.058	4.910	00:00:07
mon.man.lodz.pl	1.210	4.994	00:00:04
staticline41435.toya.net.pl	0.183	0.957	00:00:04
david.man.lodz.pl	0.342	1.648	00:00:01
isvcs01.man.lodz.pl	0.037	0.148	00:00:00
TOTAL: 12	4.299	18.745	06:38:26

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	52
host227.elsnet.pl	6
apn-46-215-21-90.dynamic.gprs.plus.pl	2
212.191.87.58	2
pc-vicki.man.lodz.pl	1
88.220.77.236	1
TOTAL: 8	448

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	332	79.6%
2	pl	85	20.4%
TOTAL: 2		417	100.0%

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

Newsgroup		Count
1	lodman.info	332
2	pl.comp.pecet	61
3	pl.rec.kuchnia	20
4	pl.comp.os.ms-windows.winnt	4
TOTAL: 4		417

Daily Usenet report

Mar 31 04:15:02 -- Apr 1 04:15:03