Daily Usenet report: Dec 27 04:15:03

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Dec 27 16:59:12 newsfeed overchan[8024]: input line too long, skipping
Dec 27 19:35:49 newsfeed overchan[8024]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	728339	92.4%	79.4 MB	91.5%
innfeed	46799	5.9%	5.5 MB	6.3%
innd	9263	1.2%	1.4 MB	1.7%
nnrpd	4183	0.5%	423.3 KB	0.5%
overchan	3	0.0%	0.5 KB	0.0%
controlchan	1	0.0%	0.2 KB	0.0%
TOTAL: 6	788588	100.0%	86.7 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	723004	99.5%
Negative hits	3290	0.5%
Positive hits	249	0.0%
Cache misses	21	0.0%
TOTAL: 4	726564	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:03.501	0.0%	75	0.000	46.680	342.000
article cleanup	00:00:13.396	0.0%	727855	0.011	0.018	0.025
article logging	00:00:42.507	0.0%	728165	0.045	0.058	0.109
article parse	00:27:51.641	1.9%	96855139	0.011	0.017	0.026
article write	00:09:41.578	0.7%	727492	0.513	0.799	1.154
artlog/artcncl	00:00:00.003	0.0%	75	0.000	0.040	0.333
data move	00:01:23.802	0.1%	101704128	0.001	0.001	0.001
hisgrep/artcncl	00:00:00.443	0.0%	68	0.000	6.515	204.000
hishave/artcncl	00:00:00.181	0.0%	75	0.000	2.413	11.800
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:33:04.950	6.5%	731619	0.709	7.634	18.284
history sync	00:00:00.032	0.0%	290	0.000	0.110	1.000
history write	01:52:30.071	7.8%	727810	2.525	9.274	19.874
hiswrite/artcncl	00:00:00.016	0.0%	7	0.000	2.286	3.500
idle	16:55:47.526	70.5%	95831189	0.494	0.636	1.843
nntp read	01:08:49.901	4.8%	96430504	0.030	0.043	0.051
overview write	00:00:04.796	0.0%	727492	0.004	0.007	0.010
perl filter	01:20:16.288	5.6%	727810	4.620	6.618	8.823
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:00:46.577	0.1%	1467791	0.025	0.032	0.040
TOTAL: 24:00:01.647	23:31:17.209	98.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.151	0.0%	58206	0.000	0.003	0.014
article prepare	00:00:00.005	0.0%	11544	0.000	0.000	0.031
article read	00:00:31.006	0.0%	2879	0.000	10.770	124.000
backlog stats	00:00:00.096	0.0%	111180	0.000	0.001	0.005
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.188	0.0%	56185	0.008	0.021	0.047
data write	00:00:02.728	0.0%	43568	0.007	0.063	0.270
idle	23:51:47.105	99.8%	111180	44.081	772.685	1443.847
status file	00:02:29.345	0.2%	16269	0.917	9.180	261.297
TOTAL: 23:54:58.977	23:54:51.624	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	00:00:42.412	75.2%	3039	0.000	13.956	10050.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.337	0.6%	6746	0.000	0.050	1.895
readart	00:00:00.002	0.0%	32	0.000	0.062	0.500
TOTAL: 00:00:56.387	00:00:42.751	75.8%	-	-	-	-

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	115	727761	727440	6	315	99%	65:17:07
2	news.icm.edu.pl	44	237	36	153	48	15%	14:09:29
3	localhost	1441	0	0	0	0	0%	00:00:00
TOTAL: 3		1600	727998	727476	159	363	99%	79:26:36

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	288.5 GB	4.5 MB	5.2 MB	288.5 GB	99%	415.7 KB
2	news.icm.edu.pl	2.1 MB	52.0 KB	3.4 MB	5.6 MB	38%	67.9 KB
3	localhost	0.0 KB	0.0 KB	0.0 KB	0.0 KB	0%	0
TOTAL: 3		288.5 GB	4.6 MB	8.6 MB	288.5 GB	99%	415.6 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 27 04:15:03 - 04:59:59	20898	2.9%	7.75	8.0 GB	2.8%	3096.28
Dec 27 05:00:00 - 05:59:59	30453	4.2%	8.46	11.8 GB	4.1%	3450.45
Dec 27 06:00:00 - 06:59:59	21388	2.9%	5.94	10.4 GB	3.6%	3022.22
Dec 27 07:00:00 - 07:59:59	21709	3.0%	6.03	9.8 GB	3.4%	2849.90
Dec 27 08:00:00 - 08:59:59	22663	3.1%	6.30	11.4 GB	3.9%	3307.91
Dec 27 09:00:00 - 09:59:59	25823	3.5%	7.17	13.1 GB	4.5%	3819.97
Dec 27 10:00:00 - 10:59:59	23986	3.3%	6.66	12.4 GB	4.3%	3607.30
Dec 27 11:00:00 - 11:59:59	24587	3.4%	6.83	12.1 GB	4.2%	3522.48
Dec 27 12:00:00 - 12:59:59	24749	3.4%	6.87	11.0 GB	3.8%	3197.33
Dec 27 13:00:00 - 13:59:59	27080	3.7%	7.52	12.1 GB	4.2%	3532.42
Dec 27 14:00:00 - 14:59:59	26804	3.7%	7.45	12.2 GB	4.2%	3547.17
Dec 27 15:00:00 - 15:59:59	27458	3.8%	7.63	12.4 GB	4.3%	3601.50
Dec 27 16:00:00 - 16:59:59	29766	4.1%	8.27	12.8 GB	4.4%	3716.16
Dec 27 17:00:00 - 17:59:59	30353	4.2%	8.43	13.5 GB	4.7%	3941.79
Dec 27 18:00:00 - 18:59:59	30359	4.2%	8.43	13.3 GB	4.6%	3860.77
Dec 27 19:00:00 - 19:59:59	29199	4.0%	8.11	12.6 GB	4.4%	3671.30
Dec 27 20:00:00 - 20:59:59	35596	4.9%	9.89	12.7 GB	4.4%	3705.52
Dec 27 21:00:00 - 21:59:59	38618	5.3%	10.73	12.7 GB	4.4%	3700.49
Dec 27 22:00:00 - 22:59:59	41334	5.7%	11.48	12.9 GB	4.5%	3750.10
Dec 27 23:00:00 - 23:59:59	40562	5.6%	11.27	12.2 GB	4.2%	3552.33
Dec 28 00:00:00 - 00:59:59	37127	5.1%	10.31	11.5 GB	4.0%	3343.43
Dec 28 01:00:00 - 01:59:59	40650	5.6%	11.29	12.1 GB	4.2%	3511.81
Dec 28 02:00:00 - 02:59:59	34147	4.7%	9.49	12.4 GB	4.3%	3608.51
Dec 28 03:00:00 - 03:59:59	33108	4.6%	9.20	11.5 GB	4.0%	3341.49
Dec 28 04:00:00 - 04:15:02	9175	1.3%	10.17	2.6 GB	0.9%	3042.49
TOTAL: 23:59:59	727592	100.0%	8.42	289.2 GB	100.0%	3510.22

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	624	270	40	5	309
2	news.icm.edu.pl	48	0	5	0	43
TOTAL: 2		672	270	45	5	352

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	199
comp.sys.raspberry-pi	42
it.tlc.cellulari.android	11
comp.mobile.android	7
it.comp.os.win.windows10	6
de.rec.sf.startrek	4
it.comp.os.win.windows7	1
TOTAL: 7	270

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

Groups	Count
pl.sci.matematyka	5
TOTAL: 1	5

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	43
TOTAL: 1	43

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	48755	824	19412	1593	4	36529	1%	23:59:32
2	icm	15037	76	11756	1	0	0	0%	23:56:41
3	poznan	20	1	16	0	0	0	5%	02:36:08
4	okapi	0	0	0	0	0	1096	0%	23:50:01
5	nask	0	0	0	0	0	9038	0%	23:50:01
TOTAL: 5		63812	901	31184	1594	4	46663	1%	98:12:23

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	2.3 MB	6.9 MB	9.2 MB	0.1 KB/s	3.9 KB	23:59:32
2	icm	302.7 KB	2.0 KB	304.7 KB	0.0 KB/s	4.0 KB	23:56:41
3	poznan	8.3 KB	0.0 KB	8.3 KB	0.0 KB/s	8.3 KB	02:36:08
4	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
5	nask	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	23:50:01
TOTAL: 5		2.6 MB	6.9 MB	9.5 MB	0.0 KB/s	3.9 KB	98:12:23

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	web.man.lodz.pl	288	32	31.1 KB	15	0	0	00:00:35
TOTAL: 1		288	32	31.1 KB	15	0	0	00:00:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Elapsed
1	*.man.lodz.pl	576	32	31.1 KB	15	00:00:39
2	*.toya.net.pl	2	0	0.0 KB	0	00:00:00
3	unresolved	101	0	0.0 KB	0	00:00:16
TOTAL: 3		679	32	31.1 KB	15	00:00:56

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
web.man.lodz.pl	2.021	4.265	00:00:35
134.122.14.132	0.008	0.019	00:00:10
80.111.142.213	0.009	0.089	00:00:04
mon.man.lodz.pl	1.627	3.541	00:00:04
212.51.192.185	0.482	1.180	00:00:01
staticline40401.toya.net.pl	0.067	0.042	00:00:00
TOTAL: 6	4.214	9.136	00:00:56

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
212.51.192.185	96
80.111.142.213	4
staticline40401.toya.net.pl	2
134.122.14.132	1
TOTAL: 5	391

Newsgroup request counts (by category):

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

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

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

Daily Usenet report

Dec 27 04:15:03 -- Dec 28 04:15:02