Daily Usenet report: Dec 21 04:15:02

Unknown entries from news log file:

First 14 / 14 lines (100.0%)

Dec 21 04:22:26 newsfeed overchan[30701]: input line too long, skipping
Dec 21 15:43:15 newsfeed overchan[30701]: input line too long, skipping
Dec 21 16:53:24 newsfeed overchan[30701]: input line too long, skipping
Dec 21 17:31:14 newsfeed overchan[30701]: input line too long, skipping
Dec 21 18:13:04 newsfeed overchan[30701]: input line too long, skipping
Dec 21 18:33:02 newsfeed overchan[30701]: input line too long, skipping
Dec 21 19:10:04 newsfeed overchan[30701]: input line too long, skipping
Dec 21 19:39:21 newsfeed overchan[30701]: input line too long, skipping
Dec 21 19:39:39 newsfeed nnrpd[11056]: SERVER perl filter not defined
Dec 21 19:42:45 newsfeed nnrpd[11651]: SERVER perl filter not defined
Dec 21 19:43:41 newsfeed nnrpd[11769]: SERVER perl filter not defined
Dec 21 20:12:14 newsfeed overchan[30701]: input line too long, skipping
Dec 22 00:08:58 newsfeed overchan[30701]: input line too long, skipping
Dec 22 01:07:45 newsfeed overchan[30701]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	650812	91.3%	71.2 MB	90.4%
innfeed	48175	6.8%	5.7 MB	7.2%
innd	9507	1.3%	1.5 MB	1.9%
nnrpd	4207	0.6%	423.8 KB	0.5%
overchan	12	0.0%	1.9 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	712714	100.0%	78.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	645985	99.5%
Negative hits	2928	0.5%
Positive hits	206	0.0%
Cache misses	23	0.0%
TOTAL: 4	649142	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:01.532	0.0%	77	0.000	19.896	605.000
article cleanup	00:00:08.583	0.0%	652509	0.009	0.013	0.017
article logging	00:00:30.208	0.0%	653030	0.037	0.046	0.074
article parse	00:16:31.852	1.1%	51583677	0.013	0.019	0.025
article write	00:07:16.369	0.5%	652295	0.400	0.669	1.031
artlog/artcncl	00:00:00.002	0.0%	77	0.000	0.026	1.000
data move	00:00:40.230	0.0%	56302502	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.483	0.0%	74	0.000	6.527	435.000
hishave/artcncl	00:00:00.007	0.0%	77	0.000	0.091	1.667
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:09:19.912	0.6%	655704	0.442	0.854	6.562
history sync	00:00:00.025	0.0%	291	0.000	0.086	0.500
history write	00:09:56.283	0.7%	652487	0.520	0.914	9.518
hiswrite/artcncl	00:00:00.006	0.0%	3	0.000	2.000	6.000
idle	21:26:08.885	89.3%	51026224	1.407	1.512	1.736
nntp read	00:30:18.550	2.1%	51260695	0.027	0.035	0.045
overview write	00:00:03.678	0.0%	652295	0.002	0.006	0.009
perl filter	01:03:47.349	4.4%	652487	3.662	5.866	7.157
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:33.666	0.0%	1319599	0.020	0.026	0.039
TOTAL: 24:00:03.390	23:45:17.620	99.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.129	0.0%	52430	0.000	0.002	0.011
article prepare	00:00:00.001	0.0%	6292	0.000	0.000	0.008
article read	00:00:15.915	0.0%	1568	0.000	10.150	77.714
backlog stats	00:00:00.104	0.0%	124778	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.043	0.0%	60890	0.003	0.017	0.039
data write	00:00:02.573	0.0%	46420	0.016	0.055	0.101
idle	23:52:22.818	99.9%	124778	466.154	688.766	1149.287
status file	00:01:07.521	0.1%	16925	0.821	3.989	239.507
TOTAL: 23:53:56.045	23:53:50.104	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:30:55.107	99.4%	3069	0.000	604.466	900054.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.265	0.0%	6786	0.000	0.039	1.316
readart	00:00:00.001	0.0%	40	0.000	0.025	0.167
TOTAL: 00:31:06.374	00:30:55.373	99.4%	-	-	-	-

Control commands to INND:

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

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	270	650362	650173	1	188	99%	99:23:08
2	news.icm.edu.pl	47	197	28	134	35	14%	13:48:47
3	localhost	1444	3	3	0	0	100%	00:00:00
TOTAL: 3		1761	650562	650204	135	223	99%	113:11:55

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	291.9 GB	1.2 MB	1.6 MB	291.9 GB	99%	470.6 KB
2	news.icm.edu.pl	1.3 MB	19.9 KB	1.4 MB	2.7 MB	47%	43.1 KB
3	localhost	4.6 KB	0.0 KB	0.0 KB	4.6 KB	100%	1.5 KB
TOTAL: 3		291.9 GB	1.2 MB	3.0 MB	291.9 GB	99%	470.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 21 04:15:02 - 04:59:59	31779	4.9%	11.78	9.1 GB	3.1%	3547.84
Dec 21 05:00:00 - 05:59:59	39176	6.0%	10.88	10.5 GB	3.6%	3068.56
Dec 21 06:00:00 - 06:59:59	36702	5.6%	10.20	12.0 GB	4.1%	3495.16
Dec 21 07:00:00 - 07:59:59	31333	4.8%	8.70	11.6 GB	4.0%	3385.51
Dec 21 08:00:00 - 08:59:59	23958	3.7%	6.66	10.0 GB	3.4%	2920.52
Dec 21 09:00:00 - 09:59:59	23844	3.7%	6.62	12.1 GB	4.1%	3530.17
Dec 21 10:00:00 - 10:59:59	27626	4.3%	7.67	13.0 GB	4.4%	3788.35
Dec 21 11:00:00 - 11:59:59	27628	4.3%	7.67	13.6 GB	4.6%	3960.44
Dec 21 12:00:00 - 12:59:59	27277	4.2%	7.58	12.5 GB	4.3%	3640.62
Dec 21 13:00:00 - 13:59:59	29268	4.5%	8.13	13.9 GB	4.8%	4055.32
Dec 21 14:00:00 - 14:59:59	26693	4.1%	7.41	13.2 GB	4.5%	3856.31
Dec 21 15:00:00 - 15:59:59	23150	3.6%	6.43	12.3 GB	4.2%	3568.38
Dec 21 16:00:00 - 16:59:59	25073	3.9%	6.96	12.9 GB	4.4%	3749.95
Dec 21 17:00:00 - 17:59:59	25828	4.0%	7.17	13.0 GB	4.5%	3797.07
Dec 21 18:00:00 - 18:59:59	25076	3.9%	6.97	12.5 GB	4.3%	3639.96
Dec 21 19:00:00 - 19:59:59	26932	4.1%	7.48	12.0 GB	4.1%	3498.75
Dec 21 20:00:00 - 20:59:59	26135	4.0%	7.26	12.4 GB	4.2%	3599.95
Dec 21 21:00:00 - 21:59:59	25890	4.0%	7.19	12.5 GB	4.3%	3633.37
Dec 21 22:00:00 - 22:59:59	24339	3.7%	6.76	12.6 GB	4.3%	3655.52
Dec 21 23:00:00 - 23:59:59	24986	3.8%	6.94	12.2 GB	4.2%	3563.00
Dec 22 00:00:00 - 00:59:59	26927	4.1%	7.48	13.2 GB	4.5%	3839.06
Dec 22 01:00:00 - 01:59:59	23468	3.6%	6.52	11.7 GB	4.0%	3401.46
Dec 22 02:00:00 - 02:59:59	23430	3.6%	6.51	11.6 GB	3.9%	3364.32
Dec 22 03:00:00 - 03:59:59	18311	2.8%	5.09	9.5 GB	3.3%	2777.91
Dec 22 04:00:00 - 04:15:02	5172	0.8%	5.73	2.7 GB	0.9%	3099.07
TOTAL: 24:00:00	650001	100.0%	7.52	292.6 GB	100.0%	3551.04

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	709	150	21	16	522
2	news.icm.edu.pl	25	0	1	0	24
TOTAL: 2		734	150	22	16	546

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	72
it.tlc.cellulari.android	31
it.comp.os.win.windows10	28
comp.sys.raspberry-pi	4
comp.mobile.android	4
nl.comp.os.linux.techniek	4
it.discussioni.energia	3
de.rec.sf.startrek	1
it.comp.os.win.windows7	1
it.comp.os.linux.ubuntu	1
de.comm.software.mailreader	1
TOTAL: 11	150

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

Groups	Count
pl.praca.oferowana	12
pl.sci.matematyka	4
TOTAL: 2	16

INND Perl filter [Top 20]:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	40227	535	11472	177	2	40492	1%	23:59:58
2	icm	23049	161	13744	207	0	0	0%	23:59:57
3	poznan	25	1	19	0	0	0	4%	02:30:46
4	okapi	0	0	0	0	0	1167	0%	23:50:02
5	nask	0	0	0	0	0	10074	0%	23:57:43
TOTAL: 5		63301	697	25235	384	2	51733	1%	98:18:26

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	1.4 MB	811.5 KB	2.2 MB	0.0 KB/s	3.1 KB	23:59:58
2	icm	422.0 KB	64.1 MB	64.5 MB	0.8 KB/s	179.5 KB	23:59:57
3	poznan	8.1 KB	0.0 KB	8.1 KB	0.0 KB/s	8.1 KB	02:30:46
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:02
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:43
TOTAL: 5		1.8 MB	64.9 MB	66.7 MB	0.2 KB/s	63.2 KB	98:18:26

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	web.man.lodz.pl	288	40	40.5 KB	16	0	00:00:23
2	isvcs01.man.lodz.pl	3	0	0.0 KB	0	3	00:00:00
TOTAL: 2		291	40	40.5 KB	16	3	00:31:06

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.man.lodz.pl	579	40	40.5 KB	16	3	00:00:27
2	unresolved	100	0	0.0 KB	0	0	00:00:04
3	*.dynamic.chello.pl	3	0	0.0 KB	0	0	00:30:34
TOTAL: 3		682	40	40.5 KB	16	3	00:31:06

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
89-64-81-234.dynamic.chello.pl	0.009	0.009	00:30:00
89-64-149-5.dynamic.chello.pl	0.000	0.030	00:00:34
web.man.lodz.pl	1.689	3.163	00:00:23
mon.man.lodz.pl	1.370	2.546	00:00:03
80.111.142.213	0.016	0.041	00:00:03
212.51.192.185	0.443	0.916	00:00:01
isvcs01.man.lodz.pl	0.027	0.030	00:00:00
TOTAL: 7	3.554	6.735	00:31:06

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
89-64-149-5.dynamic.chello.pl	2
89-64-81-234.dynamic.chello.pl	1
TOTAL: 5	391

NNRP client timeouts [Top 20]:

System	Conn	Peer
89-64-81-234.dynamic.chello.pl	1	0
TOTAL: 1	1	0

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 21 04:15:02 -- Dec 22 04:15:02