Daily Usenet report: Sep 15 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Sep 15 13:08:26 newsfeed nnrpd[8018]: SERVER perl filter not defined
Sep 15 15:31:21 newsfeed nnrpd[12970]: SERVER perl filter not defined
Sep 15 17:00:05 newsfeed pgpverify[16064]: pgpverify: verification failed
Sep 15 17:05:15 newsfeed nnrpd[16171]: SERVER perl filter not defined
Sep 15 17:05:48 newsfeed nnrpd[16172]: SERVER perl filter not defined
Sep 15 17:06:41 newsfeed nnrpd[16220]: SERVER perl filter not defined
Sep 15 17:08:15 newsfeed nnrpd[16369]: SERVER perl filter not defined
Sep 15 17:09:27 newsfeed nnrpd[16370]: SERVER perl filter not defined
Sep 15 22:08:52 newsfeed nnrpd[26833]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2326482	97.1%	269.0 MB	96.7%
innfeed	44323	1.9%	5.3 MB	1.9%
innd	19538	0.8%	3.4 MB	1.2%
nnrpd	5290	0.2%	569.7 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	2395637	100.0%	278.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2321955	98.0%
Negative hits	28500	1.2%
Positive hits	17772	0.8%
Cache misses	128	0.0%
TOTAL: 4	2368355	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.639	0.0%	225	0.000	25.062	359.000
article cleanup	00:00:27.205	0.0%	2326190	0.000	0.012	0.025
article logging	00:00:56.250	0.1%	2326599	0.010	0.024	0.338
article parse	00:49:27.126	3.4%	157737272	0.009	0.019	0.046
article write	00:24:55.316	1.7%	2322305	0.289	0.644	3.951
artlog/artcncl	00:00:00.003	0.0%	223	0.000	0.013	1.000
artlog/artparse	00:00:00.000	0.0%	5	0.000	0.000	0.000
data move	00:01:36.406	0.1%	172718935	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.026	0.0%	210	0.000	4.886	48.857
hishave/artcncl	00:00:00.125	0.0%	225	0.000	0.556	21.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:27:52.386	1.9%	2368960	0.119	0.706	11.315
history sync	00:00:00.012	0.0%	293	0.000	0.041	1.000
history write	00:36:18.527	2.5%	2322695	0.188	0.938	15.500
hiswrite/artcncl	00:00:00.023	0.0%	15	0.000	1.533	6.000
idle	16:19:41.856	68.0%	134913144	0.197	0.436	2.944
nntp read	00:41:01.435	2.8%	155985461	0.009	0.016	0.026
overview write	00:00:10.943	0.0%	2322305	0.000	0.005	0.012
perl filter	03:55:41.819	16.4%	2322690	4.151	6.089	8.762
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:38.285	0.3%	4703888	0.029	0.046	0.071
TOTAL: 24:00:15.362	23:21:54.382	97.3%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.246	0.0%	99644	0.000	0.002	0.062
article prepare	00:00:00.015	0.0%	98608	0.000	0.000	0.062
article read	00:01:01.261	0.1%	24557	0.000	2.495	573.000
backlog stats	00:00:00.191	0.0%	314342	0.000	0.001	0.022
callbacks	00:00:00.000	0.0%	15	0.000	0.000	0.000
data read	00:00:01.740	0.0%	173979	0.000	0.010	0.117
data write	00:00:10.763	0.0%	120008	0.000	0.090	0.711
idle	23:58:07.988	99.9%	314342	41.355	274.504	1152.943
status file	00:00:09.384	0.0%	15457	0.000	0.607	5.067
TOTAL: 23:59:42.553	23:59:31.588	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:32:54.227	99.9%	5529	0.000	2961.517	327887.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.190	0.0%	12051	0.000	0.016	0.500
readart	00:00:01.096	0.0%	122	0.000	8.984	20.854
TOTAL: 04:33:08.279	04:32:55.513	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	1097	2321752	2317800	407	3545	99%	279:24:35
2	news.nask.pl	2	17784	4072	13462	250	22%	23:40:06
3	news.icm.edu.pl	47	313	45	174	94	14%	17:36:02
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		1155	2339857	2321925	14043	3889	99%	320:40:43

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	722.0 GB	33.0 MB	35.8 MB	722.0 GB	99%	326.2 KB
2	news.nask.pl	13.8 MB	884.4 KB	943.8 KB	15.6 MB	88%	3.7 KB
3	news.icm.edu.pl	1.1 MB	8.2 KB	1.3 MB	2.4 MB	47%	17.6 KB
4	localhost	13.2 KB	0.0 KB	0.0 KB	13.2 KB	100%	1.7 KB
TOTAL: 4		722.0 GB	33.9 MB	38.0 MB	722.1 GB	99%	325.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Sep 15 04:15:02 - 04:59:59	56901	2.5%	21.09	17.2 GB	2.4%	6699.27
Sep 15 05:00:00 - 05:59:59	74656	3.2%	20.74	22.7 GB	3.1%	6620.75
Sep 15 06:00:00 - 06:59:59	127559	5.5%	35.43	34.5 GB	4.8%	10049.80
Sep 15 07:00:00 - 07:59:59	122062	5.3%	33.91	36.4 GB	5.0%	10602.76
Sep 15 08:00:00 - 08:59:59	108784	4.7%	30.22	31.7 GB	4.4%	9224.83
Sep 15 09:00:00 - 09:59:59	116912	5.0%	32.48	33.4 GB	4.6%	9737.55
Sep 15 10:00:00 - 10:59:59	124973	5.4%	34.71	35.5 GB	4.9%	10335.31
Sep 15 11:00:00 - 11:59:59	121299	5.2%	33.69	34.7 GB	4.8%	10109.47
Sep 15 12:00:00 - 12:59:59	128123	5.5%	35.59	36.1 GB	5.0%	10517.31
Sep 15 13:00:00 - 13:59:59	126178	5.4%	35.05	36.5 GB	5.1%	10633.45
Sep 15 14:00:00 - 14:59:59	102068	4.4%	28.35	34.8 GB	4.8%	10149.87
Sep 15 15:00:00 - 15:59:59	93034	4.0%	25.84	33.5 GB	4.6%	9760.38
Sep 15 16:00:00 - 16:59:59	96607	4.2%	26.84	32.9 GB	4.6%	9570.46
Sep 15 17:00:00 - 17:59:59	89951	3.9%	24.99	30.2 GB	4.2%	8810.05
Sep 15 18:00:00 - 18:59:59	83128	3.6%	23.09	29.5 GB	4.1%	8589.84
Sep 15 19:00:00 - 19:59:59	100879	4.3%	28.02	33.2 GB	4.6%	9671.55
Sep 15 20:00:00 - 20:59:59	104782	4.5%	29.11	33.0 GB	4.6%	9617.80
Sep 15 21:00:00 - 21:59:59	123532	5.3%	34.31	35.0 GB	4.8%	10193.36
Sep 15 22:00:00 - 22:59:59	54902	2.4%	15.25	18.3 GB	2.5%	5332.96
Sep 15 23:00:00 - 23:59:59	47179	2.0%	13.11	17.3 GB	2.4%	5027.73
Sep 16 00:00:00 - 00:59:59	79449	3.4%	22.07	25.9 GB	3.6%	7555.58
Sep 16 01:00:00 - 01:59:59	83936	3.6%	23.32	26.9 GB	3.7%	7844.93
Sep 16 02:00:00 - 02:59:59	65639	2.8%	18.23	22.4 GB	3.1%	6521.75
Sep 16 03:00:00 - 03:59:59	74459	3.2%	20.68	24.7 GB	3.4%	7199.31
Sep 16 04:00:00 - 04:15:01	14970	0.6%	16.61	5.6 GB	0.8%	6521.25
TOTAL: 23:59:59	2321962	100.0%	26.87	722.1 GB	100.0%	8763.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3885	201	3263	1	420
2	news.nask.pl	317	3	234	10	70
3	news.icm.edu.pl	95	0	1	0	94
TOTAL: 3		4297	204	3498	11	584

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	110
it.comp.os.win.windows10	59
it.tlc.cellulari.android	14
comp.mobile.android	5
comp.mobile.ipad	3
hr.alt.cellular.gsm.iphone	3
de.rec.sf.startrek	3
it.comp.os.win.windows7	2
it.comp.os.win.windows8	2
comp.sys.raspberry-pi	1
hr.ponuda.hardver.komponente	1
hr.ponuda.hardver.periferije	1
TOTAL: 12	204

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

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

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	89
Binary in non-binary group	79
Scoring filter (10)	1
Scoring filter (9)	1
TOTAL: 4	170

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	61661	7682	25672	12151	0	4	12%	23:58:58
2	panorama	50183	2526	21041	988	40	75032	5%	23:59:38
3	poznan	6259	41	4020	31	0	0	0%	23:51:55
4	okapi	0	0	0	0	0	2692	0%	24:00:03
5	nask	0	0	0	0	0	14090	0%	24:00:03
TOTAL: 5		118103	10249	50733	13170	40	91818	8%	119:50:37

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	955.4 MB	2.8 GB	3.7 GB	45.1 KB/s	196.2 KB	23:58:58
2	panorama	9.4 MB	7.3 MB	16.7 MB	0.2 KB/s	4.9 KB	23:59:38
3	poznan	175.4 KB	60.1 KB	235.5 KB	0.0 KB/s	3.3 KB	23:51:55
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:03
TOTAL: 5		964.9 MB	2.8 GB	3.7 GB	9.1 KB/s	166.9 KB	119:50:37

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
TOTAL: 1	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	17	77	167.8 KB	12	0	03:46:07
2	web.man.lodz.pl	289	43	45.0 KB	21	1	00:00:32
3	apn-31-1-188-104.dynamic.gprs.plus.pl	2	2	3.5 KB	1	0	00:40:09
4	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 4		315	122	216.3 KB	34	8	04:33:08

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	17	77	167.8 KB	12	0	03:46:07
2	*.man.lodz.pl	680	43	45.0 KB	21	8	00:00:36
3	*.dynamic.gprs.plus.pl	2	2	3.5 KB	1	0	00:40:09
4	*.adsl.inetia.pl	2	0	0.0 KB	0	0	00:00:56
5	unresolved	2	0	0.0 KB	0	0	00:05:04
6	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:08
7	*.toya.net.pl	83	0	0.0 KB	0	0	00:00:05
TOTAL: 7		788	122	216.3 KB	34	8	04:33:08

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.038	0.292	03:46:07
apn-31-1-188-104.dynamic.gprs.plus.pl	0.027	0.031	00:40:09
185.48.195.23	0.086	0.160	00:05:04
77-254-125-145.adsl.inetia.pl	0.032	0.043	00:00:56
web.man.lodz.pl	0.779	4.170	00:00:32
host227.elsnet.pl	0.004	0.027	00:00:08
staticline41435.toya.net.pl	0.231	1.218	00:00:05
mon.man.lodz.pl	0.713	3.968	00:00:02
david.man.lodz.pl	0.258	1.360	00:00:00
isvcs01.man.lodz.pl	0.039	0.125	00:00:00
TOTAL: 10	2.207	11.394	04:33:08

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	83
185.48.195.23	2
77-254-125-145.adsl.inetia.pl	2
host227.elsnet.pl	2
TOTAL: 6	473

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	4	0
apn-31-1-188-104.dynamic.gprs.plus.pl	1	0
TOTAL: 2	5	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	120	98.4%
2	lodz	2	1.6%
TOTAL: 2		122	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	51
2	pl.comp.os.ms-windows.winnt	28
3	pl.misc.kolej	26
4	pl.comp.pecet	14
5	lodz.ogloszenia	2
6	pl.rec.mineraly	1
TOTAL: 6		122

Daily Usenet report

Sep 15 04:15:02 -- Sep 16 04:15:01