Daily Usenet report: Dec 2 04:15:03

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Dec 2 08:45:03 newsfeed pgpverify[9739]: pgpverify: verification failed
Dec 2 16:46:13 newsfeed overchan[31600]: input line too long, skipping
Dec 2 16:52:40 newsfeed overchan[31600]: input line too long, skipping
Dec 2 17:07:25 newsfeed overchan[31600]: input line too long, skipping
Dec 2 20:12:51 newsfeed overchan[31600]: input line too long, skipping
Dec 2 20:16:07 newsfeed overchan[31600]: input line too long, skipping
Dec 3 01:31:25 newsfeed overchan[31600]: input line too long, skipping
Dec 3 03:19:14 newsfeed overchan[31600]: input line too long, skipping
Dec 3 03:20:19 newsfeed overchan[31600]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	4169609	98.3%	477.1 MB	98.0%
innfeed	42595	1.0%	5.0 MB	1.0%
innd	25940	0.6%	4.3 MB	0.9%
nnrpd	4751	0.1%	501.0 KB	0.1%
overchan	9	0.0%	1.5 KB	0.0%
controlchan	4	0.0%	0.5 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	4242909	100.0%	486.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	4143027	97.8%
Negative hits	83696	2.0%
Positive hits	10019	0.2%
Cache misses	415	0.0%
TOTAL: 4	4237157	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:07.880	0.0%	483	0.000	16.315	299.000
article cleanup	00:00:53.008	0.1%	4169240	0.007	0.013	0.020
article logging	00:02:37.181	0.2%	4169955	0.021	0.038	0.118
article parse	01:07:39.899	4.7%	102054820	0.015	0.040	0.064
article write	00:24:42.612	1.7%	4162649	0.139	0.356	0.825
artlog/artcncl	00:00:00.015	0.0%	473	0.000	0.032	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:31.086	0.2%	120670775	0.001	0.001	0.003
hisgrep/artcncl	00:00:02.516	0.0%	476	0.000	5.286	162.500
hishave/artcncl	00:00:01.039	0.0%	483	0.000	2.151	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:18:29.032	9.6%	4261737	0.259	1.950	7.728
history sync	00:00:00.008	0.0%	290	0.000	0.028	1.000
history write	05:45:09.299	24.0%	4166476	1.459	4.970	18.660
hiswrite/artcncl	00:00:00.081	0.0%	7	3.333	11.571	18.000
idle	05:22:30.734	22.4%	54794868	0.114	0.353	0.858
nntp read	01:02:00.916	4.3%	98454515	0.020	0.038	0.053
overview write	00:00:21.794	0.0%	4162649	0.003	0.005	0.007
perl filter	07:15:56.792	30.3%	4166453	3.053	6.278	9.052
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:31.148	0.5%	8343133	0.028	0.047	0.074
TOTAL: 24:00:06.367	23:29:35.040	97.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.169	0.0%	55060	0.000	0.003	0.026
article prepare	00:00:00.003	0.0%	13170	0.000	0.000	0.016
article read	00:01:38.973	0.1%	3400	1.706	29.110	175.667
backlog stats	00:00:00.139	0.0%	146779	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	105	0.000	0.000	0.000
data read	00:00:01.411	0.0%	79163	0.005	0.018	0.038
data write	00:00:03.486	0.0%	61968	0.018	0.056	0.177
idle	23:52:47.924	99.8%	146779	363.792	585.696	1035.152
status file	00:00:24.306	0.0%	15847	0.675	1.534	68.411
TOTAL: 23:55:08.365	23:54:56.411	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:59.151	82.1%	3206	0.000	18.450	373.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.270	0.4%	7080	0.000	0.038	0.667
readart	00:00:00.001	0.0%	46	0.000	0.022	0.500
TOTAL: 00:01:12.055	00:00:59.422	82.5%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	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	211	4161804	4153014	5974	2816	99%	551:42:30
2	news.nask.pl	2	13490	8767	990	3733	64%	23:53:55
3	news.icm.edu.pl	41	304	29	261	14	9%	17:29:58
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		255	4175598	4161810	7225	6563	99%	593:06:23

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	926.0 GB	23.5 MB	32.7 MB	926.1 GB	99%	233.7 KB
2	news.nask.pl	44.6 MB	737.7 KB	18.6 MB	63.9 MB	69%	5.2 KB
3	news.icm.edu.pl	1.1 MB	9.3 KB	583.0 KB	1.7 MB	65%	40.0 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		926.1 GB	24.2 MB	51.8 MB	926.2 GB	99%	233.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 2 04:15:03 - 04:59:59	99443	2.4%	36.87	29.6 GB	3.2%	11514.16
Dec 2 05:00:00 - 05:59:59	122795	3.0%	34.11	38.9 GB	4.2%	11330.83
Dec 2 06:00:00 - 06:59:59	118268	2.8%	32.85	36.4 GB	3.9%	10602.44
Dec 2 07:00:00 - 07:59:59	121788	2.9%	33.83	39.2 GB	4.2%	11413.88
Dec 2 08:00:00 - 08:59:59	133462	3.2%	37.07	39.5 GB	4.3%	11510.42
Dec 2 09:00:00 - 09:59:59	133334	3.2%	37.04	40.5 GB	4.4%	11797.49
Dec 2 10:00:00 - 10:59:59	147047	3.5%	40.85	38.3 GB	4.1%	11164.19
Dec 2 11:00:00 - 11:59:59	151414	3.6%	42.06	39.2 GB	4.2%	11413.31
Dec 2 12:00:00 - 12:59:59	133295	3.2%	37.03	36.8 GB	4.0%	10724.69
Dec 2 13:00:00 - 13:59:59	171057	4.1%	47.52	42.2 GB	4.6%	12297.76
Dec 2 14:00:00 - 14:59:59	173675	4.2%	48.24	43.1 GB	4.7%	12546.07
Dec 2 15:00:00 - 15:59:59	232250	5.6%	64.51	40.1 GB	4.3%	11668.58
Dec 2 16:00:00 - 16:59:59	326406	7.8%	90.67	37.0 GB	4.0%	10782.63
Dec 2 17:00:00 - 17:59:59	348569	8.4%	96.82	34.3 GB	3.7%	10002.46
Dec 2 18:00:00 - 18:59:59	364662	8.8%	101.30	35.3 GB	3.8%	10290.09
Dec 2 19:00:00 - 19:59:59	168179	4.0%	46.72	35.0 GB	3.8%	10208.55
Dec 2 20:00:00 - 20:59:59	153645	3.7%	42.68	37.9 GB	4.1%	11048.11
Dec 2 21:00:00 - 21:59:59	137278	3.3%	38.13	37.7 GB	4.1%	10980.07
Dec 2 22:00:00 - 22:59:59	166914	4.0%	46.37	40.6 GB	4.4%	11819.19
Dec 2 23:00:00 - 23:59:59	168076	4.0%	46.69	41.0 GB	4.4%	11932.22
Dec 3 00:00:00 - 00:59:59	145356	3.5%	40.38	33.1 GB	3.6%	9647.08
Dec 3 01:00:00 - 01:59:59	148282	3.6%	41.19	42.4 GB	4.6%	12349.68
Dec 3 02:00:00 - 02:59:59	144671	3.5%	40.19	41.0 GB	4.4%	11955.05
Dec 3 03:00:00 - 03:59:59	119304	2.9%	33.14	36.3 GB	3.9%	10570.75
Dec 3 04:00:00 - 04:15:04	32687	0.8%	36.16	10.0 GB	1.1%	11641.07
TOTAL: 24:00:01	4161857	100.0%	48.17	925.6 GB	100.0%	11233.40

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	3984	31	281	6	3666
2	newsfeed.pionier.net.pl	3280	212	2457	3	608
3	news.icm.edu.pl	15	0	1	0	14
TOTAL: 3		7279	243	2739	9	4288

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	110
it.comp.os.win.windows10	57
comp.mobile.android	25
it.tlc.cellulari.android	11
nl.comp.os.linux.techniek	10
it.comp.os.linux.ubuntu	10
comp.mobile.ipad	4
nl.comp.sys.android	3
comp.sys.raspberry-pi	2
rec.arts.tv.x-factor	2
alt.sex.telephones	2
it.comp.os.win.windows8	1
nl.actueel	1
nl.wetenschap.regulier	1
nl.gezondheid.alternatief-genezen	1
de.comp.office-pakete.ms-office.word	1
de.comm.software.mailreader	1
nl.comp.3d-printen	1
TOTAL: 18	243

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

Groups	Count
pl.praca.oferowana	5
alt.france.homos.rencontres	2
pl.sci.historia	1
bit.listserv.catholic	1
TOTAL: 4	9

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3245
Binary in non-binary group	188
EMP (phl)	31
Scoring filter (9)	30
Scoring filter (8)	25
EMP (fsl)	10
Scoring filter (11)	8
HTML post	5
Scoring filter (12)	2
Excessively crossposted test article	2
Scoring filter (10)	2
Too many newsgroups	2
TOTAL: 12	3550

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	464
	news.nask.pl	250
	news.icm.edu.pl	1
	TOTAL: 3	715
TOTAL: 1		715

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	43267	1423	14769	425	277	38394	3%	23:59:59
2	poznan	13758	266	8540	13	0	0	1%	23:59:59
3	icm	20136	41	17287	72	0	0	0%	23:59:56
4	nask	2922	3	2535	0	0	0	0%	23:59:55
5	okapi	0	0	0	0	0	1078	0%	23:57:52
TOTAL: 5		80083	1733	43131	510	277	39472	2%	119:57:41

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.8 MB	3.8 MB	9.6 MB	0.1 KB/s	5.3 KB	23:59:59
2	icm	2.8 MB	19.1 MB	21.9 MB	0.3 KB/s	198.3 KB	23:59:56
3	poznan	1.9 MB	171.9 KB	2.1 MB	0.0 KB/s	7.6 KB	23:59:59
4	nask	4.4 KB	0.0 KB	4.4 KB	0.0 KB/s	1.5 KB	23:59:55
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:52
TOTAL: 5		10.5 MB	23.0 MB	33.6 MB	0.1 KB/s	15.3 KB	119:57:41

Backlog files shrunk by innfeed:

Server	Size
panorama	488.3 KB
TOTAL: 1	488.3 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	46	40.4 KB	21	0	0	00:00:36
TOTAL: 1		288	46	40.4 KB	21	0	0	00:01:11

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	46	40.4 KB	21	00:00:42
2	unresolved	1	0	0.0 KB	0	00:00:29
TOTAL: 2		673	46	40.4 KB	21	00:01:11

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.548	8.604	00:00:36
185.48.193.35	0.016	0.048	00:00:29
mon.man.lodz.pl	1.120	8.008	00:00:04
david.man.lodz.pl	0.452	2.776	00:00:01
TOTAL: 4	3.136	19.436	00:01:11

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.193.35	1
TOTAL: 3	385

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.35	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	28
2	pl.comp.pecet	18
TOTAL: 2		46

Daily Usenet report

Dec 2 04:15:03 -- Dec 3 04:15:04