Daily Usenet report: Aug 24 04:15:02

Unknown entries from news log file:

First 9 / 9 lines (100.0%)

Aug 24 13:30:22 newsfeed overchan[12152]: input line too long, skipping
Aug 24 13:59:47 newsfeed overchan[12152]: input line too long, skipping
Aug 24 15:32:52 newsfeed overchan[12152]: input line too long, skipping
Aug 24 19:47:03 newsfeed overchan[12152]: input line too long, skipping
Aug 24 21:12:41 newsfeed overchan[12152]: input line too long, skipping
Aug 24 21:27:46 newsfeed nnrpd[15439]: SERVER perl filter not defined
Aug 24 23:05:33 newsfeed overchan[12152]: input line too long, skipping
Aug 25 00:35:07 newsfeed overchan[12152]: input line too long, skipping
Aug 25 00:44:26 newsfeed nnrpd[22182]: SERVER perl filter not defined

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1383124	97.0%	153.2 MB	96.6%
innfeed	28194	2.0%	3.3 MB	2.1%
innd	9284	0.7%	1.6 MB	1.0%
nnrpd	4890	0.3%	523.4 KB	0.3%
overchan	9	0.0%	1.5 KB	0.0%
controlchan	1	0.0%	0.0 KB	0.0%
TOTAL: 6	1425502	100.0%	158.6 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1372400	99.3%
Negative hits	9497	0.7%
Positive hits	206	0.0%
Cache misses	29	0.0%
TOTAL: 4	1382132	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:00.871	0.0%	74	0.000	11.770	94.000
article cleanup	00:00:16.132	0.0%	1381352	0.009	0.012	0.016
article logging	00:00:56.582	0.1%	1381799	0.030	0.041	0.053
article parse	00:35:18.835	2.5%	73512702	0.018	0.029	0.036
article write	00:14:46.893	1.0%	1381040	0.214	0.642	1.665
artlog/artcncl	00:00:00.000	0.0%	74	0.000	0.000	0.000
data move	00:01:14.127	0.1%	83114074	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.239	0.0%	73	0.000	3.274	18.000
hishave/artcncl	00:00:00.085	0.0%	74	0.000	1.149	7.167
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:54.187	2.4%	1391557	0.090	1.462	10.155
history sync	00:00:00.012	0.0%	291	0.000	0.041	0.500
history write	00:54:55.710	3.8%	1381332	0.389	2.386	7.301
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	16:43:44.840	69.7%	58884692	0.519	1.023	2.857
nntp read	00:53:55.278	3.7%	72394332	0.035	0.045	0.055
overview write	00:00:06.574	0.0%	1381040	0.003	0.005	0.009
perl filter	03:43:51.152	15.5%	1381332	4.412	9.723	18.888
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:01:09.975	0.1%	2775328	0.019	0.025	0.037
TOTAL: 24:00:01.900	23:44:11.492	98.9%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.096	0.0%	38845	0.000	0.002	0.042
article prepare	00:00:00.001	0.0%	2400	0.000	0.000	0.050
article read	00:00:00.348	0.0%	600	0.000	0.580	143.000
backlog stats	00:00:00.084	0.0%	105774	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:00.965	0.0%	52129	0.000	0.019	0.038
data write	00:00:02.627	0.0%	38871	0.027	0.068	0.137
idle	23:55:25.549	100.0%	105774	341.081	814.241	2384.887
status file	00:00:13.167	0.0%	9360	0.000	1.407	14.583
TOTAL: 23:55:47.581	23:55:42.837	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:22.170	56.4%	3054	0.000	7.259	815.333
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.246	0.6%	6762	0.000	0.036	0.737
readart	00:00:00.000	0.0%	30	0.000	0.000	0.000
TOTAL: 00:00:39.332	00:00:22.416	57.0%	-	-	-	-

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	367	1382264	1382047	0	217	99%	156:13:34
2	news.icm.edu.pl	49	236	18	141	77	7%	14:44:08
3	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 3		419	1382502	1382067	141	294	99%	170:57:42

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	640.1 GB	1.5 MB	2.0 MB	640.1 GB	99%	485.5 KB
2	news.icm.edu.pl	838.0 KB	78.6 KB	2.4 MB	3.3 MB	24%	35.5 KB
3	localhost	2.6 KB	0.0 KB	0.0 KB	2.6 KB	100%	1.3 KB
TOTAL: 3		640.1 GB	1.6 MB	4.4 MB	640.1 GB	99%	485.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Aug 24 04:15:02 - 04:59:59	41255	3.0%	15.29	12.6 GB	2.0%	4896.04
Aug 24 05:00:00 - 05:59:59	50852	3.7%	14.13	20.2 GB	3.2%	5883.18
Aug 24 06:00:00 - 06:59:59	67904	4.9%	18.86	31.9 GB	5.0%	9300.80
Aug 24 07:00:00 - 07:59:59	74239	5.4%	20.62	28.3 GB	4.4%	8245.18
Aug 24 08:00:00 - 08:59:59	65532	4.7%	18.20	38.6 GB	6.0%	11249.47
Aug 24 09:00:00 - 09:59:59	69958	5.1%	19.43	35.3 GB	5.5%	10267.49
Aug 24 10:00:00 - 10:59:59	59105	4.3%	16.42	31.3 GB	4.9%	9127.69
Aug 24 11:00:00 - 11:59:59	44962	3.3%	12.49	25.1 GB	3.9%	7313.57
Aug 24 12:00:00 - 12:59:59	48654	3.5%	13.52	28.3 GB	4.4%	8235.23
Aug 24 13:00:00 - 13:59:59	49385	3.6%	13.72	28.0 GB	4.4%	8160.01
Aug 24 14:00:00 - 14:59:59	58444	4.2%	16.23	27.4 GB	4.3%	7966.43
Aug 24 15:00:00 - 15:59:59	72200	5.2%	20.06	28.0 GB	4.4%	8167.86
Aug 24 16:00:00 - 16:59:59	48558	3.5%	13.49	30.1 GB	4.7%	8755.80
Aug 24 17:00:00 - 17:59:59	51833	3.7%	14.40	29.4 GB	4.6%	8550.44
Aug 24 18:00:00 - 18:59:59	57961	4.2%	16.10	29.4 GB	4.6%	8562.58
Aug 24 19:00:00 - 19:59:59	75532	5.5%	20.98	30.1 GB	4.7%	8763.86
Aug 24 20:00:00 - 20:59:59	75524	5.5%	20.98	30.7 GB	4.8%	8929.24
Aug 24 21:00:00 - 21:59:59	68295	4.9%	18.97	30.7 GB	4.8%	8941.09
Aug 24 22:00:00 - 22:59:59	59623	4.3%	16.56	24.7 GB	3.9%	7208.37
Aug 24 23:00:00 - 23:59:59	41552	3.0%	11.54	21.4 GB	3.3%	6225.83
Aug 25 00:00:00 - 00:59:59	49801	3.6%	13.83	24.0 GB	3.7%	6990.36
Aug 25 01:00:00 - 01:59:59	27827	2.0%	7.73	15.7 GB	2.5%	4574.23
Aug 25 02:00:00 - 02:59:59	23912	1.7%	6.64	13.1 GB	2.0%	3824.20
Aug 25 03:00:00 - 03:59:59	75748	5.5%	21.04	19.0 GB	3.0%	5531.79
Aug 25 04:00:00 - 04:15:02	23635	1.7%	26.20	7.5 GB	1.2%	8714.52
TOTAL: 24:00:00	1382291	100.0%	16.00	640.7 GB	100.0%	7776.15

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	664	198	18	1	447
2	news.icm.edu.pl	95	0	2	0	93
TOTAL: 2		759	198	20	1	540

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	165
it.tlc.cellulari.android	21
comp.sys.raspberry-pi	6
comp.mobile.android	3
it.comp.os.win.windows7	1
it.comp.os.win.windows8	1
it.comp.os.linux.ubuntu	1
TOTAL: 7	198

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

Groups	Count
pl.praca.oferowana	1
TOTAL: 1	1

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	84
Binary in non-binary group	8
Scoring filter (8)	1
TOTAL: 3	93

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	newsfeed.pionier.net.pl	447
	TOTAL: 1	447
TOTAL: 1		447

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	icm	22599	131	12577	434	0	0%	23:59:58
2	panorama	28269	2	12444	15	26520	0%	23:59:58
3	okapi	0	0	0	0	1049	0%	23:57:51
4	nask	0	0	0	0	8735	0%	23:57:51
5	poznan	15	0	11	0	0	0%	01:41:45
TOTAL: 5		50883	133	25032	449	36304	0%	97:37:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	371.3 KB	21.0 MB	21.4 MB	0.3 KB/s	38.8 KB	23:59:58
2	panorama	111.1 KB	56.0 KB	167.1 KB	0.0 KB/s	9.8 KB	23:59:58
3	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
4	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:51
5	poznan	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	01:41:45
TOTAL: 5		482.4 KB	21.1 MB	21.6 MB	0.1 KB/s	37.9 KB	97:37:23

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	30	34.1 KB	13	0	00:00:18
2	isvcs01.man.lodz.pl	2	0	0.0 KB	0	2	00:00:00
TOTAL: 2		290	30	34.1 KB	13	2	00:00:38

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	578	30	34.1 KB	13	2	00:00:24
2	*.security.ipip.net	1	0	0.0 KB	0	0	00:00:00
3	*.toya.net.pl	2	0	0.0 KB	0	0	00:00:04
4	unresolved	101	0	0.0 KB	0	0	00:00:09
TOTAL: 4		682	30	34.1 KB	13	2	00:00:38

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	1.516	3.340	00:00:18
122.228.19.79	0.000	0.008	00:00:05
mon.man.lodz.pl	1.240	2.248	00:00:05
staticline-31-183-131-140.toya.net.pl	0.008	0.028	00:00:04
80.111.142.213	0.016	0.028	00:00:02
212.51.192.185	0.364	0.924	00:00:00
scan-141.security.ipip.net	0.004	0.004	00:00:00
isvcs01.man.lodz.pl	0.020	0.020	00:00:00
TOTAL: 8	3.168	6.600	00:00:38

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
staticline-31-183-131-140.toya.net.pl	2
122.228.19.79	1
scan-141.security.ipip.net	1
TOTAL: 6	392

NNRP unrecognized commands (by host):

System	Conn
122.228.19.79	5
scan-141.security.ipip.net	4
TOTAL: 2	9

NNRP unrecognized commands (by command):

Command	Count
GET / HTTP/1.1	2
Host: 212.191.2.21:119	2
Connection: close	1
User-Agent: Mozilla/5.0 (Macintosh; Inte...	1
User-Agent: HTTP Banner Detection (https...	1
Connection: keep-alive	1
Accept: /	1
TOTAL: 7	9

NNRP client timeouts [Top 20]:

System	Conn	Peer
122.228.19.79	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Aug 24 04:15:02 -- Aug 25 04:15:02