Daily Usenet report: May 13 04:15:03

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

May 13 19:37:03 newsfeed overchan[16472]: input line too long, skipping
May 13 19:37:37 newsfeed overchan[16472]: input line too long, skipping
May 13 19:42:11 newsfeed overchan[16472]: input line too long, skipping
May 13 19:42:32 newsfeed overchan[16472]: input line too long, skipping
May 13 20:24:06 newsfeed overchan[16472]: input line too long, skipping
May 13 20:37:20 newsfeed overchan[16472]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3053379	97.6%	355.0 MB	97.3%
innfeed	45002	1.4%	5.3 MB	1.5%
innd	24358	0.8%	3.9 MB	1.1%
nnrpd	4741	0.2%	499.7 KB	0.1%
overchan	7	0.0%	1.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3127488	100.0%	364.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3033183	97.6%
Negative hits	62683	2.0%
Positive hits	11496	0.4%
Cache misses	110	0.0%
TOTAL: 4	3107472	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.065	0.0%	190	0.000	16.132	571.000
article cleanup	00:00:39.325	0.0%	3052017	0.010	0.013	0.019
article logging	00:01:57.090	0.1%	3052611	0.028	0.038	0.152
article parse	01:09:38.478	4.8%	193503554	0.016	0.022	0.033
article write	01:00:22.492	4.2%	3041047	0.334	1.191	5.105
artlog/artcncl	00:00:00.019	0.0%	187	0.000	0.102	4.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:56.690	0.2%	212097092	0.001	0.001	0.002
hisgrep/artcncl	00:00:01.079	0.0%	175	0.000	6.166	571.000
hishave/artcncl	00:00:00.974	0.0%	190	0.000	5.126	49.111
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:49:31.617	3.4%	3122431	0.041	0.952	8.682
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	01:57:58.075	8.2%	3046527	0.260	2.323	11.303
hiswrite/artcncl	00:00:00.120	0.0%	15	0.000	8.000	12.750
idle	11:29:45.081	47.9%	129330974	0.146	0.320	0.597
nntp read	01:18:54.264	5.5%	191066244	0.018	0.025	0.034
overview write	00:00:15.828	0.0%	3041047	0.003	0.005	0.007
perl filter	05:19:40.365	22.2%	3046527	5.403	6.296	7.678
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:04:43.296	0.3%	6271140	0.036	0.045	0.074
TOTAL: 24:00:02.198	23:16:27.878	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.839	0.0%	296215	0.000	0.003	0.012
article prepare	00:00:00.169	0.0%	693544	0.000	0.000	0.031
article read	00:03:51.308	0.3%	173327	0.000	1.335	463.750
backlog stats	00:00:00.852	0.0%	907274	0.000	0.001	0.003
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:08.146	0.0%	454998	0.005	0.018	0.056
data write	00:03:35.182	0.3%	469473	0.016	0.458	0.613
idle	23:45:05.343	99.4%	907274	17.559	94.244	863.920
status file	00:00:14.193	0.0%	16582	0.573	0.856	1.700
TOTAL: 23:53:27.519	23:52:56.032	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:10:20.001	98.0%	7637	0.000	81.184	10037.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.381	0.1%	15902	0.000	0.024	1.333
readart	00:00:00.013	0.0%	26	0.000	0.500	6.500
TOTAL: 00:10:32.335	00:10:20.395	98.1%	-	-	-	-

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	763	3042638	3034028	2734	5876	99%	442:16:31
2	news.nask.pl	1	15763	7532	3137	5094	47%	24:03:10
3	news.icm.edu.pl	51	194	10	166	18	5%	15:16:56
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		816	3058595	3041570	6037	10988	99%	481:36:37

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	885.6 GB	202.9 MB	353.7 MB	886.1 GB	99%	305.7 KB
2	news.nask.pl	36.8 MB	1.5 MB	203.8 MB	242.1 MB	15%	19.6 KB
3	news.icm.edu.pl	269.1 KB	31.8 KB	1.3 MB	1.5 MB	16%	56.6 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		885.6 GB	204.5 MB	558.7 MB	886.4 GB	99%	304.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
May 13 04:15:03 - 04:59:59	77425	2.5%	28.71	24.4 GB	2.8%	9492.26
May 13 05:00:00 - 05:59:59	106335	3.5%	29.54	34.6 GB	3.9%	10089.37
May 13 06:00:00 - 06:59:59	120403	4.0%	33.45	38.6 GB	4.4%	11245.95
May 13 07:00:00 - 07:59:59	127916	4.2%	35.53	39.7 GB	4.5%	11568.81
May 13 08:00:00 - 08:59:59	136822	4.5%	38.01	40.4 GB	4.6%	11781.41
May 13 09:00:00 - 09:59:59	142785	4.7%	39.66	42.2 GB	4.8%	12295.76
May 13 10:00:00 - 10:59:59	141497	4.7%	39.30	41.9 GB	4.7%	12199.41
May 13 11:00:00 - 11:59:59	147629	4.9%	41.01	41.5 GB	4.7%	12073.26
May 13 12:00:00 - 12:59:59	150087	4.9%	41.69	41.2 GB	4.7%	12004.87
May 13 13:00:00 - 13:59:59	144701	4.8%	40.19	40.1 GB	4.5%	11669.65
May 13 14:00:00 - 14:59:59	146979	4.8%	40.83	40.6 GB	4.6%	11820.02
May 13 15:00:00 - 15:59:59	146577	4.8%	40.72	41.0 GB	4.6%	11952.07
May 13 16:00:00 - 16:59:59	147707	4.9%	41.03	41.9 GB	4.7%	12212.33
May 13 17:00:00 - 17:59:59	145879	4.8%	40.52	40.1 GB	4.5%	11691.11
May 13 18:00:00 - 18:59:59	149667	4.9%	41.57	39.0 GB	4.4%	11348.78
May 13 19:00:00 - 19:59:59	144086	4.7%	40.02	38.6 GB	4.4%	11235.86
May 13 20:00:00 - 20:59:59	120838	4.0%	33.57	33.4 GB	3.8%	9721.57
May 13 21:00:00 - 21:59:59	136143	4.5%	37.82	37.8 GB	4.3%	11006.34
May 13 22:00:00 - 22:59:59	100150	3.3%	27.82	31.3 GB	3.5%	9120.31
May 13 23:00:00 - 23:59:59	69152	2.3%	19.21	20.5 GB	2.3%	5968.24
May 14 00:00:00 - 00:59:59	107000	3.5%	29.72	31.9 GB	3.6%	9294.82
May 14 01:00:00 - 01:59:59	103936	3.4%	28.87	32.2 GB	3.6%	9368.29
May 14 02:00:00 - 02:59:59	110801	3.6%	30.78	34.6 GB	3.9%	10069.78
May 14 03:00:00 - 03:59:59	91309	3.0%	25.36	29.2 GB	3.3%	8507.12
May 14 04:00:00 - 04:15:02	25808	0.8%	28.61	8.3 GB	0.9%	9616.51
TOTAL: 23:59:59	3041632	100.0%	35.20	885.0 GB	100.0%	10740.37

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	6225	136	5056	3	1030
2	news.nask.pl	5316	36	428	6	4846
3	news.icm.edu.pl	19	0	1	0	18
TOTAL: 3		11560	172	5485	9	5894

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	86
comp.mobile.android	39
it.tlc.cellulari.android	18
it.comp.os.win.windows10	13
comp.sys.raspberry-pi	8
comp.mobile.ipad	2
it.comp.os.win.windows7	2
alt.sex.telephones	2
it.comp.dotnet	1
hr.ponuda.hardver.komponente	1
TOTAL: 10	172

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

Groups	Count
pl.praca.oferowana	6
bit.listserv.catholic	1
pl.sci.matematyka	1
pl.sci.historia	1
TOTAL: 4	9

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	3725
Binary in non-binary group	1347
EMP (phl)	125
Scoring filter (8)	34
Scoring filter (10)	33
Too many newsgroups	16
Scoring filter (9)	7
Scoring filter (11)	6
HTML post	3
Scoring filter (12)	2
Scoring filter (13)	2
Excessively crossposted test article	1
TOTAL: 12	5301

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	352
	news.nask.pl	240
	news.icm.edu.pl	1
	TOTAL: 3	593
TOTAL: 1		593

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	icm	188912	165968	13181	5936	6	5434	87%	23:59:57
2	panorama	114569	441	85992	477	6	204131	0%	23:59:57
3	poznan	11173	160	7241	38	0	1	1%	23:59:53
4	nask	6367	11	4342	0	0	0	0%	23:59:26
5	okapi	0	0	0	0	0	993	0%	23:57:50
TOTAL: 5		321021	166580	110756	6451	12	210559	51%	119:57:03

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	29.8 GB	419.1 MB	30.2 GB	366.6 KB/s	184.3 KB	23:59:57
2	panorama	1.4 MB	8.4 MB	9.8 MB	0.1 KB/s	11.0 KB	23:59:57
3	poznan	709.0 KB	106.3 KB	815.3 KB	0.0 KB/s	4.1 KB	23:59:53
4	nask	24.6 KB	0.0 KB	24.6 KB	0.0 KB/s	2.2 KB	23:59:26
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		29.8 GB	427.7 MB	30.2 GB	73.4 KB/s	183.1 KB	119:57:03

Backlog files shrunk by innfeed:

Server	Size
panorama	8.6 MB
TOTAL: 1	8.6 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	26	23.6 KB	12	0	0	00:00:25
TOTAL: 1		288	26	23.6 KB	12	0	0	00:10:31

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	26	23.6 KB	12	00:00:31
2	unresolved	2	0	0.0 KB	0	00:10:00
TOTAL: 2		674	26	23.6 KB	12	00:10:31

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.194.49	0.240	0.496	00:10:00
web.man.lodz.pl	1.188	4.716	00:00:25
mon.man.lodz.pl	1.020	4.580	00:00:04
david.man.lodz.pl	0.356	1.548	00:00:01
TOTAL: 4	2.804	11.340	00:10:31

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.194.49	2
TOTAL: 3	386

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.194.49	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	14
2	pl.comp.pecet	12
TOTAL: 2		26

Daily Usenet report

May 13 04:15:03 -- May 14 04:15:02