Daily Usenet report: Apr 1 04:15:03

Unknown entries from news log file:

First 13 / 13 lines (100.0%)

Apr 1 06:56:12 newsfeed overchan[2371]: input line too long, skipping
Apr 1 07:04:04 newsfeed pgpverify[9480]: pgpverify: verification failed
Apr 1 07:20:18 newsfeed overchan[2371]: input line too long, skipping
Apr 1 08:07:02 newsfeed overchan[2371]: input line too long, skipping
Apr 1 12:17:02 newsfeed nnrpd[19782]: SERVER perl filter not defined
Apr 1 13:23:16 newsfeed nnrpd[22003]: SERVER perl filter not defined
Apr 1 14:59:30 newsfeed overchan[2371]: input line too long, skipping
Apr 1 17:00:11 newsfeed nnrpd[29334]: SERVER perl filter not defined
Apr 1 17:00:29 newsfeed nnrpd[29335]: SERVER perl filter not defined
Apr 1 18:44:56 newsfeed nnrpd[32677]: SERVER perl filter not defined
Apr 1 20:22:42 newsfeed nnrpd[3574]: SERVER perl filter not defined
Apr 1 20:24:06 newsfeed overchan[2371]: input line too long, skipping
Apr 1 23:26:32 newsfeed nnrpd[9636]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1665239	98.1%	189.9 MB	97.7%
innd	18041	1.1%	2.8 MB	1.4%
nnrpd	7066	0.4%	743.7 KB	0.4%
innfeed	6692	0.4%	985.8 KB	0.5%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	1697047	100.0%	194.3 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1644660	96.1%
Negative hits	49548	2.9%
Positive hits	16145	0.9%
Cache misses	308	0.0%
TOTAL: 4	1710661	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:20.564	0.0%	500	0.000	41.128	598.000
article cleanup	00:00:32.145	0.0%	1650098	0.004	0.019	0.046
article logging	00:00:56.827	0.1%	1665520	0.014	0.034	0.158
article parse	01:06:01.784	4.6%	133273112	0.012	0.030	0.054
article write	00:21:18.887	1.5%	1642351	0.364	0.779	5.012
artlog/artcncl	00:00:00.014	0.0%	478	0.000	0.029	1.000
data move	00:02:28.723	0.2%	143587292	0.001	0.001	0.003
hisgrep/artcncl	00:00:05.540	0.0%	431	0.000	12.854	264.000
hishave/artcncl	00:00:01.030	0.0%	500	0.000	2.060	18.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:44:56.035	7.3%	1712135	0.005	3.677	8.857
history sync	00:00:00.020	0.0%	297	0.000	0.067	4.000
history write	03:35:23.153	15.0%	1646386	0.053	7.849	23.258
hiswrite/artcncl	00:00:00.996	0.0%	69	0.000	14.435	249.000
idle	11:39:59.063	48.6%	82835806	0.321	0.507	3.252
nntp read	00:55:24.011	3.8%	131891606	0.016	0.025	0.040
overview write	00:00:12.617	0.0%	1642351	0.000	0.008	0.028
perl filter	03:56:02.230	16.4%	1646386	5.605	8.602	12.221
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:37.133	0.3%	3340612	0.013	0.065	0.121
TOTAL: 24:00:35.297	23:27:20.772	97.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.207	0.0%	55929	0.000	0.004	1.000
article prepare	00:00:00.007	0.0%	31878	0.000	0.000	0.125
article read	00:00:01.319	0.0%	7900	0.000	0.167	1.953
backlog stats	00:00:00.178	0.0%	287379	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:02.194	0.0%	184945	0.000	0.012	1.000
data write	00:00:08.643	0.0%	139455	0.000	0.062	12.000
idle	23:59:19.909	100.0%	287379	0.000	300.509	19206.000
status file	00:00:01.531	0.0%	195	0.000	7.851	272.000
TOTAL: 23:59:41.617	23:59:33.988	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:00.310	0.0%	75	3.955	4.133	4.208
idle	06:43:11.286	99.9%	47824	0.000	505.840	450269.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.985	0.0%	97685	0.000	0.020	0.667
readart	00:00:01.694	0.0%	563	0.000	3.009	16.000
TOTAL: 06:43:42.367	06:43:15.275	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	686	1645170	1637093	1739	6338	99%	320:30:06
2	news.nask.pl	2	16100	4608	10231	1261	28%	23:31:11
3	news.icm.edu.pl	38	331	24	198	109	7%	18:49:39
4	localhost	8	7	7	0	0	100%	00:00:00
TOTAL: 4		734	1661608	1641732	12168	7708	98%	362:50:56

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	543.5 GB	12.9 MB	637.4 MB	544.2 GB	99%	347.2 KB
2	news.nask.pl	17.0 MB	3.4 MB	3.5 MB	23.9 MB	71%	4.2 KB
3	news.icm.edu.pl	427.1 KB	19.0 KB	3.1 MB	3.6 MB	11%	27.5 KB
4	localhost	14.3 KB	0.0 KB	0.0 KB	14.3 KB	100%	2.0 KB
TOTAL: 4		543.5 GB	16.4 MB	644.0 MB	544.2 GB	99%	346.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 1 04:15:03 - 04:59:59	49375	3.0%	18.31	18.2 GB	3.3%	7072.68
Apr 1 05:00:00 - 05:59:59	78765	4.8%	21.88	27.3 GB	5.0%	7956.51
Apr 1 06:00:00 - 06:59:59	58078	3.5%	16.13	18.6 GB	3.4%	5426.80
Apr 1 07:00:00 - 07:59:59	51332	3.1%	14.26	17.6 GB	3.2%	5130.36
Apr 1 08:00:00 - 08:59:59	23981	1.5%	6.66	7.4 GB	1.4%	2163.79
Apr 1 09:00:00 - 09:59:59	38726	2.4%	10.76	13.2 GB	2.4%	3838.94
Apr 1 10:00:00 - 10:59:59	65076	4.0%	18.08	19.8 GB	3.7%	5778.10
Apr 1 11:00:00 - 11:59:59	90906	5.5%	25.25	26.4 GB	4.9%	7687.52
Apr 1 12:00:00 - 12:59:59	92203	5.6%	25.61	28.1 GB	5.2%	8193.77
Apr 1 13:00:00 - 13:59:59	86422	5.3%	24.01	26.8 GB	4.9%	7811.57
Apr 1 14:00:00 - 14:59:59	76313	4.6%	21.20	26.7 GB	4.9%	7783.30
Apr 1 15:00:00 - 15:59:59	78482	4.8%	21.80	27.9 GB	5.1%	8113.16
Apr 1 16:00:00 - 16:59:59	76134	4.6%	21.15	27.6 GB	5.1%	8033.13
Apr 1 17:00:00 - 17:59:59	74399	4.5%	20.67	27.1 GB	5.0%	7881.33
Apr 1 18:00:00 - 18:59:59	78441	4.8%	21.79	26.8 GB	4.9%	7818.59
Apr 1 19:00:00 - 19:59:59	69336	4.2%	19.26	26.3 GB	4.8%	7647.68
Apr 1 20:00:00 - 20:59:59	88468	5.4%	24.57	26.2 GB	4.8%	7638.87
Apr 1 21:00:00 - 21:59:59	91847	5.6%	25.51	25.6 GB	4.7%	7464.32
Apr 1 22:00:00 - 22:59:59	57545	3.5%	15.98	17.4 GB	3.2%	5069.03
Apr 1 23:00:00 - 23:59:59	60712	3.7%	16.86	23.1 GB	4.3%	6735.31
Apr 2 00:00:00 - 00:59:59	58020	3.5%	16.12	17.4 GB	3.2%	5061.05
Apr 2 01:00:00 - 01:59:59	61387	3.7%	17.05	19.6 GB	3.6%	5720.07
Apr 2 02:00:00 - 02:59:59	45255	2.8%	12.57	16.8 GB	3.1%	4893.43
Apr 2 03:00:00 - 03:59:59	70712	4.3%	19.64	24.6 GB	4.5%	7176.51
Apr 2 04:00:00 - 04:15:03	19678	1.2%	21.79	6.5 GB	1.2%	7598.41
TOTAL: 24:00:00	1641593	100.0%	19.00	543.2 GB	100.0%	6592.82

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	21707	292	2439	4	18972
2	news.nask.pl	1351	12	1268	8	63
3	news.icm.edu.pl	110	0	3	0	107
TOTAL: 3		23168	304	3710	12	19142

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	207
it.tlc.cellulari.android	49
comp.mobile.android	12
comp.mobile.ipad	10
it.comp.os.win.windows7	7
de.comp.lang.vbclassic	6
uk.rec.cycling.moderated	3
comp.sys.raspberry-pi	2
de.rec.sf.startrek	2
alt.sex.telephones	2
hr.rec.glazba.hifi	2
nl.comp.os.linux.techniek	1
de.comm.software.mailreader	1
TOTAL: 13	304

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

Groups	Count
pl.praca.oferowana	5
pl.sci.historia	4
pl.sci.matematyka	3
TOTAL: 3	12

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	3602
EMP (md5)	108
HTML post	5
EMP (fsl)	2
Scoring filter (8)	1
Too many newsgroups	1
Scoring filter (10)	1
TOTAL: 7	3720

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	56572	1850	53817	218	0	0	3%	24:00:57
2	icm	40639	368	28907	4807	1	29	0%	24:02:42
3	poznan	5858	19	4164	27	0	0	0%	23:59:28
4	nask	20866	12	14520	667	0	0	0%	23:58:19
5	okapi	0	0	0	0	0	2402	0%	24:00:00
TOTAL: 5		123935	2249	101408	5719	1	2431	1%	120:01:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	43.3 MB	900.9 MB	944.2 MB	11.2 KB/s	186.8 KB	24:02:42
2	panorama	7.6 MB	676.3 KB	8.3 MB	0.1 KB/s	4.1 KB	24:00:57
3	nask	61.9 KB	247.3 MB	247.4 MB	2.9 KB/s	373.1 KB	23:58:19
4	poznan	58.1 KB	59.1 KB	117.3 KB	0.0 KB/s	2.5 KB	23:59:28
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 5		51.0 MB	1.1 GB	1.2 GB	2.8 KB/s	154.2 KB	120:01:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	362	705.3 KB	288	0	00:00:08
2	pc-212-51-218-225.p.lodz.pl	10	129	258.7 KB	5	0	04:04:44
3	web.man.lodz.pl	288	72	89.1 KB	27	0	00:00:24
4	isvcs01.man.lodz.pl	7	0	0.0 KB	0	7	00:00:00
TOTAL: 4		593	563	1.0 MB	320	7	06:43:41

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	362	705.3 KB	288	0	00:00:08
2	*.p.lodz.pl	10	129	258.7 KB	5	0	04:04:44
3	*.man.lodz.pl	679	72	89.1 KB	27	7	00:00:31
4	*.snarked.org	2	0	0.0 KB	0	0	00:00:02
5	unresolved	6	0	0.0 KB	0	0	02:37:57
6	*.rz.izeto.pl	1	0	0.0 KB	0	0	00:00:08
7	*.range109-156.btcentralplus.com	1	0	0.0 KB	0	0	00:00:05
8	*.toya.net.pl	25	0	0.0 KB	0	0	00:00:02
TOTAL: 8		1012	563	1.0 MB	320	7	06:43:41

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
pc-212-51-218-225.p.lodz.pl	0.074	0.199	04:04:44
185.48.194.161	2.393	3.493	02:22:15
212.191.87.58	0.007	0.019	00:15:42
web.man.lodz.pl	1.165	5.267	00:00:24
auriga.rz.izeto.pl	0.006	0.013	00:00:08
pc-skrzep2.cm.p.lodz.pl	1.195	5.030	00:00:08
mon.man.lodz.pl	1.105	5.331	00:00:05
host109-156-62-126.range109-156.btcentralplus.com	0.008	0.016	00:00:05
news.snarked.org	0.035	0.038	00:00:02
staticline41435.toya.net.pl	0.090	0.475	00:00:02
david.man.lodz.pl	0.369	1.652	00:00:01
isvcs01.man.lodz.pl	0.062	0.146	00:00:00
TOTAL: 12	6.509	21.679	06:43:41

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	25
185.48.194.161	5
news.snarked.org	2
auriga.rz.izeto.pl	1
host109-156-62-126.range109-156.btcentralplus.com	1
212.191.87.58	1
TOTAL: 8	419

NNRP client timeouts [Top 20]:

System	Conn	Peer
pc-212-51-218-225.p.lodz.pl	9	2
185.48.194.161	3	0
TOTAL: 2	12	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	362	64.3%
2	pl	201	35.7%
TOTAL: 2		563	100.0%

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

Newsgroup		Count
1	lodman.info	362
2	pl.misc.kolej	77
3	pl.comp.pecet	72
4	pl.misc.elektronika	52
TOTAL: 4		563

Daily Usenet report

Apr 1 04:15:03 -- Apr 2 04:15:03