Daily Usenet report: Jun 13 04:15:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Jun 13 06:41:15 newsfeed overchan[385795]: input line too long, skipping
Jun 13 10:26:47 newsfeed overchan[385795]: input line too long, skipping
Jun 13 16:22:59 newsfeed overchan[385795]: input line too long, skipping
Jun 13 16:38:33 newsfeed overchan[385795]: input line too long, skipping
Jun 13 16:42:00 newsfeed nnrpd[430770]: SERVER perl filter not defined
Jun 13 17:05:36 newsfeed overchan[385795]: input line too long, skipping
Jun 13 17:50:46 newsfeed overchan[385795]: input line too long, skipping
Jun 13 19:14:40 newsfeed overchan[385795]: input line too long, skipping
Jun 13 23:04:11 newsfeed overchan[385795]: input line too long, skipping
Jun 13 23:09:44 newsfeed nnrpd[453205]: SERVER perl filter not defined
Jun 14 01:15:02 newsfeed pgpverify[462491]: pgpverify: /usr/bin/gpg1: no such file
Jun 14 01:15:02 newsfeed pgpverify[462499]: pgpverify: /usr/bin/gpg1: no such file

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	902148	95.7%	101.1 MB	95.2%
innfeed	27097	2.9%	3.2 MB	3.0%
innd	10363	1.1%	1.6 MB	1.5%
nnrpd	3377	0.4%	319.3 KB	0.3%
overchan	9	0.0%	1.5 KB	0.0%
controlchan	3	0.0%	0.2 KB	0.0%
pgpverify	2	0.0%	0.3 KB	0.0%
TOTAL: 7	942999	100.0%	106.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	897395	99.6%
Negative hits	2944	0.3%
Positive hits	228	0.0%
Cache misses	18	0.0%
TOTAL: 4	900585	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.668	0.0%	61	0.000	10.951	278.000
article cleanup	00:00:05.207	0.0%	902527	0.003	0.006	0.009
article logging	00:00:18.161	0.0%	902882	0.015	0.020	0.030
article parse	00:08:18.850	0.6%	149864565	0.003	0.003	0.005
article write	00:05:53.053	0.4%	902320	0.218	0.391	0.552
artlog/artcncl	00:00:00.000	0.0%	61	0.000	0.000	0.000
data move	00:00:34.772	0.0%	156476180	0.000	0.000	0.000
hisgrep/artcncl	00:00:00.125	0.0%	53	0.000	2.358	80.000
hishave/artcncl	00:00:00.006	0.0%	61	0.000	0.098	1.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:03:51.346	0.3%	905643	0.164	0.255	1.038
history sync	00:00:00.011	0.0%	290	0.000	0.038	0.500
history write	00:06:41.326	0.5%	902506	0.228	0.445	2.598
hiswrite/artcncl	00:00:00.002	0.0%	8	0.000	0.250	1.000
idle	22:05:33.771	92.1%	148618192	0.367	0.535	0.846
nntp read	00:27:37.698	1.9%	149276299	0.010	0.011	0.014
overview write	00:00:02.146	0.0%	902320	0.001	0.002	0.004
perl filter	00:42:21.791	2.9%	902506	2.193	2.816	3.414
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:22.423	0.0%	1815109	0.010	0.012	0.016
TOTAL: 24:00:01.970	23:41:41.356	98.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.043	0.0%	32247	0.000	0.001	0.011
article prepare	00:00:00.001	0.0%	10218	0.000	0.000	0.011
article read	00:00:09.613	0.0%	2552	0.000	3.767	207.444
backlog stats	00:00:00.052	0.0%	90271	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.402	0.0%	42862	0.000	0.009	0.033
data write	00:00:01.041	0.0%	33177	0.004	0.031	0.126
idle	23:56:24.995	100.0%	90271	612.838	954.736	2093.613
status file	00:00:05.554	0.0%	9033	0.390	0.615	3.773
TOTAL: 23:56:43.872	23:56:41.701	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:10:11.073	98.6%	2795	0.000	218.631	75331.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.076	0.0%	6030	0.000	0.013	0.500
readart	00:00:00.001	0.0%	56	0.000	0.018	0.500
TOTAL: 00:10:19.468	00:10:11.150	98.7%	-	-	-	-

Control commands to INND:

Command	Number
flush	27
flushlogs	2
go	2
logmode	2
lowmark	1
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	193

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	2	0	0
TOTAL	0	0	0	2	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	179	901700	901511	0	189	99%	89:00:27
2	news.icm.edu.pl	49	219	31	171	17	14%	14:39:50
3	localhost	1427	2	2	0	0	100%	00:00:00
TOTAL: 3		1655	901921	901544	171	206	99%	103:40:17

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	347.2 GB	1.7 MB	2.2 MB	347.3 GB	99%	403.8 KB
2	news.icm.edu.pl	1.9 MB	0.0 KB	1.0 MB	2.9 MB	64%	62.5 KB
3	localhost	2.5 KB	0.0 KB	0.0 KB	2.5 KB	100%	1.2 KB
TOTAL: 3		347.3 GB	1.7 MB	3.3 MB	347.3 GB	99%	403.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jun 13 04:15:01 - 04:59:59	25403	2.8%	9.41	10.9 GB	3.1%	4222.10
Jun 13 05:00:00 - 05:59:59	32167	3.6%	8.94	14.6 GB	4.2%	4265.05
Jun 13 06:00:00 - 06:59:59	34092	3.8%	9.47	14.7 GB	4.2%	4289.31
Jun 13 07:00:00 - 07:59:59	33902	3.8%	9.42	14.8 GB	4.3%	4306.27
Jun 13 08:00:00 - 08:59:59	30402	3.4%	8.45	14.1 GB	4.1%	4101.73
Jun 13 09:00:00 - 09:59:59	28457	3.2%	7.90	13.4 GB	3.8%	3897.37
Jun 13 10:00:00 - 10:59:59	36205	4.0%	10.06	14.0 GB	4.0%	4070.24
Jun 13 11:00:00 - 11:59:59	36734	4.1%	10.20	14.5 GB	4.2%	4222.20
Jun 13 12:00:00 - 12:59:59	40556	4.5%	11.27	14.1 GB	4.1%	4118.93
Jun 13 13:00:00 - 13:59:59	38285	4.2%	10.63	13.6 GB	3.9%	3973.37
Jun 13 14:00:00 - 14:59:59	56202	6.2%	15.61	15.0 GB	4.3%	4372.56
Jun 13 15:00:00 - 15:59:59	51062	5.7%	14.18	14.7 GB	4.2%	4287.61
Jun 13 16:00:00 - 16:59:59	37281	4.1%	10.36	14.0 GB	4.0%	4092.31
Jun 13 17:00:00 - 17:59:59	55840	6.2%	15.51	13.7 GB	3.9%	3980.78
Jun 13 18:00:00 - 18:59:59	31020	3.4%	8.62	14.5 GB	4.2%	4229.91
Jun 13 19:00:00 - 19:59:59	34696	3.8%	9.64	14.8 GB	4.3%	4312.16
Jun 13 20:00:00 - 20:59:59	34699	3.8%	9.64	14.9 GB	4.3%	4328.32
Jun 13 21:00:00 - 21:59:59	50697	5.6%	14.08	15.9 GB	4.6%	4639.83
Jun 13 22:00:00 - 22:59:59	34506	3.8%	9.59	14.9 GB	4.3%	4342.20
Jun 13 23:00:00 - 23:59:59	34667	3.8%	9.63	15.4 GB	4.4%	4472.52
Jun 14 00:00:00 - 00:59:59	38638	4.3%	10.73	14.4 GB	4.1%	4201.50
Jun 14 01:00:00 - 01:59:59	37296	4.1%	10.36	14.8 GB	4.2%	4303.68
Jun 14 02:00:00 - 02:59:59	33089	3.7%	9.19	14.6 GB	4.2%	4260.62
Jun 14 03:00:00 - 03:59:59	28444	3.2%	7.90	14.1 GB	4.0%	4097.84
Jun 14 04:00:00 - 04:15:02	7184	0.8%	7.96	3.2 GB	0.9%	3749.60
TOTAL: 24:00:01	901524	100.0%	10.43	347.7 GB	100.0%	4219.58

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	544	161	21	7	355
2	news.icm.edu.pl	19	0	0	0	19
TOTAL: 2		563	161	21	7	374

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	78
it.tlc.cellulari.android	24
alt.comp.software.seamonkey	22
comp.mobile.android	12
alt.comp.software.firefox	10
it.comp.os.win.windows10	7
it.discussioni.energia	2
de.comm.provider.telefonie	2
comp.sys.raspberry-pi	2
it.comp.os.linux.ubuntu	1
de.comm.software.mailreader	1
TOTAL: 11	161

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

Groups	Count
pl.sci.matematyka	4
pl.praca.oferowana	3
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	12
Binary in non-binary group	7
TOTAL: 2	19

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	355
	TOTAL: 1	355
TOTAL: 1		355

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	17708	1670	7975	414	0	0	9%	23:59:43
2	panorama	22642	156	7576	94	1	24481	0%	23:59:50
3	poznan	18	0	15	0	0	0	0%	01:28:02
4	okapi	0	0	0	0	0	1090	0%	23:57:48
5	nask	0	0	0	0	0	6987	0%	23:57:48
TOTAL: 5		40368	1826	15566	508	1	32558	4%	97:23:11

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	7.1 MB	79.6 MB	86.7 MB	1.0 KB/s	42.6 KB	23:59:43
2	panorama	527.0 KB	443.4 KB	970.4 KB	0.0 KB/s	3.9 KB	23:59:50
3	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:28:02
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:48
TOTAL: 5		7.6 MB	80.0 MB	87.6 MB	0.3 KB/s	38.5 KB	97:23:11

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	292	56	53.7 KB	28	0	00:00:10
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		294	56	53.7 KB	28	2	00:10:19

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	294	56	53.7 KB	28	2	00:00:10
2	*.quintex.com	1	0	0.0 KB	0	0	00:00:03
3	*.neoplus.adsl.tpnet.pl	2	0	0.0 KB	0	0	00:10:02
4	unresolved	100	0	0.0 KB	0	0	00:00:03
TOTAL: 4		397	56	53.7 KB	28	2	00:10:19

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
dju95.neoplus.adsl.tpnet.pl	0.044	0.026	00:10:02
web.man.lodz.pl	0.827	1.934	00:00:10
tor38.quintex.com	0.004	0.004	00:00:03
80.111.142.213	0.005	0.037	00:00:01
212.51.192.185	0.275	0.508	00:00:01
isvcs01.man.lodz.pl	0.010	0.020	00:00:00
TOTAL: 6	1.165	2.529	00:10:19

Curious NNRP server explorers [Top 20]:

System	Conn
212.51.192.185	96
80.111.142.213	4
dju95.neoplus.adsl.tpnet.pl	2
tor38.quintex.com	1
TOTAL: 4	103

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.pecet	50
2	pl.comp.os.ms-windows.winnt	6
TOTAL: 2		56

Daily Usenet report

Jun 13 04:15:01 -- Jun 14 04:15:02