Daily Usenet report: Mar 8 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

Mar 8 07:03:11 newsfeed overchan[6101]: input line too long, skipping
Mar 8 07:28:39 newsfeed overchan[6101]: input line too long, skipping
Mar 8 07:28:58 newsfeed overchan[6101]: input line too long, skipping
Mar 8 07:29:16 newsfeed overchan[6101]: input line too long, skipping
Mar 8 07:53:51 newsfeed nnrpd[14861]: SERVER perl filter not defined
Mar 8 08:30:27 newsfeed nnrpd[16297]: SERVER perl filter not defined
Mar 8 08:32:09 newsfeed nnrpd[16342]: SERVER perl filter not defined
Mar 8 09:39:19 newsfeed overchan[6101]: input line too long, skipping
Mar 8 10:51:52 newsfeed nnrpd[21485]: SERVER perl filter not defined
Mar 8 11:56:26 newsfeed overchan[6101]: input line too long, skipping
Mar 8 12:24:57 newsfeed overchan[6101]: input line too long, skipping
Mar 8 12:54:55 newsfeed nnrpd[26160]: SERVER perl filter not defined
Mar 8 15:25:08 newsfeed overchan[6101]: input line too long, skipping
Mar 8 16:40:53 newsfeed overchan[6101]: input line too long, skipping
Mar 8 16:41:15 newsfeed overchan[6101]: input line too long, skipping
Mar 8 16:42:24 newsfeed overchan[6101]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3449632	98.4%	384.1 MB	98.1%
innd	25688	0.7%	4.1 MB	1.0%
innfeed	24735	0.7%	2.9 MB	0.8%
nnrpd	4813	0.1%	507.4 KB	0.1%
overchan	12	0.0%	2.1 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3504881	100.0%	391.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3401868	97.9%
Negative hits	59731	1.7%
Positive hits	11597	0.3%
Cache misses	124	0.0%
TOTAL: 4	3473320	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.301	0.0%	238	0.000	5.466	51.000
article cleanup	00:00:42.060	0.0%	3446956	0.008	0.012	0.016
article logging	00:02:33.937	0.2%	3447626	0.031	0.045	0.129
article parse	00:44:20.169	3.1%	76604284	0.022	0.035	0.053
article write	00:18:28.546	1.3%	3433517	0.204	0.323	0.479
artlog/artcncl	00:00:00.011	0.0%	232	0.000	0.047	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:01:51.836	0.1%	91571074	0.001	0.001	0.002
hisgrep/artcncl	00:00:00.184	0.0%	230	0.000	0.800	14.000
hishave/artcncl	00:00:00.051	0.0%	238	0.000	0.214	2.062
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:28:23.121	2.0%	3509183	0.029	0.485	4.456
history sync	00:00:00.020	0.0%	290	0.000	0.069	1.000
history write	01:39:48.130	6.9%	3436763	0.215	1.742	8.293
hiswrite/artcncl	00:00:00.067	0.0%	8	0.000	8.375	22.333
idle	14:06:29.446	58.8%	56810297	0.480	0.894	2.674
nntp read	00:50:05.000	3.5%	74249581	0.027	0.040	0.052
overview write	00:00:18.400	0.0%	3433517	0.004	0.005	0.007
perl filter	05:08:07.957	21.4%	3436763	3.509	5.379	6.872
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:36.740	0.5%	6886672	0.040	0.058	0.077
TOTAL: 24:00:02.225	23:27:46.976	97.8%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.113	0.0%	45400	0.000	0.002	0.015
article prepare	00:00:00.004	0.0%	10582	0.000	0.000	0.025
article read	00:01:16.293	0.1%	2690	0.000	28.362	406.154
backlog stats	00:00:00.137	0.0%	156606	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	34	0.000	0.000	0.000
data read	00:00:01.438	0.0%	82940	0.000	0.017	0.035
data write	00:00:03.706	0.0%	63632	0.022	0.058	0.120
idle	23:53:09.379	99.9%	156606	309.808	549.081	1019.084
status file	00:00:08.300	0.0%	8087	0.571	1.026	2.067
TOTAL: 23:54:51.002	23:54:39.370	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:34:24.190	99.3%	3110	0.000	663.727	327759.667
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.261	0.0%	6867	0.000	0.038	1.000
readart	00:00:00.036	0.0%	59	0.000	0.610	29.000
TOTAL: 00:34:38.500	00:34:24.487	99.3%	-	-	-	-

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	1204	3435776	3424362	400	11014	99%	409:51:37
2	news.nask.pl	2	14229	11009	795	2425	77%	23:58:46
3	news.icm.edu.pl	45	267	15	242	10	5%	16:14:50
4	localhost	6	5	5	0	0	100%	00:00:00
TOTAL: 4		1257	3450277	3435391	1437	13449	99%	450:05:13

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	653.4 GB	45.9 MB	219.1 MB	653.7 GB	99%	199.5 KB
2	news.nask.pl	48.6 MB	1.9 MB	17.9 MB	68.4 MB	71%	5.2 KB
3	news.icm.edu.pl	321.9 KB	99.3 KB	494.1 KB	915.3 KB	35%	36.6 KB
4	localhost	16.6 KB	0.0 KB	0.0 KB	16.6 KB	100%	3.3 KB
TOTAL: 4		653.5 GB	47.9 MB	237.5 MB	653.7 GB	99%	198.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Mar 8 04:15:02 - 04:59:59	109790	3.2%	40.69	22.1 GB	3.4%	8590.12
Mar 8 05:00:00 - 05:59:59	151234	4.4%	42.01	30.7 GB	4.7%	8950.17
Mar 8 06:00:00 - 06:59:59	156034	4.5%	43.34	33.2 GB	5.1%	9662.73
Mar 8 07:00:00 - 07:59:59	152426	4.4%	42.34	33.8 GB	5.2%	9851.80
Mar 8 08:00:00 - 08:59:59	150240	4.4%	41.73	36.2 GB	5.5%	10545.14
Mar 8 09:00:00 - 09:59:59	174609	5.1%	48.50	31.6 GB	4.8%	9198.74
Mar 8 10:00:00 - 10:59:59	173530	5.1%	48.20	32.3 GB	4.9%	9410.23
Mar 8 11:00:00 - 11:59:59	127239	3.7%	35.34	28.8 GB	4.4%	8385.68
Mar 8 12:00:00 - 12:59:59	116397	3.4%	32.33	23.6 GB	3.6%	6865.14
Mar 8 13:00:00 - 13:59:59	156406	4.6%	43.45	26.6 GB	4.1%	7736.49
Mar 8 14:00:00 - 14:59:59	150317	4.4%	41.75	29.4 GB	4.5%	8568.65
Mar 8 15:00:00 - 15:59:59	164172	4.8%	45.60	32.0 GB	4.9%	9317.67
Mar 8 16:00:00 - 16:59:59	112758	3.3%	31.32	14.3 GB	2.2%	4179.43
Mar 8 17:00:00 - 17:59:59	171513	5.0%	47.64	30.8 GB	4.7%	8958.06
Mar 8 18:00:00 - 18:59:59	82408	2.4%	22.89	12.3 GB	1.9%	3586.51
Mar 8 19:00:00 - 19:59:59	175967	5.1%	48.88	28.9 GB	4.4%	8418.31
Mar 8 20:00:00 - 20:59:59	138026	4.0%	38.34	22.0 GB	3.4%	6398.92
Mar 8 21:00:00 - 21:59:59	142347	4.1%	39.54	25.7 GB	3.9%	7471.20
Mar 8 22:00:00 - 22:59:59	133306	3.9%	37.03	25.8 GB	3.9%	7507.92
Mar 8 23:00:00 - 23:59:59	168651	4.9%	46.85	32.3 GB	4.9%	9419.47
Mar 9 00:00:00 - 00:59:59	110353	3.2%	30.65	19.3 GB	3.0%	5634.72
Mar 9 01:00:00 - 01:59:59	137146	4.0%	38.10	28.0 GB	4.3%	8148.71
Mar 9 02:00:00 - 02:59:59	128328	3.7%	35.65	25.3 GB	3.9%	7381.01
Mar 9 03:00:00 - 03:59:59	122580	3.6%	34.05	22.9 GB	3.5%	6667.43
Mar 9 04:00:00 - 04:15:03	29519	0.9%	32.69	5.8 GB	0.9%	6781.37
TOTAL: 24:00:01	3435296	100.0%	39.76	653.7 GB	100.0%	7933.36

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	11429	210	9789	1	1429
2	news.nask.pl	2670	22	400	4	2244
3	news.icm.edu.pl	10	0	3	0	7
TOTAL: 3		14109	232	10192	5	3680

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	145
it.tlc.cellulari.android	34
it.comp.os.win.windows7	8
it.comp.os.win.windows10	8
nl.comp.os.linux.techniek	7
comp.sys.raspberry-pi	7
de.rec.sf.startrek	6
de.comp.lang.perl	6
comp.mobile.ipad	5
uk.rec.cycling.moderated	2
it.comp.os.linux.ubuntu	2
de.comp.lang.vbclassic	1
de.comp.office-pakete.ms-office.outlook	1
TOTAL: 13	232

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

Groups	Count
pl.sci.matematyka	3
bit.listserv.catholic	1
pl.praca.oferowana	1
TOTAL: 3	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	1819
Binary in non-binary group	1105
Scoring filter (9)	27
Poison newsgroup	23
EMP (phl)	16
Scoring filter (8)	9
Scoring filter (10)	5
HTML post	2
Scoring filter (11)	2
Scoring filter (13)	1
EMP (fsl)	1
TOTAL: 11	3010

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	412
	news.nask.pl	258
	TOTAL: 2	670
TOTAL: 1		670

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	26542	1118	11704	266	98	32290	4%	23:59:59
2	poznan	19194	556	10418	33	0	1	2%	23:59:57
3	icm	26993	56	19222	3	1	1	0%	23:59:56
4	nask	2236	3	1575	0	0	0	0%	23:48:46
5	okapi	0	0	0	0	0	1225	0%	23:57:51
TOTAL: 5		74965	1733	42919	302	99	33517	2%	119:46:29

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	8.4 MB	1.0 MB	9.4 MB	0.1 KB/s	6.9 KB	23:59:59
2	icm	7.2 MB	6.0 KB	7.2 MB	0.1 KB/s	124.4 KB	23:59:56
3	poznan	2.4 MB	212.1 KB	2.6 MB	0.0 KB/s	4.6 KB	23:59:57
4	nask	7.8 KB	0.0 KB	7.8 KB	0.0 KB/s	2.6 KB	23:48:46
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
TOTAL: 5		17.9 MB	1.2 MB	19.2 MB	0.0 KB/s	9.6 KB	119:46:29

Backlog files shrunk by innfeed:

Server	Size
panorama	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	58	52.5 KB	27	0	00:00:25
2	irl97.internetdsl.tpnet.pl	2	1	1.5 KB	1	0	00:34:05
3	isvcs01.man.lodz.pl	5	0	0.0 KB	0	5	00:00:02
TOTAL: 3		295	59	54.1 KB	28	5	00:34:37

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	677	58	52.5 KB	27	5	00:00:32
2	*.internetdsl.tpnet.pl	2	1	1.5 KB	1	0	00:34:05
TOTAL: 2		679	59	54.1 KB	28	5	00:34:37

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
irl97.internetdsl.tpnet.pl	0.032	0.032	00:34:05
web.man.lodz.pl	1.788	6.440	00:00:25
mon.man.lodz.pl	1.520	6.492	00:00:03
isvcs01.man.lodz.pl	0.044	0.120	00:00:02
david.man.lodz.pl	0.448	2.116	00:00:01
TOTAL: 5	3.832	15.200	00:34:37

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
TOTAL: 2	384

Newsgroup request counts (by category):

Category		Count	Pct
1	pl	58	98.3%
2	lodz	1	1.7%
TOTAL: 2		59	100.0%

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

Newsgroup		Count
1	pl.comp.os.ms-windows.winnt	48
2	pl.comp.pecet	10
3	lodz.ogloszenia	1
TOTAL: 3		59

Daily Usenet report

Mar 8 04:15:02 -- Mar 9 04:15:03