Daily Usenet report: Aug 4 04:15:01

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Aug 4 04:50:03 newsfeed pgpverify[18678]: pgpverify: verification failed
Aug 4 04:50:03 newsfeed controlchan[2583]: skipping newgroup y nl-admin@nic.surfnet.nl (pgpverify failed) in <1470276241.16448@news.a3.xs4all.nl>
Aug 4 08:07:08 newsfeed nnrpd[28370]: SERVER perl filter not defined
Aug 4 09:26:17 newsfeed nnrpd[30963]: SERVER perl filter not defined
Aug 4 11:16:54 newsfeed nnrpd[2334]: SERVER perl filter not defined
Aug 4 13:14:11 newsfeed nnrpd[6380]: SERVER perl filter not defined
Aug 4 15:00:11 newsfeed nnrpd[10119]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2480462	98.6%	283.4 MB	98.3%
innd	25033	1.0%	3.7 MB	1.3%
innfeed	4885	0.2%	688.8 KB	0.2%
nnrpd	4811	0.2%	501.4 KB	0.2%
controlchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2515193	100.0%	288.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2470997	96.4%
Negative hits	77675	3.0%
Positive hits	14778	0.6%
Cache misses	114	0.0%
TOTAL: 4	2563564	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.346	0.0%	246	0.000	5.472	90.000
article cleanup	00:00:36.525	0.0%	2478386	0.006	0.015	0.031
article logging	00:01:12.059	0.1%	2478938	0.013	0.029	0.489
article parse	01:06:07.054	4.6%	225093966	0.008	0.018	0.043
article write	00:33:25.931	2.3%	2464071	0.338	0.814	3.653
artlog/artcncl	00:00:00.004	0.0%	217	0.000	0.018	1.000
data move	00:02:25.680	0.2%	241384725	0.000	0.001	0.002
hisgrep/artcncl	00:00:00.170	0.0%	212	0.000	0.802	18.000
hishave/artcncl	00:00:00.036	0.0%	246	0.000	0.146	1.750
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:20:52.673	1.5%	2564742	0.104	0.488	8.448
history sync	00:00:00.014	0.0%	292	0.000	0.048	1.000
history write	00:28:37.291	2.0%	2472350	0.192	0.695	8.928
hiswrite/artcncl	00:00:00.031	0.0%	34	0.000	0.912	21.000
idle	14:22:16.708	59.9%	187910642	0.192	0.275	0.654
nntp read	01:08:02.575	4.7%	223307313	0.012	0.018	0.044
overview write	00:00:13.416	0.0%	2464071	0.000	0.005	0.015
perl filter	05:03:40.824	21.1%	2472350	4.963	7.370	11.125
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:26.788	0.3%	5040428	0.037	0.053	0.112
TOTAL: 23:59:10.441	23:11:59.125	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.432	0.0%	144515	0.000	0.003	0.133
article prepare	00:00:00.064	0.0%	295010	0.000	0.000	0.250
article read	00:01:25.153	0.1%	73672	0.000	1.156	198.500
backlog stats	00:00:00.431	0.0%	578411	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	15	0.000	0.000	0.000
data read	00:00:03.275	0.0%	268129	0.000	0.012	0.091
data write	00:00:36.592	0.0%	313191	0.000	0.117	0.269
idle	23:57:41.583	99.8%	578411	11.595	149.135	4032.800
status file	00:00:00.741	0.0%	1043	0.000	0.710	2.000
TOTAL: 24:00:00.494	23:59:48.271	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:32:05.801	99.4%	3088	0.000	623.640	600046.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.209	0.0%	6839	0.000	0.031	3.056
readart	00:00:00.079	0.0%	65	0.000	1.215	8.500
TOTAL: 00:32:18.180	00:32:06.089	99.4%	-	-	-	-

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	288	2464985	2454287	4974	5724	99%	180:23:07
2	news.nask.pl	2	22603	10787	3318	8498	47%	23:52:38
3	news.icm.edu.pl	37	409	27	350	32	6%	17:51:50
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		333	2488002	2465106	8642	14254	99%	222:07:35

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	772.7 GB	39.8 MB	40.7 MB	772.7 GB	99%	329.4 KB
2	news.nask.pl	57.5 MB	1.8 MB	90.6 MB	149.9 MB	38%	8.0 KB
3	news.icm.edu.pl	1.3 MB	0.0 KB	1.4 MB	2.7 MB	48%	46.2 KB
4	localhost	9.0 KB	0.0 KB	0.0 KB	9.0 KB	100%	1.8 KB
TOTAL: 4		772.7 GB	41.6 MB	132.7 MB	772.9 GB	99%	326.9 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 4 04:15:01 - 04:59:59	70956	2.9%	26.29	23.1 GB	3.0%	8972.24
Aug 4 05:00:00 - 05:59:59	73946	3.0%	20.54	23.5 GB	3.0%	6852.42
Aug 4 06:00:00 - 06:59:59	97108	3.9%	26.97	30.3 GB	3.9%	8835.23
Aug 4 07:00:00 - 07:59:59	116171	4.7%	32.27	34.9 GB	4.5%	10157.99
Aug 4 08:00:00 - 08:59:59	101266	4.1%	28.13	31.1 GB	4.0%	9056.14
Aug 4 09:00:00 - 09:59:59	105435	4.3%	29.29	29.6 GB	3.8%	8625.57
Aug 4 10:00:00 - 10:59:59	79969	3.2%	22.21	22.5 GB	2.9%	6555.21
Aug 4 11:00:00 - 11:59:59	91521	3.7%	25.42	31.4 GB	4.1%	9152.43
Aug 4 12:00:00 - 12:59:59	120937	4.9%	33.59	34.0 GB	4.4%	9908.42
Aug 4 13:00:00 - 13:59:59	106613	4.3%	29.61	32.0 GB	4.1%	9322.74
Aug 4 14:00:00 - 14:59:59	76713	3.1%	21.31	25.5 GB	3.3%	7433.30
Aug 4 15:00:00 - 15:59:59	110417	4.5%	30.67	34.3 GB	4.4%	10003.03
Aug 4 16:00:00 - 16:59:59	109621	4.4%	30.45	36.4 GB	4.7%	10592.74
Aug 4 17:00:00 - 17:59:59	99927	4.1%	27.76	34.3 GB	4.4%	9979.63
Aug 4 18:00:00 - 18:59:59	103386	4.2%	28.72	37.5 GB	4.8%	10913.57
Aug 4 19:00:00 - 19:59:59	101481	4.1%	28.19	35.0 GB	4.5%	10201.75
Aug 4 20:00:00 - 20:59:59	105004	4.3%	29.17	34.6 GB	4.5%	10078.70
Aug 4 21:00:00 - 21:59:59	122905	5.0%	34.14	36.0 GB	4.7%	10478.78
Aug 4 22:00:00 - 22:59:59	128763	5.2%	35.77	38.4 GB	5.0%	11191.58
Aug 4 23:00:00 - 23:59:59	126988	5.2%	35.27	36.1 GB	4.7%	10526.57
Aug 5 00:00:00 - 00:59:59	97090	3.9%	26.97	27.1 GB	3.5%	7893.01
Aug 5 01:00:00 - 01:59:59	99331	4.0%	27.59	31.5 GB	4.1%	9176.98
Aug 5 02:00:00 - 02:59:59	102076	4.1%	28.35	34.5 GB	4.5%	10055.65
Aug 5 03:00:00 - 03:59:59	95369	3.9%	26.49	31.3 GB	4.1%	9125.17
Aug 5 04:00:00 - 04:15:01	22378	0.9%	24.84	7.7 GB	1.0%	8942.65
TOTAL: 24:00:00	2465371	100.0%	28.53	772.8 GB	100.0%	9378.39

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	8752	19	563	6	8164
2	newsfeed.pionier.net.pl	6090	190	5473	0	427
3	news.icm.edu.pl	32	0	0	0	32
TOTAL: 3		14874	209	6036	6	8623

Unwanted newsgroups [Top 20]:

Newsgroup	Count
it.comp.os.win.windows10	103
uk.railway	59
it.tlc.cellulari.android	10
comp.mobile.android	10
comp.mobile.ipad	8
de.rec.sf.startrek	6
nl.comp.3d-printen	3
hr.ponuda.hardver.konfiguracije	2
it.comp.os.win.windows7	2
de.comm.software.mailreader	2
nl.comp.sys.android	1
hr.ponuda.hardver.komponente	1
de.comp.lang.vbclassic	1
hr.rec.kampiranje	1
TOTAL: 14	209

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

Groups	Count
christnet.christianlife	4
pl.praca.oferowana	1
bit.listserv.catholic	1
TOTAL: 3	6

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	7720
Binary in non-binary group	139
Scoring filter (8)	79
EMP (phl)	55
Scoring filter (9)	33
Scoring filter (10)	9
Too many newsgroups	8
Scoring filter (11)	6
HTML post	6
Scoring filter (12)	6
Scoring filter (13)	5
Scoring filter (21)	2
Scoring filter (24)	1
PFM bot misc	1
TOTAL: 14	8070

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	96195	53208	20069	16290	1	5827	55%	24:00:15
2	panorama	36091	2832	30541	1035	36	104263	7%	24:04:01
3	poznan	16771	112	10657	29	0	0	0%	23:55:40
4	nask	6947	17	4487	49	0	0	0%	24:07:34
5	okapi	0	0	0	0	0	2127	0%	24:00:00
TOTAL: 5		156004	56169	65754	17403	37	112217	36%	120:07:30

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	9.4 GB	3.6 GB	13.0 GB	157.9 KB/s	196.3 KB	24:00:15
2	panorama	10.3 MB	4.2 MB	14.5 MB	0.2 KB/s	3.8 KB	24:04:01
3	poznan	742.5 KB	100.9 KB	843.4 KB	0.0 KB/s	6.0 KB	23:55:40
4	nask	64.9 KB	25.3 MB	25.4 MB	0.3 KB/s	393.6 KB	24:07:34
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		9.4 GB	3.6 GB	13.1 GB	31.6 KB/s	186.0 KB	120:07:30

Backlog files shrunk by innfeed:

Server	Size
panorama	3.8 MB
okapi	488.4 KB
TOTAL: 2	4.3 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	287	54	49.9 KB	26	0	00:00:21
2	oqu42.internetdsl.tpnet.pl	4	2	3.3 KB	1	0	00:30:28
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:00
TOTAL: 3		296	56	53.3 KB	27	5	00:32:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	676	54	49.9 KB	26	5	00:00:25
2	*.internetdsl.tpnet.pl	4	2	3.3 KB	1	0	00:30:28
3	*.icpnet.pl	1	0	0.0 KB	0	0	00:01:20
4	unresolved	1	0	0.0 KB	0	0	00:00:02
TOTAL: 4		682	56	53.3 KB	27	5	00:32:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
oqu42.internetdsl.tpnet.pl	0.022	0.064	00:30:28
d158-242.icpnet.pl	0.038	0.015	00:01:20
web.man.lodz.pl	0.992	5.320	00:00:21
mon.man.lodz.pl	0.864	4.547	00:00:03
196.52.22.41	0.005	0.010	00:00:02
david.man.lodz.pl	0.274	1.649	00:00:01
isvcs01.man.lodz.pl	0.028	0.102	00:00:00
TOTAL: 7	2.223	11.707	00:32:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
d158-242.icpnet.pl	1
196.52.22.41	1
TOTAL: 4	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
oqu42.internetdsl.tpnet.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	54	96.4%
2	lodman	2	3.6%
TOTAL: 2		56	100.0%

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

Newsgroup		Count
1	pl.comp.pecet	30
2	pl.comp.os.ms-windows.winnt	24
3	lodman.info	2
TOTAL: 3		56

Daily Usenet report

Aug 4 04:15:01 -- Aug 5 04:15:01