Daily Usenet report: Oct 1 04:15:02

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

Oct 1 04:37:20 newsfeed nnrpd[334]: SERVER perl filter not defined
Oct 1 07:04:05 newsfeed pgpverify[6587]: pgpverify: verification failed
Oct 1 09:46:44 newsfeed nnrpd[12185]: SERVER perl filter not defined
Oct 1 11:04:25 newsfeed nnrpd[14869]: SERVER perl filter not defined
Oct 1 12:37:28 newsfeed nnrpd[18069]: SERVER perl filter not defined
Oct 1 13:26:12 newsfeed nnrpd[19760]: SERVER perl filter not defined
Oct 1 13:48:12 newsfeed nnrpd[20485]: SERVER perl filter not defined
Oct 1 14:01:12 newsfeed nnrpd[21049]: SERVER perl filter not defined
Oct 1 14:47:05 newsfeed nnrpd[22553]: SERVER perl filter not defined
Oct 1 15:29:20 newsfeed nnrpd[24088]: SERVER perl filter not defined
Oct 1 15:30:19 newsfeed nnrpd[24141]: SERVER perl filter not defined
Oct 1 15:31:15 newsfeed nnrpd[24147]: SERVER perl filter not defined
Oct 1 16:45:55 newsfeed nnrpd[26659]: SERVER perl filter not defined
Oct 1 21:34:17 newsfeed nnrpd[4275]: SERVER perl filter not defined
Oct 2 04:15:02 newsfeed nnrpd[18209]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 2 04:15:02 newsfeed nnrpd[18211]: web.man.lodz.pl rejected Flushing log and syslog files
Oct 2 04:15:02 newsfeed nnrpd[18212]: web.man.lodz.pl rejected Flushing log and syslog files

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	2154274	97.0%	246.6 MB	96.5%
innfeed	43733	2.0%	5.2 MB	2.0%
innd	18707	0.8%	3.3 MB	1.3%
nnrpd	5131	0.2%	548.4 KB	0.2%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	2221849	100.0%	255.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	2147989	97.9%
Negative hits	30178	1.4%
Positive hits	16440	0.7%
Cache misses	120	0.0%
TOTAL: 4	2194727	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.719	0.0%	278	0.000	13.378	614.000
article cleanup	00:00:23.181	0.0%	2152598	0.003	0.011	0.025
article logging	00:00:49.011	0.1%	2153183	0.009	0.023	0.239
article parse	00:38:19.061	2.7%	166378110	0.006	0.014	0.050
article write	00:25:01.464	1.7%	2148666	0.160	0.699	4.279
artlog/artcncl	00:00:00.005	0.0%	275	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:01:26.150	0.1%	180329581	0.000	0.000	0.005
hisgrep/artcncl	00:00:00.433	0.0%	234	0.000	1.850	32.250
hishave/artcncl	00:00:00.097	0.0%	278	0.000	0.349	8.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:52:20.677	3.6%	2196104	0.064	1.430	15.912
history sync	00:00:00.014	0.0%	293	0.000	0.048	1.000
history write	01:13:04.844	5.1%	2149488	0.154	2.040	28.377
hiswrite/artcncl	00:00:00.039	0.0%	44	0.000	0.886	10.000
idle	16:42:51.762	69.7%	144471111	0.085	0.416	1.084
nntp read	00:33:25.851	2.3%	164801390	0.008	0.012	0.021
overview write	00:00:09.249	0.0%	2148666	0.000	0.004	0.012
perl filter	03:01:01.663	12.6%	2149487	2.588	5.053	7.571
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:02:59.062	0.2%	4369244	0.022	0.041	0.070
TOTAL: 23:59:35.131	23:31:56.282	98.1%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.323	0.0%	186016	0.000	0.002	0.059
article prepare	00:00:00.033	0.0%	137260	0.000	0.000	0.083
article read	00:02:04.167	0.1%	34308	0.000	3.619	266.000
backlog stats	00:00:00.241	0.0%	430436	0.000	0.001	0.014
callbacks	00:00:00.000	0.0%	16	0.000	0.000	0.000
data read	00:00:01.862	0.0%	240248	0.000	0.008	0.062
data write	00:00:11.672	0.0%	171185	0.000	0.068	1.179
idle	23:57:53.856	99.8%	430436	4.334	200.434	1159.241
status file	00:00:07.940	0.0%	15107	0.000	0.526	2.000
TOTAL: 24:00:30.809	24:00:20.094	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	03:07:47.214	99.8%	3502	0.000	3217.366	450250.250
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.121	0.0%	8126	0.000	0.015	0.500
readart	00:00:01.703	0.0%	287	0.000	5.934	27.000
TOTAL: 03:08:04.282	03:07:49.038	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
us-control@lists.killfile.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	1022	2149558	2145695	326	3537	99%	301:07:27
2	news.nask.pl	2	16794	3645	12769	380	21%	24:10:41
3	news.icm.edu.pl	46	220	16	193	11	7%	16:29:28
4	localhost	14	13	13	0	0	100%	00:00:01
TOTAL: 4		1084	2166585	2149369	13288	3928	99%	341:47:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	657.5 GB	10.2 MB	105.2 MB	657.6 GB	99%	320.8 KB
2	news.nask.pl	12.3 MB	954.5 KB	1.1 MB	14.2 MB	86%	3.6 KB
3	news.icm.edu.pl	329.1 KB	11.6 KB	882.5 KB	1.2 MB	26%	45.3 KB
4	localhost	22.4 KB	0.0 KB	0.0 KB	22.4 KB	100%	1.7 KB
TOTAL: 4		657.5 GB	11.1 MB	107.1 MB	657.6 GB	99%	320.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Oct 1 04:15:02 - 04:59:59	62426	2.9%	23.14	20.2 GB	3.1%	7850.48
Oct 1 05:00:00 - 05:59:59	85487	4.0%	23.75	28.3 GB	4.3%	8243.92
Oct 1 06:00:00 - 06:59:59	94719	4.4%	26.31	30.7 GB	4.7%	8927.72
Oct 1 07:00:00 - 07:59:59	70406	3.3%	19.56	22.6 GB	3.4%	6592.36
Oct 1 08:00:00 - 08:59:59	90127	4.2%	25.04	29.5 GB	4.5%	8603.92
Oct 1 09:00:00 - 09:59:59	110770	5.2%	30.77	30.0 GB	4.6%	8729.79
Oct 1 10:00:00 - 10:59:59	114076	5.3%	31.69	31.8 GB	4.8%	9264.75
Oct 1 11:00:00 - 11:59:59	93992	4.4%	26.11	30.0 GB	4.6%	8748.22
Oct 1 12:00:00 - 12:59:59	99140	4.6%	27.54	31.0 GB	4.7%	9027.31
Oct 1 13:00:00 - 13:59:59	95523	4.4%	26.53	30.8 GB	4.7%	8973.24
Oct 1 14:00:00 - 14:59:59	85459	4.0%	23.74	29.2 GB	4.4%	8499.35
Oct 1 15:00:00 - 15:59:59	85248	4.0%	23.68	29.0 GB	4.4%	8457.00
Oct 1 16:00:00 - 16:59:59	81168	3.8%	22.55	25.7 GB	3.9%	7487.93
Oct 1 17:00:00 - 17:59:59	102181	4.8%	28.38	30.6 GB	4.7%	8923.46
Oct 1 18:00:00 - 18:59:59	106271	4.9%	29.52	31.4 GB	4.8%	9146.48
Oct 1 19:00:00 - 19:59:59	101661	4.7%	28.24	29.3 GB	4.5%	8534.74
Oct 1 20:00:00 - 20:59:59	125201	5.8%	34.78	36.0 GB	5.5%	10491.24
Oct 1 21:00:00 - 21:59:59	102057	4.7%	28.35	29.1 GB	4.4%	8471.66
Oct 1 22:00:00 - 22:59:59	38575	1.8%	10.72	11.8 GB	1.8%	3433.38
Oct 1 23:00:00 - 23:59:59	46743	2.2%	12.98	12.1 GB	1.8%	3519.68
Oct 2 00:00:00 - 00:59:59	90544	4.2%	25.15	23.5 GB	3.6%	6850.49
Oct 2 01:00:00 - 01:59:59	72763	3.4%	20.21	22.1 GB	3.4%	6437.19
Oct 2 02:00:00 - 02:59:59	89813	4.2%	24.95	29.3 GB	4.5%	8533.89
Oct 2 03:00:00 - 03:59:59	83305	3.9%	23.14	26.7 GB	4.1%	7782.03
Oct 2 04:00:00 - 04:15:02	21828	1.0%	24.20	6.9 GB	1.0%	7985.82
TOTAL: 24:00:00	2149483	100.0%	24.88	657.7 GB	100.0%	7981.84

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	4088	257	2764	1	1066
2	news.nask.pl	417	9	345	20	43
3	news.icm.edu.pl	11	0	1	0	10
TOTAL: 3		4516	266	3110	21	1119

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	77
it.tlc.cellulari.android	63
it.comp.os.win.windows10	60
comp.mobile.android	15
comp.sys.raspberry-pi	10
it.comp.os.win.windows7	8
hr.ponuda.hardver.komponente	8
nl.comp.os.linux.techniek	5
alt.sex.telephones	5
uk.rec.cycling.moderated	4
hr.alt.cellular.gsm.iphone	3
it.comp.os.linux.ubuntu	2
comp.mobile.ipad	2
it.tlc.cellulari.iphone	1
de.comm.software.mailreader	1
comp.sys.mac.vintage	1
hr.alt.cellular.gsm.android	1
TOTAL: 17	266

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

Groups	Count
pl.praca.oferowana	20
bit.listserv.catholic	1
TOTAL: 2	21

INND Perl filter [Top 20]:

Reason	Count
EMP (fsl)	375
Binary in non-binary group	147
EMP (md5)	4
Too many newsgroups	3
HTML post	2
Scoring filter (14)	1
Scoring filter (11)	1
TOTAL: 7	533

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	126334	3885	92219	5037	50	84663	3%	23:51:31
2	icm	65256	1920	24958	22209	0	10	2%	24:00:42
3	poznan	5514	74	3504	32	0	0	1%	24:01:01
4	nask	0	0	0	0	0	13401	0%	24:00:00
5	okapi	0	0	0	0	0	2584	0%	24:00:00
TOTAL: 5		197104	5879	120681	27278	50	100658	2%	119:53:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	21.8 MB	5.0 GB	5.0 GB	60.6 KB/s	217.0 KB	24:00:42
2	panorama	13.4 MB	26.4 MB	39.8 MB	0.5 KB/s	4.6 KB	23:51:31
3	poznan	221.3 KB	71.9 KB	293.2 KB	0.0 KB/s	2.8 KB	24:01:01
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		35.5 MB	5.0 GB	5.0 GB	12.2 KB/s	159.1 KB	119:53:14

Backlog files shrunk by innfeed:

Server	Size
panorama	1.9 MB
okapi	488.3 KB
TOTAL: 2	2.4 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-212-51-218-225.p.lodz.pl	10	152	309.0 KB	7	0	02:28:47
2	web.man.lodz.pl	290	80	97.7 KB	34	0	00:00:31
3	lze200.lze.lodz.pl	3	10	21.3 KB	3	0	00:36:31
4	eth-west-parpa2-46-193-0-13.wb.wifirst.net	2	10	8.7 KB	1	0	00:00:55
5	isvcs01.man.lodz.pl	13	0	0.0 KB	0	13	00:00:00
TOTAL: 5		318	252	436.6 KB	45	13	03:08:04

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.p.lodz.pl	10	152	309.0 KB	7	0	02:28:47
2	*.man.lodz.pl	687	80	97.7 KB	34	13	00:00:36
3	*.lze.lodz.pl	3	10	21.3 KB	3	0	00:36:31
4	*.wb.wifirst.net	2	10	8.7 KB	1	0	00:00:55
5	unresolved	1	0	0.0 KB	0	0	00:00:01
6	*.snarked.org	2	0	0.0 KB	0	0	00:00:02
7	*.neoplus.adsl.tpnet.pl	1	0	0.0 KB	0	0	00:00:47
8	*.elsnet.pl	2	0	0.0 KB	0	0	00:00:18
9	*.timplus.net	1	0	0.0 KB	0	0	00:00:03
10	*.toya.net.pl	25	0	0.0 KB	0	0	00:00:01
TOTAL: 10		734	252	436.6 KB	45	13	03:08:04

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.039	0.086	02:28:47
lze200.lze.lodz.pl	0.009	0.022	00:36:31
eth-west-parpa2-46-193-0-13.wb.wifirst.net	0.004	0.028	00:00:55
agve186.neoplus.adsl.tpnet.pl	0.012	0.008	00:00:47
web.man.lodz.pl	0.617	2.548	00:00:31
host227.elsnet.pl	0.004	0.017	00:00:18
91-214-3-226.timplus.net	0.000	0.015	00:00:03
mon.man.lodz.pl	0.587	2.393	00:00:02
news.snarked.org	0.014	0.017	00:00:02
staticline41435.toya.net.pl	0.058	0.237	00:00:01
173.0.15.140	0.006	0.011	00:00:01
isvcs01.man.lodz.pl	0.065	0.118	00:00:00
david.man.lodz.pl	0.195	0.818	00:00:00
TOTAL: 13	1.610	6.318	03:08:04

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	25
news.snarked.org	2
host227.elsnet.pl	2
agve186.neoplus.adsl.tpnet.pl	1
91-214-3-226.timplus.net	1
173.0.15.140	1
TOTAL: 8	416

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	3	1
lze200.lze.lodz.pl	1	0
TOTAL: 2	4	1

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	242	96.0%
2	lodz	10	4.0%
TOTAL: 2		252	100.0%

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

Newsgroup		Count
1	pl.misc.elektronika	79
2	pl.comp.pecet	74
3	pl.misc.kolej	73
4	pl.comp.os.ms-windows.winnt	10
5	lodz.test	10
6	pl.misc.telefonia.gsm	5
7	pl.rec.dom	1
TOTAL: 7		252

Daily Usenet report

Oct 1 04:15:02 -- Oct 2 04:15:02