Daily Usenet report: Dec 3 04:15:02

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Dec 3 09:37:56 newsfeed overchan[2150]: input line too long, skipping
Dec 3 11:12:29 newsfeed nnrpd[4202]: SERVER perl filter not defined
Dec 3 11:49:32 newsfeed nnrpd[5442]: SERVER perl filter not defined
Dec 3 16:10:55 newsfeed nnrpd[14153]: SERVER perl filter not defined
Dec 3 19:00:21 newsfeed nnrpd[19793]: SERVER perl filter not defined
Dec 3 19:08:15 newsfeed nnrpd[20075]: SERVER perl filter not defined
Dec 3 21:42:51 newsfeed nnrpd[25128]: SERVER perl filter not defined
Dec 3 23:32:35 newsfeed pgpverify[28779]: pgpverify: verification failed
Dec 4 00:12:28 newsfeed nnrpd[30182]: SERVER perl filter not defined
Dec 4 00:12:55 newsfeed nnrpd[30189]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1952879	97.8%	222.7 MB	97.3%
innd	29701	1.5%	4.3 MB	1.9%
nnrpd	7368	0.4%	783.4 KB	0.3%
innfeed	7190	0.4%	1.0 MB	0.5%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
TOTAL: 7	1997143	100.0%	228.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1947767	95.3%
Negative hits	77963	3.8%
Positive hits	18022	0.9%
Cache misses	148	0.0%
TOTAL: 4	2043900	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:08.874	0.0%	278	0.000	31.921	957.000
article cleanup	00:00:31.697	0.0%	1951963	0.000	0.016	0.030
article logging	00:00:51.189	0.1%	1952084	0.008	0.026	0.176
article parse	01:11:57.396	5.0%	174053337	0.009	0.025	0.055
article write	00:18:32.832	1.3%	1934456	0.189	0.575	1.646
artlog/artcncl	00:00:00.005	0.0%	273	0.000	0.018	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:44.721	0.2%	186491468	0.000	0.001	0.002
hisgrep/artcncl	00:00:01.811	0.0%	274	0.000	6.609	293.000
hishave/artcncl	00:00:00.694	0.0%	278	0.000	2.496	291.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:58:13.009	4.0%	2044514	0.125	1.708	5.996
history sync	00:00:00.009	0.0%	302	0.000	0.030	1.000
history write	03:29:58.445	14.6%	1948556	0.870	6.466	19.385
hiswrite/artcncl	00:00:00.025	0.0%	4	0.000	6.250	15.000
idle	11:53:25.693	49.6%	115331919	0.171	0.371	4.906
nntp read	01:03:34.153	4.4%	172481586	0.010	0.022	0.035
overview write	00:00:12.082	0.0%	1934456	0.000	0.006	0.020
perl filter	04:16:00.706	17.8%	1948554	3.921	7.883	9.624
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:31.755	0.2%	3909253	0.019	0.054	0.098
TOTAL: 23:59:38.703	23:19:45.096	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.124	0.0%	40389	0.000	0.003	1.000
article prepare	00:00:00.013	0.0%	46700	0.000	0.000	0.042
article read	00:00:01.498	0.0%	11536	0.000	0.130	1.000
backlog stats	00:00:00.160	0.0%	277615	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.801	0.0%	172455	0.000	0.010	1.000
data write	00:00:09.286	0.0%	138430	0.000	0.067	5.000
idle	23:59:31.327	100.0%	277615	0.000	311.119	15416.000
status file	00:00:00.561	0.0%	190	0.000	2.953	260.000
TOTAL: 23:59:50.714	23:59:44.770	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:55:48.605	99.8%	6183	0.000	2870.549	210005.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:03.540	0.0%	67910	0.000	0.052	3.444
readart	00:00:00.632	0.0%	521	0.000	1.213	14.000
TOTAL: 04:56:17.238	04:55:52.777	99.9%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	149
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	190

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
grisbi-control@grisbi.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	676	1949406	1931432	1365	16609	99%	307:15:19
2	news.nask.pl	2	17587	4047	12691	849	23%	23:26:00
3	news.icm.edu.pl	37	326	41	245	40	12%	18:45:30
4	localhost	9	8	8	0	0	100%	00:00:00
TOTAL: 4		724	1967327	1935528	14301	17498	98%	349:26:49

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	601.9 GB	54.2 MB	1.9 GB	603.8 GB	99%	325.0 KB
2	news.nask.pl	16.1 MB	2.5 MB	2.7 MB	21.4 MB	75%	4.5 KB
3	news.icm.edu.pl	1.1 MB	80.0 KB	810.9 KB	2.0 MB	56%	25.0 KB
4	localhost	12.9 KB	0.0 KB	0.0 KB	12.9 KB	100%	1.6 KB
TOTAL: 4		601.9 GB	56.8 MB	1.9 GB	603.9 GB	99%	324.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 3 04:15:02 - 04:59:59	46761	2.4%	17.33	15.9 GB	2.6%	6183.90
Dec 3 05:00:00 - 05:59:59	87862	4.5%	24.41	28.4 GB	4.7%	8269.89
Dec 3 06:00:00 - 06:59:59	95808	5.0%	26.61	31.1 GB	5.2%	9068.41
Dec 3 07:00:00 - 07:59:59	93474	4.8%	25.96	30.0 GB	5.0%	8731.44
Dec 3 08:00:00 - 08:59:59	100492	5.2%	27.91	31.8 GB	5.3%	9249.30
Dec 3 09:00:00 - 09:59:59	97343	5.0%	27.04	31.7 GB	5.3%	9245.17
Dec 3 10:00:00 - 10:59:59	91919	4.8%	25.53	31.5 GB	5.2%	9173.91
Dec 3 11:00:00 - 11:59:59	92573	4.8%	25.71	30.1 GB	5.0%	8773.11
Dec 3 12:00:00 - 12:59:59	89343	4.6%	24.82	30.0 GB	5.0%	8752.11
Dec 3 13:00:00 - 13:59:59	96417	5.0%	26.78	29.7 GB	4.9%	8655.51
Dec 3 14:00:00 - 14:59:59	82330	4.3%	22.87	23.8 GB	4.0%	6921.23
Dec 3 15:00:00 - 15:59:59	79551	4.1%	22.10	26.8 GB	4.4%	7794.15
Dec 3 16:00:00 - 16:59:59	91945	4.8%	25.54	30.4 GB	5.0%	8843.02
Dec 3 17:00:00 - 17:59:59	61999	3.2%	17.22	19.6 GB	3.3%	5713.31
Dec 3 18:00:00 - 18:59:59	39550	2.0%	10.99	10.3 GB	1.7%	2994.93
Dec 3 19:00:00 - 19:59:59	22972	1.2%	6.38	5.0 GB	0.8%	1453.97
Dec 3 20:00:00 - 20:59:59	76296	3.9%	21.19	20.9 GB	3.5%	6098.38
Dec 3 21:00:00 - 21:59:59	77182	4.0%	21.44	22.3 GB	3.7%	6490.02
Dec 3 22:00:00 - 22:59:59	45441	2.3%	12.62	11.5 GB	1.9%	3353.89
Dec 3 23:00:00 - 23:59:59	72370	3.7%	20.10	20.2 GB	3.4%	5897.21
Dec 4 00:00:00 - 00:59:59	76491	4.0%	21.25	22.2 GB	3.7%	6465.23
Dec 4 01:00:00 - 01:59:59	101674	5.3%	28.24	31.8 GB	5.3%	9273.31
Dec 4 02:00:00 - 02:59:59	103726	5.4%	28.81	31.7 GB	5.3%	9242.07
Dec 4 03:00:00 - 03:59:59	86756	4.5%	24.10	27.0 GB	4.5%	7864.93
Dec 4 04:00:00 - 04:15:02	24700	1.3%	27.38	7.6 GB	1.3%	8808.52
TOTAL: 24:00:00	1934975	100.0%	22.40	601.4 GB	100.0%	7298.58

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	16712	175	2589	0	13948
2	news.nask.pl	879	39	815	2	23
3	news.icm.edu.pl	40	0	5	0	35
TOTAL: 3		17631	214	3409	2	14006

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	99
alt.sex.telephones	35
it.tlc.cellulari.android	28
it.comp.os.win.windows7	11
comp.mobile.android	11
hr.alt.cellular.gsm.iphone	9
comp.sys.raspberry-pi	4
comp.mobile.ipad	4
nl.comp.os.linux.techniek	3
uk.rec.cycling.moderated	3
it.comp.os.win.windows8	3
hr.alt.cellular.gsm.android	2
hr.ponuda.hardver.komponente	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 14	214

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

Groups	Count
bit.listserv.catholic	1
pl.sci.historia	1
TOTAL: 2	2

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	13772
HTML post	51
EMP (md5)	32
EMP (fsl)	22
Scoring filter (8)	6
TOTAL: 5	13883

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	49020	4818	29691	3357	35	9%	23:53:46
2	panorama	43779	2012	37804	318	0	4%	23:51:19
3	nask	24880	1015	14566	3	0	4%	23:57:13
4	poznan	5584	65	3845	37	0	1%	24:00:35
5	okapi	0	0	0	0	2871	0%	24:00:00
TOTAL: 5		123263	7910	85906	3715	2906	6%	119:42:53

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	896.7 MB	752.2 MB	1.6 GB	19.6 KB/s	206.5 KB	23:53:46
2	nask	140.0 MB	1.9 MB	141.9 MB	1.7 KB/s	142.8 KB	23:57:13
3	panorama	7.8 MB	2.0 MB	9.7 MB	0.1 KB/s	4.3 KB	23:51:19
4	poznan	289.1 KB	79.1 KB	368.2 KB	0.0 KB/s	3.6 KB	24:00:35
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		1.0 GB	756.1 MB	1.8 GB	4.3 KB/s	158.6 KB	119:42:53

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	374	645.4 KB	288	0	00:00:08
2	web.man.lodz.pl	288	114	101.3 KB	51	0	00:00:27
3	lze200.lze.lodz.pl	6	29	66.6 KB	5	0	01:10:09
4	212.51.203.199	2	4	7.8 KB	1	0	00:06:10
5	isvcs01.man.lodz.pl	8	0	0.0 KB	0	8	00:00:00
TOTAL: 5		592	521	821.1 KB	345	8	04:56:16

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	374	645.4 KB	288	0	00:00:08
2	*.man.lodz.pl	680	114	101.3 KB	51	8	00:00:34
3	*.lze.lodz.pl	6	29	66.6 KB	5	0	01:10:09
4	unresolved	2	4	7.8 KB	1	0	00:06:10
5	*.191.14.62.static.jazztel.es	2	0	0.0 KB	0	0	00:03:21
6	*.p.lodz.pl	24	0	0.0 KB	0	0	03:35:45
7	*.toya.net.pl	71	0	0.0 KB	0	0	00:00:07
TOTAL: 7		1073	521	821.1 KB	345	8	04:56:16

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.084	0.280	03:35:41
lze200.lze.lodz.pl	0.058	0.093	01:10:09
212.51.203.199	0.004	0.037	00:06:10
180.191.14.62.static.jazztel.es	0.082	0.136	00:03:21
web.man.lodz.pl	1.040	3.767	00:00:27
pc-skrzep2.cm.p.lodz.pl	1.029	3.627	00:00:08
staticline41435.toya.net.pl	0.298	0.853	00:00:07
mon.man.lodz.pl	0.911	3.442	00:00:04
r8ds.p.lodz.pl	0.287	0.147	00:00:03
david.man.lodz.pl	0.296	1.170	00:00:01
isvcs01.man.lodz.pl	0.073	0.090	00:00:00
TOTAL: 11	4.162	13.642	04:56:16

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	71
pc-212-51-218-225.p.lodz.pl	23
180.191.14.62.static.jazztel.es	2
r8ds.p.lodz.pl	1
TOTAL: 6	481

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	374	71.8%
2	pl	147	28.2%
TOTAL: 2		521	100.0%

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

Newsgroup		Count
1	lodman.info	374
2	pl.comp.pecet	86
3	pl.comp.os.ms-windows.winnt	28
4	pl.rec.kuchnia	19
5	pl.misc.telefonia.gsm	6
6	pl.rec.radio.amatorskie	4
7	pl.rec.dom	4
TOTAL: 7		521

Daily Usenet report

Dec 3 04:15:02 -- Dec 4 04:15:02