Daily Usenet report: Apr 1 04:15:03

Unknown entries from news log file:

First 8 / 8 lines (100.0%)

Apr 1 07:06:48 newsfeed overchan[25164]: input line too long, skipping
Apr 1 07:07:03 newsfeed overchan[25164]: input line too long, skipping
Apr 1 17:04:38 newsfeed overchan[25164]: input line too long, skipping
Apr 1 20:36:29 newsfeed overchan[25164]: input line too long, skipping
Apr 1 20:58:56 newsfeed overchan[25164]: input line too long, skipping
Apr 1 21:18:09 newsfeed overchan[25164]: input line too long, skipping
Apr 1 21:29:09 newsfeed overchan[25164]: input line too long, skipping
Apr 1 23:17:37 newsfeed overchan[25164]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3106425	97.7%	338.9 MB	97.2%
innfeed	45160	1.4%	5.4 MB	1.5%
innd	24118	0.8%	3.8 MB	1.1%
nnrpd	4744	0.1%	503.7 KB	0.1%
overchan	9	0.0%	1.7 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3180457	100.0%	348.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3097902	96.3%
Negative hits	109592	3.4%
Positive hits	9200	0.3%
Cache misses	101	0.0%
TOTAL: 4	3216795	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:02.848	0.0%	143	0.000	19.916	588.500
article cleanup	00:00:40.030	0.0%	3106222	0.010	0.013	0.018
article logging	00:02:04.439	0.1%	3106833	0.031	0.040	0.078
article parse	00:53:01.584	3.7%	109963301	0.013	0.029	0.040
article write	00:19:56.625	1.4%	3101825	0.225	0.386	1.174
artlog/artcncl	00:00:00.005	0.0%	142	0.000	0.035	0.500
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:04:26.192	0.3%	125674719	0.001	0.002	0.003
hisgrep/artcncl	00:00:00.463	0.0%	138	0.000	3.355	38.000
hishave/artcncl	00:00:00.191	0.0%	143	0.000	1.336	28.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:35:29.693	2.5%	3224274	0.041	0.661	2.328
history sync	00:00:00.013	0.0%	290	0.000	0.045	0.500
history write	01:58:41.453	8.2%	3104846	0.349	2.294	7.803
hiswrite/artcncl	00:00:00.021	0.0%	5	0.000	4.200	14.000
idle	12:50:31.167	53.5%	86333478	0.324	0.535	1.611
nntp read	01:21:10.560	5.6%	107473458	0.030	0.045	0.057
overview write	00:00:16.918	0.0%	3101825	0.004	0.005	0.008
perl filter	05:16:03.767	21.9%	3104846	4.206	6.108	7.445
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:06.844	0.4%	6219572	0.047	0.059	0.080
TOTAL: 24:00:04.918	23:28:32.813	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.183	0.0%	69246	0.000	0.003	0.013
article prepare	00:00:00.007	0.0%	13818	0.000	0.001	0.019
article read	00:01:04.545	0.1%	3490	0.000	18.494	281.143
backlog stats	00:00:00.129	0.0%	167135	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	29	0.000	0.000	0.000
data read	00:00:01.637	0.0%	84855	0.004	0.019	0.042
data write	00:00:05.469	0.0%	71722	0.020	0.076	0.222
idle	23:52:11.718	99.9%	167135	148.150	514.146	1046.745
status file	00:00:21.047	0.0%	16044	0.625	1.312	19.350
TOTAL: 23:54:02.616	23:53:44.735	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:03:11.123	93.0%	4255	0.000	44.917	10047.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.401	0.2%	9130	0.000	0.044	0.500
readart	00:00:00.002	0.0%	22	0.000	0.091	0.500
TOTAL: 00:03:25.562	00:03:11.526	93.2%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	27
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	192

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	1288	3103012	3093095	6553	3364	99%	456:50:39
2	news.nask.pl	2	10178	8144	1052	982	80%	23:51:31
3	news.icm.edu.pl	45	245	22	203	20	8%	17:03:02
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		1336	3113435	3101261	7808	4366	99%	497:45:12

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	727.6 GB	7.4 MB	377.2 MB	728.0 GB	99%	246.5 KB
2	news.nask.pl	33.1 MB	310.4 KB	9.7 MB	43.1 MB	76%	4.8 KB
3	news.icm.edu.pl	547.9 KB	22.2 KB	1.3 MB	1.9 MB	28%	45.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		727.6 GB	7.7 MB	388.2 MB	728.0 GB	99%	245.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Apr 1 04:15:03 - 04:59:59	94212	3.0%	34.93	24.7 GB	3.4%	9621.22
Apr 1 05:00:00 - 05:59:59	141235	4.6%	39.23	37.6 GB	5.2%	10951.76
Apr 1 06:00:00 - 06:59:59	142850	4.6%	39.68	37.5 GB	5.2%	10935.94
Apr 1 07:00:00 - 07:59:59	122112	3.9%	33.92	29.8 GB	4.1%	8666.23
Apr 1 08:00:00 - 08:59:59	128107	4.1%	35.59	35.2 GB	4.8%	10254.93
Apr 1 09:00:00 - 09:59:59	176790	5.7%	49.11	36.1 GB	5.0%	10516.33
Apr 1 10:00:00 - 10:59:59	140241	4.5%	38.96	37.0 GB	5.1%	10789.19
Apr 1 11:00:00 - 11:59:59	134555	4.3%	37.38	38.1 GB	5.2%	11104.57
Apr 1 12:00:00 - 12:59:59	126066	4.1%	35.02	37.9 GB	5.2%	11053.33
Apr 1 13:00:00 - 13:59:59	152413	4.9%	42.34	34.9 GB	4.8%	10161.57
Apr 1 14:00:00 - 14:59:59	102069	3.3%	28.35	25.3 GB	3.5%	7369.58
Apr 1 15:00:00 - 15:59:59	106351	3.4%	29.54	25.5 GB	3.5%	7426.80
Apr 1 16:00:00 - 16:59:59	104581	3.4%	29.05	28.1 GB	3.9%	8191.12
Apr 1 17:00:00 - 17:59:59	144982	4.7%	40.27	36.2 GB	5.0%	10544.30
Apr 1 18:00:00 - 18:59:59	93508	3.0%	25.97	22.2 GB	3.1%	6470.57
Apr 1 19:00:00 - 19:59:59	134401	4.3%	37.33	25.7 GB	3.5%	7488.04
Apr 1 20:00:00 - 20:59:59	122943	4.0%	34.15	27.2 GB	3.7%	7909.29
Apr 1 21:00:00 - 21:59:59	123617	4.0%	34.34	24.4 GB	3.3%	7095.76
Apr 1 22:00:00 - 22:59:59	141341	4.6%	39.26	27.1 GB	3.7%	7890.03
Apr 1 23:00:00 - 23:59:59	137706	4.4%	38.25	29.6 GB	4.1%	8626.48
Apr 2 00:00:00 - 00:59:59	136155	4.4%	37.82	24.1 GB	3.3%	7033.72
Apr 2 01:00:00 - 01:59:59	140628	4.5%	39.06	29.3 GB	4.0%	8528.30
Apr 2 02:00:00 - 02:59:59	131396	4.2%	36.50	27.6 GB	3.8%	8049.20
Apr 2 03:00:00 - 03:59:59	101049	3.3%	28.07	21.3 GB	2.9%	6218.44
Apr 2 04:00:00 - 04:15:02	21993	0.7%	24.38	5.3 GB	0.7%	6181.53
TOTAL: 23:59:59	3101301	100.0%	35.90	728.0 GB	100.0%	8834.78

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	3789	122	1253	0	2414
2	news.nask.pl	1170	14	121	1	1034
3	news.icm.edu.pl	23	0	2	0	21
TOTAL: 3		4982	136	1376	1	3469

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	64
it.comp.os.win.windows10	33
it.tlc.cellulari.android	19
comp.mobile.android	12
it.comp.os.win.windows7	2
de.rec.sf.startrek	2
it.comp.os.win.windows8	1
hr.alt.cellular.gsm.android	1
openwatcom.users.assembler	1
it.comp.os.linux.ubuntu	1
TOTAL: 10	136

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

Groups	Count
bit.listserv.catholic	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	2061
EMP (md5)	682
EMP (phl)	52
Scoring filter (8)	20
Poison newsgroup	19
Scoring filter (9)	8
Scoring filter (10)	7
Too many newsgroups	5
HTML post	2
TOTAL: 9	2856

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	425
	news.nask.pl	186
	news.icm.edu.pl	2
	TOTAL: 3	613
TOTAL: 1		613

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	57949	772	30204	212	86	38069	1%	23:59:57
2	poznan	13206	258	7876	13	1	1	1%	23:59:37
3	icm	21326	53	14313	1360	0	1	0%	23:59:44
4	nask	2500	0	1767	0	0	0	0%	23:50:12
5	okapi	0	0	0	0	0	785	0%	23:57:49
TOTAL: 5		94981	1083	54160	1585	87	38856	1%	119:47:19

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	11.8 MB	496.7 MB	508.5 MB	6.0 KB/s	368.5 KB	23:59:44
2	panorama	2.0 MB	2.0 MB	4.0 MB	0.0 KB/s	4.1 KB	23:59:57
3	poznan	763.7 KB	214.6 KB	978.3 KB	0.0 KB/s	3.6 KB	23:59:37
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:12
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:49
TOTAL: 5		14.6 MB	498.9 MB	513.5 MB	1.2 KB/s	197.1 KB	119:47:19

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	22	21.0 KB	11	0	0	00:00:24
TOTAL: 1		288	22	21.0 KB	11	0	0	00:03:24

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	22	21.0 KB	11	00:00:30
2	unresolved	3	0	0.0 KB	0	00:02:53
TOTAL: 2		675	22	21.0 KB	11	00:03:24

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.193.99	0.088	0.184	00:02:53
web.man.lodz.pl	1.824	5.292	00:00:24
mon.man.lodz.pl	1.536	4.748	00:00:05
david.man.lodz.pl	0.468	1.272	00:00:01
TOTAL: 4	3.916	11.496	00:03:24

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.193.99	3
TOTAL: 3	387

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.193.99	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

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