Daily Usenet report: Nov 3 04:15:02

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Nov 3 04:15:02 newsfeed nnrpd[30915]: web.man.lodz.pl rejected Flushing log and syslog files
Nov 3 04:15:02 newsfeed nnrpd[30928]: web.man.lodz.pl rejected Flushing log and syslog files
Nov 3 11:17:51 newsfeed nnrpd[12916]: SERVER perl filter not defined
Nov 3 11:35:53 newsfeed nnrpd[13573]: SERVER perl filter not defined
Nov 3 11:37:47 newsfeed nnrpd[13756]: SERVER perl filter not defined
Nov 3 14:56:04 newsfeed nnrpd[20253]: SERVER perl filter not defined
Nov 3 15:29:19 newsfeed nnrpd[21453]: SERVER perl filter not defined
Nov 4 01:32:11 newsfeed pgpverify[9024]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1495710	98.1%	171.9 MB	97.7%
innd	14890	1.0%	2.4 MB	1.4%
nnrpd	7122	0.5%	760.4 KB	0.4%
innfeed	6571	0.4%	970.9 KB	0.5%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	1524297	100.0%	176.0 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1491596	96.4%
Negative hits	37223	2.4%
Positive hits	17645	1.1%
Cache misses	128	0.0%
TOTAL: 4	1546592	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:09.472	0.0%	335	0.000	28.275	555.000
article cleanup	00:00:26.909	0.0%	1495712	0.000	0.018	0.037
article logging	00:00:40.815	0.0%	1495834	0.000	0.027	0.365
article parse	01:00:22.094	4.2%	124997490	0.009	0.029	0.059
article write	00:14:03.535	1.0%	1490487	0.269	0.566	1.589
artlog/artcncl	00:00:00.005	0.0%	333	0.000	0.015	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:21.290	0.2%	134398303	0.001	0.001	0.005
hisgrep/artcncl	00:00:00.745	0.0%	294	0.000	2.534	192.000
hishave/artcncl	00:00:00.371	0.0%	335	0.000	1.107	16.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:41:48.593	7.1%	1546819	0.032	3.949	15.253
history sync	00:00:00.017	0.0%	290	0.000	0.059	2.000
history write	02:54:14.733	12.1%	1492084	0.780	7.007	25.133
hiswrite/artcncl	00:00:00.438	0.0%	41	0.000	10.683	53.800
idle	12:58:59.242	54.1%	84230832	0.244	0.555	2.755
nntp read	00:55:02.379	3.8%	123769829	0.012	0.027	0.039
overview write	00:00:09.707	0.0%	1490487	0.000	0.007	0.014
perl filter	03:31:49.528	14.7%	1492083	5.348	8.518	11.288
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:54.846	0.2%	3013478	0.019	0.058	0.091
TOTAL: 24:00:25.014	23:23:04.719	97.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.100	0.0%	32497	0.000	0.003	1.000
article prepare	00:00:00.005	0.0%	14580	0.000	0.000	0.125
article read	00:00:00.319	0.0%	3565	0.000	0.089	1.000
backlog stats	00:00:00.122	0.0%	211290	0.000	0.001	0.027
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.391	0.0%	134839	0.000	0.010	1.000
data write	00:00:03.761	0.0%	104958	0.000	0.036	1.000
idle	23:59:22.429	100.0%	211290	0.000	408.739	16282.000
status file	00:00:00.750	0.0%	189	0.000	3.968	87.750
TOTAL: 23:59:33.533	23:59:28.877	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	05:33:45.051	99.8%	32847	0.000	609.646	296631.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:11.244	0.1%	250901	0.000	0.045	5.000
readart	00:00:11.946	0.1%	1417	0.000	8.430	20.000
TOTAL: 05:34:32.491	05:34:08.241	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
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	707	1491263	1485943	1016	4304	99%	301:00:37
2	news.nask.pl	2	18147	4480	12840	827	24%	24:34:44
3	news.icm.edu.pl	43	430	46	282	102	10%	20:49:56
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		758	1509845	1490474	14138	5233	98%	346:25:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	473.8 GB	15.9 MB	162.8 MB	474.0 GB	99%	333.5 KB
2	news.nask.pl	20.9 MB	2.4 MB	2.7 MB	26.0 MB	80%	5.0 KB
3	news.icm.edu.pl	1.3 MB	72.4 KB	7.4 MB	8.7 MB	15%	60.4 KB
4	localhost	5.1 KB	0.0 KB	0.0 KB	5.1 KB	100%	1.0 KB
TOTAL: 4		473.9 GB	18.4 MB	172.8 MB	474.0 GB	99%	332.3 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Nov 3 04:15:02 - 04:59:59	44811	3.0%	16.61	15.3 GB	3.2%	5956.57
Nov 3 05:00:00 - 05:59:59	68465	4.6%	19.02	23.2 GB	4.9%	6747.24
Nov 3 06:00:00 - 06:59:59	60305	4.0%	16.75	21.1 GB	4.4%	6135.64
Nov 3 07:00:00 - 07:59:59	66516	4.5%	18.48	23.2 GB	4.9%	6755.57
Nov 3 08:00:00 - 08:59:59	68011	4.6%	18.89	23.6 GB	5.0%	6879.64
Nov 3 09:00:00 - 09:59:59	63830	4.3%	17.73	22.3 GB	4.7%	6493.29
Nov 3 10:00:00 - 10:59:59	65183	4.4%	18.11	21.7 GB	4.6%	6324.49
Nov 3 11:00:00 - 11:59:59	73007	4.9%	20.28	22.4 GB	4.7%	6528.12
Nov 3 12:00:00 - 12:59:59	71526	4.8%	19.87	22.5 GB	4.7%	6539.76
Nov 3 13:00:00 - 13:59:59	68369	4.6%	18.99	22.2 GB	4.7%	6453.22
Nov 3 14:00:00 - 14:59:59	74636	5.0%	20.73	22.8 GB	4.8%	6645.69
Nov 3 15:00:00 - 15:59:59	70651	4.7%	19.63	22.9 GB	4.8%	6675.35
Nov 3 16:00:00 - 16:59:59	73605	4.9%	20.45	22.7 GB	4.8%	6621.49
Nov 3 17:00:00 - 17:59:59	46724	3.1%	12.98	14.0 GB	3.0%	4076.95
Nov 3 18:00:00 - 18:59:59	66538	4.5%	18.48	19.2 GB	4.1%	5600.50
Nov 3 19:00:00 - 19:59:59	65304	4.4%	18.14	18.8 GB	4.0%	5480.45
Nov 3 20:00:00 - 20:59:59	39524	2.7%	10.98	11.4 GB	2.4%	3309.97
Nov 3 21:00:00 - 21:59:59	36516	2.5%	10.14	10.5 GB	2.2%	3053.92
Nov 3 22:00:00 - 22:59:59	62269	4.2%	17.30	19.3 GB	4.1%	5612.75
Nov 3 23:00:00 - 23:59:59	44020	3.0%	12.23	12.1 GB	2.5%	3513.40
Nov 4 00:00:00 - 00:59:59	49712	3.3%	13.81	14.4 GB	3.0%	4188.84
Nov 4 01:00:00 - 01:59:59	66269	4.4%	18.41	21.2 GB	4.5%	6175.08
Nov 4 02:00:00 - 02:59:59	66191	4.4%	18.39	21.2 GB	4.5%	6187.93
Nov 4 03:00:00 - 03:59:59	63631	4.3%	17.68	21.1 GB	4.5%	6141.67
Nov 4 04:00:00 - 04:15:03	14416	1.0%	15.96	4.7 GB	1.0%	5452.52
TOTAL: 24:00:01	1490029	100.0%	17.25	473.7 GB	100.0%	5748.80

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4408	294	2852	0	1262
2	news.nask.pl	838	37	771	1	29
3	news.icm.edu.pl	102	0	6	0	96
TOTAL: 3		5348	331	3629	1	1387

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	180
comp.mobile.android	32
alt.sex.telephones	24
it.tlc.cellulari.android	22
hr.alt.cellular.gsm.android	11
it.comp.os.win.windows8	11
hr.alt.cellular.gsm.iphone	11
it.comp.os.win.windows7	9
de.comp.lang.php	6
comp.sys.raspberry-pi	5
de.comm.provider.telefonie	4
hr.ponuda.hardver.komponente	4
comp.mobile.ipad	3
de.rec.sf.startrek	2
it.comp.os.linux.ubuntu	2
nl.comp.os.linux.techniek	2
hr.rec.glazba.hifi	1
uk.rec.cycling.moderated	1
de.comp.office-pakete.ms-office.misc	1
TOTAL: 19	331

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

Groups	Count
pl.sci.historia	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	1136
EMP (md5)	105
HTML post	22
Attached HTML file	1
TOTAL: 4	1264

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	33534	1741	30578	271	0	5%	24:00:13
2	icm	37161	888	28830	391	2	2%	24:01:44
3	poznan	6013	55	4153	20	0	0%	23:54:59
4	nask	24609	53	15432	240	0	0%	24:01:58
5	okapi	0	0	0	0	2732	0%	24:00:06
TOTAL: 5		101317	2737	78993	922	2734	2%	119:59:00

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	149.9 MB	114.3 MB	264.2 MB	3.1 KB/s	211.5 KB	24:01:44
2	panorama	8.7 MB	814.9 KB	9.5 MB	0.1 KB/s	4.8 KB	24:00:13
3	nask	711.6 KB	39.3 MB	40.0 MB	0.5 KB/s	139.8 KB	24:01:58
4	poznan	187.5 KB	36.6 KB	224.0 KB	0.0 KB/s	3.0 KB	23:54:59
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:06
TOTAL: 5		159.4 MB	154.5 MB	313.9 MB	0.7 KB/s	87.9 KB	119:59:00

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	17	1033	2.0 MB	19	0	03:45:29
2	pc-skrzep2.cm.p.lodz.pl	279	315	435.6 KB	279	0	00:00:08
3	web.man.lodz.pl	291	58	53.4 KB	26	0	00:00:22
4	pc24.zsk.p.lodz.pl	5	11	9.6 KB	3	2	00:30:09
5	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 5		595	1417	2.5 MB	327	5	05:34:32

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	17	1033	2.0 MB	19	0	03:45:29
2	*.cm.p.lodz.pl	279	315	435.6 KB	279	0	00:00:08
3	*.man.lodz.pl	678	58	53.4 KB	26	3	00:00:28
4	*.zsk.p.lodz.pl	5	11	9.6 KB	3	2	00:30:09
5	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	00:57:20
6	unresolved	3	0	0.0 KB	0	0	00:20:52
7	*.toya.net.pl	49	0	0.0 KB	0	0	00:00:03
TOTAL: 7		1033	1417	2.5 MB	327	5	05:34:32

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.144	0.510	03:45:29
251.191.14.62.static.jazztel.es	1.105	1.651	00:57:20
pc24.zsk.p.lodz.pl	0.871	0.358	00:30:09
212.51.203.199	0.017	0.020	00:20:51
web.man.lodz.pl	1.087	3.769	00:00:22
pc-skrzep2.cm.p.lodz.pl	1.052	3.664	00:00:08
mon.man.lodz.pl	0.962	3.792	00:00:04
staticline41435.toya.net.pl	0.168	0.614	00:00:03
199.187.127.10	0.026	0.013	00:00:01
david.man.lodz.pl	0.334	1.219	00:00:01
isvcs01.man.lodz.pl	0.019	0.043	00:00:00
TOTAL: 11	5.785	15.653	05:34:32

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	49
251.191.14.62.static.jazztel.es	2
212.51.203.199	2
199.187.127.10	1
TOTAL: 6	438

NNRP client timeouts [Top 20]:

System	Conn	Peer
251.191.14.62.static.jazztel.es	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	1102	77.8%
2	lodman	315	22.2%
TOTAL: 2		1417	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	557
2	pl.misc.kolej	476
3	lodman.info	315
4	pl.comp.pecet	40
5	pl.comp.os.ms-windows.winnt	18
6	pl.test	11
TOTAL: 6		1417

Daily Usenet report

Nov 3 04:15:02 -- Nov 4 04:15:03