Daily Usenet report: Nov 17 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Nov 17 08:57:38 newsfeed nnrpd[26803]: SERVER perl filter not defined
Nov 17 13:10:04 newsfeed pgpverify[2901]: pgpverify: verification failed
Nov 17 14:42:12 newsfeed nnrpd[5870]: unable to get certificate from '/etc/news/cert.pem'
Nov 17 14:42:12 newsfeed nnrpd[5870]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Nov 17 18:55:40 newsfeed overchan[2236]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2150422	98.4%	243.3 MB	98.0%
innd	18611	0.9%	3.1 MB	1.2%
nnrpd	10524	0.5%	1.1 MB	0.4%
innfeed	6335	0.3%	944.6 KB	0.4%
controlchan	3	0.0%	0.4 KB	0.0%
overchan	1	0.0%	0.2 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	2185897	100.0%	248.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2144780	95.3%
Negative hits	72538	3.2%
Positive hits	33930	1.5%
Cache misses	226	0.0%
TOTAL: 4	2251474	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:11.147	0.0%	358	0.000	31.137	435.000
article cleanup	00:00:28.316	0.0%	2151153	0.004	0.013	0.033
article logging	00:01:04.537	0.1%	2151283	0.012	0.030	0.197
article parse	01:01:33.390	4.3%	57191688	0.025	0.065	0.092
article write	00:20:13.594	1.4%	2144959	0.330	0.566	913.333
artlog/artcncl	00:00:00.008	0.0%	353	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:06:42.344	0.5%	69464141	0.000	0.006	1.094
hisgrep/artcncl	00:00:01.181	0.0%	245	0.000	4.820	29.250
hishave/artcncl	00:00:00.737	0.0%	358	0.000	2.059	45.250
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	02:08:32.472	8.9%	2253255	0.012	3.423	53.879
history sync	00:00:00.017	0.0%	293	0.000	0.058	1.000
history write	04:31:33.920	18.8%	2146622	0.542	7.590	1030.433
hiswrite/artcncl	00:00:00.804	0.0%	113	0.000	7.115	227.000
idle	09:14:42.440	38.5%	25315365	0.252	1.315	3.933
nntp read	00:31:19.200	2.2%	55416498	0.016	0.034	0.435
overview write	00:00:13.429	0.0%	2144959	0.000	0.006	0.016
perl filter	05:38:53.500	23.5%	2146618	5.920	9.472	14.322
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:17.537	0.3%	4327521	0.033	0.060	0.089
TOTAL: 24:00:42.395	23:39:48.573	98.5%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.129	0.0%	37595	0.000	0.003	0.200
article prepare	00:00:00.003	0.0%	11964	0.000	0.000	0.125
article read	00:00:00.178	0.0%	2958	0.000	0.060	9.667
backlog stats	00:00:00.140	0.0%	175051	0.000	0.001	0.026
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.207	0.0%	111772	0.000	0.011	0.078
data write	00:00:02.215	0.0%	74461	0.000	0.030	3.000
idle	23:59:40.266	100.0%	175051	0.000	493.458	25100.000
status file	00:00:01.504	0.0%	237	0.000	6.346	198.000
TOTAL: 23:59:50.499	23:59:45.642	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.962	0.0%	8868	0.108	0.108	0.108
idle	74:10:47.379	100.0%	382659	0.000	697.873	608860.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:13.412	0.0%	768748	0.000	0.017	10.923
readart	00:00:05.215	0.0%	9610	0.000	0.543	183.000
TOTAL: 74:12:28.121	74:11:06.968	100.0%	-	-	-	-

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
newsmaster@carnet.hr	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	1014	2136860	2132199	1359	3302	99%	407:51:11
2	news.task.gda.pl	13	28495	7887	19540	1068	27%	45:23:13
3	news.nask.pl	2	14937	4316	8767	1854	28%	23:43:16
4	localhost	2	1	1	0	0	100%	00:00:00
TOTAL: 4		1031	2180293	2144403	29666	6224	98%	476:57:40

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	748.9 GB	28.6 MB	116.4 MB	749.1 GB	99%	367.8 KB
2	news.nask.pl	14.3 MB	4.5 MB	4.6 MB	23.5 MB	61%	3.9 KB
3	news.task.gda.pl	13.9 MB	37.3 KB	2.7 MB	16.6 MB	83%	1.9 KB
4	localhost	1.5 KB	0.0 KB	0.0 KB	1.5 KB	100%	1.5 KB
TOTAL: 4		749.0 GB	33.1 MB	123.7 MB	749.1 GB	99%	365.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 17 04:15:02 - 04:59:59	75062	3.5%	27.82	26.8 GB	3.6%	10408.54
Nov 17 05:00:00 - 05:59:59	102366	4.8%	28.43	37.1 GB	5.0%	10802.04
Nov 17 06:00:00 - 06:59:59	96106	4.5%	26.70	36.7 GB	4.9%	10679.85
Nov 17 07:00:00 - 07:59:59	68754	3.2%	19.10	26.6 GB	3.6%	7760.19
Nov 17 08:00:00 - 08:59:59	62583	2.9%	17.38	24.1 GB	3.2%	7023.28
Nov 17 09:00:00 - 09:59:59	74260	3.5%	20.63	27.4 GB	3.7%	7977.65
Nov 17 10:00:00 - 10:59:59	80654	3.8%	22.40	29.3 GB	3.9%	8530.78
Nov 17 11:00:00 - 11:59:59	74756	3.5%	20.77	26.9 GB	3.6%	7828.75
Nov 17 12:00:00 - 12:59:59	100093	4.7%	27.80	34.2 GB	4.6%	9954.21
Nov 17 13:00:00 - 13:59:59	104755	4.9%	29.10	36.5 GB	4.9%	10621.86
Nov 17 14:00:00 - 14:59:59	72321	3.4%	20.09	24.6 GB	3.3%	7176.54
Nov 17 15:00:00 - 15:59:59	89857	4.2%	24.96	30.4 GB	4.1%	8840.48
Nov 17 16:00:00 - 16:59:59	86440	4.0%	24.01	30.3 GB	4.1%	8835.79
Nov 17 17:00:00 - 17:59:59	102196	4.8%	28.39	36.4 GB	4.9%	10591.63
Nov 17 18:00:00 - 18:59:59	103776	4.8%	28.83	36.2 GB	4.8%	10545.42
Nov 17 19:00:00 - 19:59:59	98393	4.6%	27.33	33.5 GB	4.5%	9749.40
Nov 17 20:00:00 - 20:59:59	89549	4.2%	24.87	30.6 GB	4.1%	8925.11
Nov 17 21:00:00 - 21:59:59	92515	4.3%	25.70	31.5 GB	4.2%	9165.50
Nov 17 22:00:00 - 22:59:59	88560	4.1%	24.60	30.3 GB	4.0%	8813.32
Nov 17 23:00:00 - 23:59:59	112976	5.3%	31.38	34.4 GB	4.6%	10026.73
Nov 18 00:00:00 - 00:59:59	88533	4.1%	24.59	27.5 GB	3.7%	8020.83
Nov 18 01:00:00 - 01:59:59	81380	3.8%	22.61	27.6 GB	3.7%	8044.31
Nov 18 02:00:00 - 02:59:59	79092	3.7%	21.97	27.5 GB	3.7%	8010.09
Nov 18 03:00:00 - 03:59:59	94937	4.4%	26.37	33.6 GB	4.5%	9800.11
Nov 18 04:00:00 - 04:15:04	23825	1.1%	26.36	8.5 GB	1.1%	9809.27
TOTAL: 24:00:02	2143739	100.0%	24.81	748.4 GB	100.0%	9082.61

Sites sending bad articles:

Server		Total	Group	Duplic	Other
1	newsfeed.pionier.net.pl	3401	141	2681	579
2	news.nask.pl	1883	27	1837	19
3	news.task.gda.pl	1046	770	19	257
TOTAL: 3		6330	938	4537	855

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	90
mailing.freebsd.svn-ports-head	90
uk.railway	50
mailing.freebsd.ports-bugs	44
alt.windows7.general	39
mailing.freebsd.svn-src-all	35
fa.freebsd.cvs-all	34
alt.politics.scorched-earth	33
mailing.freebsd.svn-src-head	33
comp.mobile.ipad	31
fr.usenet.abus.nocem	30
mailing.openbsd.ports-changes	29
mailing.database.pgsql-general	26
mailing.openbsd.source-changes	26
comp.sys.raspberry-pi	21
mailing.gcc.gcc-cvs	20
mailing.freebsd.ports	20
mailing.postfix.users	18
mailing.openbsd.misc	18
mozilla.dev.tree-management	17
TOTAL: 73	938

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	473
EMP (fsl)	162
EMP (phl)	70
Attached HTML file	8
EMP (md5)	7
HTML post	1
TOTAL: 6	721

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	38411	2324	34729	548	0	6%	24:00:00
2	task	1874	50	960	34	0	2%	21:14:36
3	poznan	13377	12	11837	9	0	0%	24:02:53
4	nask	17915	11	12171	5	0	0%	24:00:29
5	okapi	0	0	0	0	1789	0%	24:00:04
6	icm	0	0	0	0	35000	0%	24:00:04
TOTAL: 6		71577	2397	59697	596	36789	3%	141:18:06

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	6.3 MB	1.8 MB	8.1 MB	0.1 KB/s	2.9 KB	24:00:00
2	task	106.7 KB	78.5 KB	185.3 KB	0.0 KB/s	2.2 KB	21:14:36
3	poznan	33.6 KB	25.9 KB	59.5 KB	0.0 KB/s	2.8 KB	24:02:53
4	nask	32.2 KB	1.9 MB	1.9 MB	0.0 KB/s	123.8 KB	24:00:29
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
6	icm	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:04
TOTAL: 6		6.4 MB	3.8 MB	10.2 MB	0.0 KB/s	3.5 KB	141:18:06

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	300	450.6 KB	288	0	0	00:00:14
2	159-205-139-102.adsl.inetia.pl	4	189	181.8 KB	2	0	0	00:39:53
3	host-217-172-248-130.lodz.mm.pl	4	189	181.8 KB	3	0	1	00:14:52
4	web.man.lodz.pl	288	82	84.2 KB	36	0	0	00:00:29
5	119.169.rev.vline.pl	26	34	35.6 KB	8	0	6	00:33:56
6	apn-95-41-61-80.dynamic.gprs.plus.pl	31	12	9.3 KB	6	0	9	00:53:39
7	37.190.191.44	16	11	9.3 KB	3	0	8	00:23:32
8	staticline-31-182-70-59.toya.net.pl	13	8	6.6 KB	2	0	4	00:39:45
9	host-89-228-167-3.lodz.mm.pl	4	5	3.9 KB	1	0	2	00:09:32
10	aaid146.neoplus.adsl.tpnet.pl	3	2	1.8 KB	1	0	1	00:07:25
11	ip-176.105.139.193.tvsat364.lodz.pl	6	2	2.1 KB	1	0	2	00:47:22
12	smrw-91-193-84-252.smrw.lodz.pl	5	1	0.8 KB	1	0	3	00:19:34
13	host-89-231-227-206.trzebinia.mm.pl	3	1	0.9 KB	1	0	1	00:32:56
14	staticline-31-182-142-159.toya.net.pl	1	1	0.9 KB	1	0	0	00:02:09
15	host-217-172-241-174.gdynia.mm.pl	3	0	0.0 KB	0	0	3	00:04:34
16	kujawiak.man.lodz.pl	1	0	0.0 KB	0	1	0	00:00:00
17	apn-46-169-17-167.dynamic.gprs.plus.pl	1	0	0.0 KB	0	0	1	00:02:22
TOTAL: 17		697	837	969.3 KB	354	1	41	74:12:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	300	450.6 KB	288	0	0	00:00:14
2	*.lodz.mm.pl	8	194	185.8 KB	4	0	3	00:24:25
3	*.adsl.inetia.pl	4	189	181.8 KB	2	0	0	00:39:53
4	*.man.lodz.pl	676	82	84.2 KB	36	1	0	00:00:38
5	*.169.rev.vline.pl	26	34	35.6 KB	8	0	6	00:33:56
6	*.dynamic.gprs.plus.pl	32	12	9.3 KB	6	0	10	00:56:01
7	unresolved	302	11	9.3 KB	3	0	8	48:17:20
8	*.toya.net.pl	21	9	7.4 KB	3	0	4	00:47:12
9	*.neoplus.adsl.tpnet.pl	3	2	1.8 KB	1	0	1	00:07:25
10	*.105.139.193.tvsat364.lodz.pl	6	2	2.1 KB	1	0	2	00:47:22
11	*.trzebinia.mm.pl	3	1	0.9 KB	1	0	1	00:32:56
12	*.smrw.lodz.pl	6	1	0.8 KB	1	0	3	00:21:43
13	*.207.15.62.static.jazztel.es	3	0	0.0 KB	0	0	0	19:39:57
14	*.gdynia.mm.pl	3	0	0.0 KB	0	0	3	00:04:34
15	*.grs-k0.g1.pl	1	0	0.0 KB	0	0	0	00:02:28
16	*.1blu.de	1	0	0.0 KB	0	0	0	00:00:00
17	*.ssp.dialog.net.pl	1	0	0.0 KB	0	0	0	00:33:35
18	*.105.135.22.tvsat364.lodz.pl	2	0	0.0 KB	0	0	0	00:22:36
TOTAL: 18		1386	837	969.3 KB	354	1	41	74:12:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.684	4.284	47:53:48
155.207.15.62.static.jazztel.es	23.128	37.804	19:39:57
apn-95-41-61-80.dynamic.gprs.plus.pl	0.288	0.508	00:53:39
ip-176.105.139.193.tvsat364.lodz.pl	0.040	0.108	00:47:22
159-205-139-102.adsl.inetia.pl	0.048	0.100	00:39:53
staticline-31-182-70-59.toya.net.pl	0.068	0.184	00:39:45
119.169.rev.vline.pl	0.096	0.388	00:33:56
dynamic-78-8-112-56.ssp.dialog.net.pl	0.096	0.016	00:33:35
host-89-231-227-206.trzebinia.mm.pl	0.032	0.048	00:32:56
37.190.191.44	0.140	0.208	00:23:32
ip-176.105.135.22.tvsat364.lodz.pl	0.004	0.036	00:22:36
smrw-91-193-84-252.smrw.lodz.pl	0.032	0.108	00:19:34
host-217-172-248-130.lodz.mm.pl	0.052	0.108	00:14:52
host-89-228-167-3.lodz.mm.pl	0.056	0.080	00:09:32
aaid146.neoplus.adsl.tpnet.pl	0.036	0.060	00:07:25
staticline40675.toya.net.pl	0.032	0.016	00:05:06
host-217-172-241-174.gdynia.mm.pl	0.064	0.056	00:04:34
18.grs-k0.g1.pl	0.020	0.032	00:02:28
apn-46-169-17-167.dynamic.gprs.plus.pl	0.052	0.032	00:02:22
staticline-31-182-142-159.toya.net.pl	0.036	0.012	00:02:09
TOTAL: 29	27.204	58.756	74:12:24

Curious NNRP server explorers [Top 20]:

System	Conn
f-oper.man.lodz.pl	291
212.191.78.190	286
david.man.lodz.pl	96
staticline41435.toya.net.pl	3
155.207.15.62.static.jazztel.es	3
staticline-31-182-158-95.toya.net.pl	3
ip-176.105.135.22.tvsat364.lodz.pl	2
smrw-194-110-116-81.smrw.lodz.pl	1
staticline40675.toya.net.pl	1
dynamic-78-8-112-56.ssp.dialog.net.pl	1
18.grs-k0.g1.pl	1
sh-69.1blu.de	1
TOTAL: 12	689

NNRP client timeouts [Top 20]:

System	Conn	Peer
155.207.15.62.static.jazztel.es	2	0
host-89-231-227-206.trzebinia.mm.pl	1	0
159-205-139-102.adsl.inetia.pl	1	0
staticline-31-182-70-59.toya.net.pl	1	0
ip-176.105.139.193.tvsat364.lodz.pl	1	0
dynamic-78-8-112-56.ssp.dialog.net.pl	1	0
TOTAL: 6	7	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodz	445	53.2%
2	lodman	307	36.7%
3	pl	82	9.8%
4	lodzman	3	0.4%
TOTAL: 4		837	100.0%

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

Newsgroup		Count
1	lodz.test	445
2	lodman.info	300
3	pl.comp.os.ms-windows.winnt	42
4	pl.comp.pecet	40
5	lodman.test	7
6	lodzman.test	3
TOTAL: 6		837

Daily Usenet report

Nov 17 04:15:02 -- Nov 18 04:15:04