Daily Usenet report: Dec 30 04:15:03

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Dec 30 09:53:22 newsfeed overchan[32611]: input line too long, skipping
Dec 30 09:53:46 newsfeed overchan[32611]: input line too long, skipping
Dec 30 09:58:22 newsfeed overchan[32611]: input line too long, skipping
Dec 30 14:38:30 newsfeed overchan[32611]: input line too long, skipping
Dec 30 14:42:20 newsfeed overchan[32611]: input line too long, skipping
Dec 30 18:32:16 newsfeed overchan[32611]: input line too long, skipping
Dec 30 19:20:34 newsfeed overchan[32611]: input line too long, skipping
Dec 30 19:35:29 newsfeed overchan[32611]: input line too long, skipping
Dec 30 19:38:06 newsfeed overchan[32611]: input line too long, skipping
Dec 31 03:24:19 newsfeed overchan[32611]: input line too long, skipping
Dec 31 03:26:54 newsfeed overchan[32611]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3668480	97.9%	415.9 MB	97.6%
innfeed	42945	1.1%	5.1 MB	1.2%
innd	30468	0.8%	4.8 MB	1.1%
nnrpd	4748	0.1%	496.5 KB	0.1%
overchan	12	0.0%	2.0 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3746654	100.0%	426.2 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3634599	96.6%
Negative hits	110996	3.0%
Positive hits	15039	0.4%
Cache misses	126	0.0%
TOTAL: 4	3760760	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:05.059	0.0%	199	0.000	25.422	369.000
article cleanup	00:00:43.417	0.1%	3669206	0.010	0.012	0.015
article logging	00:02:13.263	0.2%	3670203	0.027	0.036	0.089
article parse	00:49:58.042	3.5%	75778595	0.030	0.040	0.055
article write	00:19:51.350	1.4%	3653748	0.176	0.326	0.538
artlog/artcncl	00:00:00.005	0.0%	195	0.000	0.026	1.000
artlog/artparse	00:00:00.000	0.0%	1	0.000	0.000	0.000
data move	00:02:24.883	0.2%	91662441	0.001	0.002	0.004
hisgrep/artcncl	00:00:01.840	0.0%	193	0.000	9.534	93.400
hishave/artcncl	00:00:00.280	0.0%	199	0.000	1.407	12.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:59:48.790	8.3%	3791072	0.321	1.896	9.367
history sync	00:00:00.017	0.0%	290	0.000	0.059	0.500
history write	05:38:49.499	23.5%	3663879	0.509	5.549	15.007
hiswrite/artcncl	00:00:00.000	0.0%	6	0.000	0.000	0.000
idle	07:57:44.367	33.2%	43553017	0.154	0.658	1.647
nntp read	00:42:48.782	3.0%	72807244	0.029	0.035	0.042
overview write	00:00:18.916	0.0%	3653748	0.004	0.005	0.007
perl filter	05:56:01.375	24.7%	3663827	4.684	5.830	7.685
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:31.380	0.4%	7327217	0.037	0.045	0.060
TOTAL: 24:00:06.259	23:36:21.265	98.4%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.166	0.0%	57587	0.000	0.003	0.012
article prepare	00:00:00.004	0.0%	12728	0.000	0.000	0.008
article read	00:01:16.531	0.1%	3154	0.000	24.265	281.200
backlog stats	00:00:00.144	0.0%	163110	0.000	0.001	0.004
callbacks	00:00:00.000	0.0%	5	0.000	0.000	0.000
data read	00:00:01.342	0.0%	87832	0.002	0.015	0.035
data write	00:00:04.017	0.0%	68915	0.020	0.058	0.144
idle	23:51:55.970	99.9%	163110	304.868	526.736	1080.728
status file	00:00:13.540	0.0%	15921	0.460	0.850	4.011
TOTAL: 23:53:48.339	23:53:31.714	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	04:53:46.962	99.8%	148863	0.000	118.411	10048.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:09.112	0.1%	298350	0.000	0.031	3.333
readart	00:00:00.002	0.0%	24	0.000	0.083	0.500
TOTAL: 04:54:16.210	04:53:56.076	99.9%	-	-	-	-

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	359	3657031	3640676	7510	8845	99%	519:49:05
2	news.nask.pl	3	19746	11092	1978	6676	56%	24:11:38
3	news.icm.edu.pl	45	266	13	239	14	4%	18:08:07
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		408	3677043	3651781	9727	15535	99%	562:08:50

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	745.8 GB	389.4 MB	961.0 MB	747.1 GB	99%	214.7 KB
2	news.nask.pl	59.2 MB	9.7 MB	410.9 MB	479.8 MB	12%	27.7 KB
3	news.icm.edu.pl	296.6 KB	112.5 KB	635.4 KB	1.0 MB	28%	38.7 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		745.9 GB	399.2 MB	1.3 GB	747.6 GB	99%	213.8 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 30 04:15:03 - 04:59:59	86511	2.4%	32.08	18.8 GB	2.5%	7324.14
Dec 30 05:00:00 - 05:59:59	140434	3.8%	39.01	31.4 GB	4.2%	9154.53
Dec 30 06:00:00 - 06:59:59	139482	3.8%	38.74	29.1 GB	3.9%	8464.62
Dec 30 07:00:00 - 07:59:59	101559	2.8%	28.21	24.0 GB	3.2%	6994.46
Dec 30 08:00:00 - 08:59:59	121062	3.3%	33.63	29.3 GB	3.9%	8543.99
Dec 30 09:00:00 - 09:59:59	127904	3.5%	35.53	32.5 GB	4.4%	9477.77
Dec 30 10:00:00 - 10:59:59	140859	3.9%	39.13	30.7 GB	4.1%	8953.26
Dec 30 11:00:00 - 11:59:59	145665	4.0%	40.46	31.1 GB	4.2%	9056.90
Dec 30 12:00:00 - 12:59:59	165539	4.5%	45.98	37.4 GB	5.0%	10899.09
Dec 30 13:00:00 - 13:59:59	153362	4.2%	42.60	37.7 GB	5.0%	10969.19
Dec 30 14:00:00 - 14:59:59	192417	5.3%	53.45	26.8 GB	3.6%	7795.45
Dec 30 15:00:00 - 15:59:59	212575	5.8%	59.05	24.9 GB	3.3%	7240.50
Dec 30 16:00:00 - 16:59:59	150023	4.1%	41.67	32.5 GB	4.4%	9476.27
Dec 30 17:00:00 - 17:59:59	148150	4.1%	41.15	28.1 GB	3.8%	8192.73
Dec 30 18:00:00 - 18:59:59	160955	4.4%	44.71	33.0 GB	4.4%	9611.22
Dec 30 19:00:00 - 19:59:59	156260	4.3%	43.41	35.2 GB	4.7%	10255.87
Dec 30 20:00:00 - 20:59:59	147580	4.0%	40.99	29.3 GB	3.9%	8538.47
Dec 30 21:00:00 - 21:59:59	181539	5.0%	50.43	35.3 GB	4.7%	10267.96
Dec 30 22:00:00 - 22:59:59	182079	5.0%	50.58	35.6 GB	4.8%	10382.47
Dec 30 23:00:00 - 23:59:59	174052	4.8%	48.35	36.8 GB	4.9%	10728.94
Dec 31 00:00:00 - 00:59:59	170108	4.7%	47.25	32.6 GB	4.4%	9491.76
Dec 31 01:00:00 - 01:59:59	158800	4.3%	44.11	32.6 GB	4.4%	9494.53
Dec 31 02:00:00 - 02:59:59	152934	4.2%	42.48	31.5 GB	4.2%	9181.42
Dec 31 03:00:00 - 03:59:59	110717	3.0%	30.75	22.8 GB	3.1%	6640.84
Dec 31 04:00:00 - 04:15:02	31185	0.9%	34.57	7.2 GB	1.0%	8338.37
TOTAL: 23:59:59	3651751	100.0%	42.27	746.3 GB	100.0%	9057.96

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	9328	117	5031	3	4177
2	news.nask.pl	7191	16	324	6	6845
3	news.icm.edu.pl	15	0	2	0	13
TOTAL: 3		16534	133	5357	9	11035

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	83
it.tlc.cellulari.android	13
uk.rec.cycling.moderated	8
comp.sys.raspberry-pi	7
comp.mobile.android	6
it.comp.os.linux.ubuntu	4
it.discussioni.energia	4
it.comp.os.win.windows7	3
it.comp.os.win.windows10	2
comp.mobile.ipad	2
de.rec.kunst	1
TOTAL: 11	133

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

Groups	Count
pl.praca.oferowana	8
bit.listserv.catholic	1
TOTAL: 2	9

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	6373
EMP (md5)	3481
Scoring filter (9)	29
Scoring filter (8)	28
EMP (phl)	20
Scoring filter (11)	18
Scoring filter (16)	14
Scoring filter (10)	5
Scoring filter (12)	3
Scoring filter (18)	2
HTML post	2
Scoring filter (13)	2
Scoring filter (15)	1
Scoring filter (14)	1
Too many newsgroups	1
TOTAL: 15	9980

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	529
	newsfeed.pionier.net.pl	473
	news.icm.edu.pl	1
	TOTAL: 3	1003
TOTAL: 1		1003

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	44404	1136	16451	266	16	39864	2%	23:59:57
2	poznan	17226	584	10338	82	0	0	3%	23:59:57
3	icm	23013	84	19018	158	0	0	0%	23:59:57
4	nask	2644	4	2064	2	0	0	0%	23:55:55
5	okapi	0	0	0	0	0	1501	0%	23:57:50
TOTAL: 5		87287	1808	47871	508	16	41365	2%	119:53:36

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	19.9 MB	43.6 MB	63.5 MB	0.8 KB/s	268.5 KB	23:59:57
2	panorama	12.3 MB	1.8 MB	14.0 MB	0.2 KB/s	10.3 KB	23:59:57
3	poznan	2.1 MB	3.4 MB	5.5 MB	0.1 KB/s	8.4 KB	23:59:57
4	nask	9.4 KB	2.9 KB	12.2 KB	0.0 KB/s	2.0 KB	23:55:55
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		34.2 MB	48.8 MB	83.0 MB	0.2 KB/s	36.7 KB	119:53:36

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	24	24.8 KB	10	0	0	00:00:23
TOTAL: 1		288	24	24.8 KB	10	0	0	04:54:15

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	24	24.8 KB	10	00:00:27
2	unresolved	5	0	0.0 KB	0	04:53:48
TOTAL: 2		677	24	24.8 KB	10	04:54:15

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.177	8.324	14.260	04:53:48
web.man.lodz.pl	1.404	8.040	00:00:23
mon.man.lodz.pl	1.028	6.936	00:00:03
david.man.lodz.pl	0.372	2.300	00:00:00
TOTAL: 4	11.128	31.536	04:54:15

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.192.177	5
TOTAL: 3	389

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 30 04:15:03 -- Dec 31 04:15:02