Daily Usenet report: Dec 31 04:15:02

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Dec 31 11:22:32 newsfeed overchan[20867]: input line too long, skipping
Jan 1 00:25:52 newsfeed overchan[20867]: input line too long, skipping
Jan 1 03:07:56 newsfeed overchan[20867]: input line too long, skipping
Jan 1 03:10:34 newsfeed overchan[20867]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3454866	97.7%	392.9 MB	97.3%
innfeed	44644	1.3%	5.3 MB	1.3%
innd	32472	0.9%	5.1 MB	1.3%
nnrpd	4753	0.1%	494.1 KB	0.1%
overchan	5	0.0%	0.8 KB	0.0%
controlchan	1	0.0%	0.1 KB	0.0%
TOTAL: 6	3536741	100.0%	403.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3404821	96.2%
Negative hits	115884	3.3%
Positive hits	17733	0.5%
Cache misses	131	0.0%
TOTAL: 4	3538569	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.120	0.0%	166	0.000	24.819	265.000
article cleanup	00:00:44.595	0.1%	3444150	0.009	0.013	0.017
article logging	00:02:20.299	0.2%	3445022	0.027	0.041	0.263
article parse	00:50:01.899	3.5%	69673724	0.029	0.043	0.056
article write	00:19:01.205	1.3%	3421768	0.159	0.334	0.510
artlog/artcncl	00:00:00.011	0.0%	166	0.000	0.066	1.000
artlog/artparse	00:00:00.000	0.0%	0	0.000	0.000	0.000
data move	00:02:17.769	0.2%	84529974	0.001	0.002	0.004
hisgrep/artcncl	00:00:01.004	0.0%	165	0.000	6.085	92.000
hishave/artcncl	00:00:01.186	0.0%	166	0.000	7.145	54.944
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:41:26.548	7.0%	3570525	0.255	1.705	8.733
history sync	00:00:00.016	0.0%	290	0.000	0.055	0.500
history write	04:51:56.977	20.3%	3434326	1.288	5.101	12.972
hiswrite/artcncl	00:00:00.000	0.0%	1	0.000	0.000	0.000
idle	08:59:14.522	37.4%	40743511	0.128	0.794	1.758
nntp read	00:44:27.521	3.1%	66937944	0.029	0.040	0.051
overview write	00:00:18.891	0.0%	3421768	0.004	0.006	0.007
perl filter	05:57:20.048	24.8%	3434291	4.569	6.243	8.204
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:27.650	0.4%	6860281	0.033	0.048	0.061
TOTAL: 24:00:08.057	23:34:44.261	98.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.165	0.0%	57591	0.000	0.003	0.032
article prepare	00:00:00.004	0.0%	15624	0.000	0.000	0.019
article read	00:01:47.357	0.1%	3867	0.042	27.762	350.875
backlog stats	00:00:00.153	0.0%	149277	0.000	0.001	0.006
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.208	0.0%	79966	0.000	0.015	0.034
data write	00:00:03.890	0.0%	63631	0.025	0.061	0.130
idle	23:52:53.796	99.8%	149277	140.492	575.935	1145.146
status file	00:00:15.386	0.0%	15494	0.517	0.993	4.683
TOTAL: 23:55:19.671	23:55:01.959	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
hisgrep	00:00:28.351	0.1%	168921	0.168	0.168	0.168
idle	07:01:30.759	99.6%	171945	0.000	147.086	149.627
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:10.491	0.0%	344558	0.000	0.030	1.333
readart	00:00:35.054	0.1%	167781	0.000	0.209	0.500
TOTAL: 07:03:07.888	07:02:44.655	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	578	3441386	3422585	5519	13282	99%	534:39:04
2	news.nask.pl	2	20679	9505	2175	8999	45%	23:33:34
3	news.icm.edu.pl	47	337	26	291	20	7%	17:47:01
4	localhost	1	0	0	0	0	0%	00:00:00
TOTAL: 4		628	3462402	3432116	7985	22301	99%	575:59:39

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	686.5 GB	1.0 GB	1.6 GB	689.1 GB	99%	210.3 KB
2	news.nask.pl	83.2 MB	9.2 MB	1.1 GB	1.2 GB	6%	66.0 KB
3	news.icm.edu.pl	899.3 KB	196.2 KB	880.0 KB	1.9 MB	45%	42.9 KB
4	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 4		686.5 GB	1.0 GB	2.7 GB	690.3 GB	99%	209.5 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 31 04:15:02 - 04:59:59	85462	2.5%	31.68	18.5 GB	2.7%	7173.88
Dec 31 05:00:00 - 05:59:59	129533	3.8%	35.98	27.1 GB	3.9%	7891.66
Dec 31 06:00:00 - 06:59:59	130702	3.8%	36.31	30.7 GB	4.5%	8947.23
Dec 31 07:00:00 - 07:59:59	129559	3.8%	35.99	31.5 GB	4.6%	9181.85
Dec 31 08:00:00 - 08:59:59	121717	3.5%	33.81	28.9 GB	4.2%	8429.77
Dec 31 09:00:00 - 09:59:59	116643	3.4%	32.40	25.2 GB	3.7%	7336.80
Dec 31 10:00:00 - 10:59:59	117934	3.4%	32.76	27.0 GB	3.9%	7852.66
Dec 31 11:00:00 - 11:59:59	88034	2.6%	24.45	17.0 GB	2.5%	4960.04
Dec 31 12:00:00 - 12:59:59	122862	3.6%	34.13	25.6 GB	3.7%	7462.28
Dec 31 13:00:00 - 13:59:59	189132	5.5%	52.54	27.8 GB	4.0%	8094.43
Dec 31 14:00:00 - 14:59:59	224095	6.5%	62.25	23.2 GB	3.4%	6764.72
Dec 31 15:00:00 - 15:59:59	192163	5.6%	53.38	25.5 GB	3.7%	7413.08
Dec 31 16:00:00 - 16:59:59	142516	4.2%	39.59	32.8 GB	4.8%	9547.21
Dec 31 17:00:00 - 17:59:59	140613	4.1%	39.06	32.0 GB	4.7%	9329.60
Dec 31 18:00:00 - 18:59:59	148118	4.3%	41.14	29.9 GB	4.4%	8698.27
Dec 31 19:00:00 - 19:59:59	185806	5.4%	51.61	34.4 GB	5.0%	10009.05
Dec 31 20:00:00 - 20:59:59	160929	4.7%	44.70	30.4 GB	4.4%	8852.09
Dec 31 21:00:00 - 21:59:59	134063	3.9%	37.24	27.5 GB	4.0%	7996.58
Dec 31 22:00:00 - 22:59:59	106579	3.1%	29.61	23.5 GB	3.4%	6854.24
Dec 31 23:00:00 - 23:59:59	128800	3.8%	35.78	30.3 GB	4.4%	8824.71
Jan 1 00:00:00 - 00:59:59	160997	4.7%	44.72	30.7 GB	4.5%	8945.39
Jan 1 01:00:00 - 01:59:59	136752	4.0%	37.99	31.0 GB	4.5%	9020.26
Jan 1 02:00:00 - 02:59:59	153462	4.5%	42.63	34.9 GB	5.1%	10159.12
Jan 1 03:00:00 - 03:59:59	140132	4.1%	38.93	32.9 GB	4.8%	9594.19
Jan 1 04:00:00 - 04:15:02	44919	1.3%	49.80	8.1 GB	1.2%	9372.62
TOTAL: 24:00:00	3431522	100.0%	39.72	686.3 GB	100.0%	8328.75

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	13552	171	9334	0	4047
2	news.nask.pl	9606	22	483	3	9098
3	news.icm.edu.pl	20	0	2	0	18
TOTAL: 3		23178	193	9819	3	13163

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	92
it.tlc.cellulari.android	25
it.comp.os.win.windows10	14
hr.sci.med	12
comp.sys.raspberry-pi	11
it.discussioni.energia	9
comp.mobile.ipad	8
it.comp.os.linux.ubuntu	6
comp.mobile.android	4
uk.rec.cycling.moderated	4
nl.comp.sys.android	4
it.comp.os.win.windows7	2
de.comp.lang.php	2
TOTAL: 13	193

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

Groups	Count
pl.sci.matematyka	2
bit.listserv.catholic	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	10453
EMP (md5)	1404
Scoring filter (9)	178
Scoring filter (10)	130
Scoring filter (11)	66
Scoring filter (8)	18
Scoring filter (16)	6
HTML post	3
Scoring filter (12)	2
Too many newsgroups	2
Scoring filter (18)	1
TOTAL: 11	12263

Miscellaneous innd statistics [Top 10]:

Event	Server	Number
Including strange strings
	news.nask.pl	596
	newsfeed.pionier.net.pl	269
	TOTAL: 2	865
TOTAL: 1		865

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	45127	1678	16790	976	2	38182	3%	23:59:58
2	poznan	15303	253	9235	77	0	0	1%	23:59:13
3	icm	19389	37	16313	12	0	0	0%	23:59:45
4	nask	2174	7	1820	0	0	0	0%	23:57:27
5	okapi	0	0	0	0	0	1025	0%	23:57:50
TOTAL: 5		81993	1975	44158	1065	2	39207	2%	119:54:13

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	30.7 MB	13.7 MB	44.4 MB	0.5 KB/s	17.1 KB	23:59:58
2	icm	7.1 MB	2.3 MB	9.4 MB	0.1 KB/s	196.1 KB	23:59:45
3	poznan	1.3 MB	3.4 MB	4.8 MB	0.1 KB/s	14.8 KB	23:59:13
4	nask	110.9 KB	0.0 KB	110.9 KB	0.0 KB/s	15.8 KB	23:57:27
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:57:50
TOTAL: 5		39.2 MB	19.4 MB	58.6 MB	0.1 KB/s	19.7 KB	119:54:13

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	46	43.2 KB	23	0	0	00:00:21
TOTAL: 1		288	46	43.2 KB	23	0	0	07:03:07

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	672	46	43.2 KB	23	00:00:26
2	unresolved	1	0	0.0 KB	0	07:02:40
TOTAL: 2		673	46	43.2 KB	23	07:03:07

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
185.48.192.177	15.080	56.952	07:02:40
web.man.lodz.pl	1.472	8.588	00:00:21
mon.man.lodz.pl	1.204	8.016	00:00:03
david.man.lodz.pl	0.448	2.360	00:00:00
TOTAL: 4	18.204	75.916	07:03:07

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
185.48.192.177	1
TOTAL: 3	385

NNRP client timeouts [Top 20]:

System	Conn	Peer
185.48.192.177	1	1
TOTAL: 1	1	1

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 31 04:15:02 -- Jan 1 04:15:02