Daily Usenet report: Jan 6 04:15:01

Unknown entries from news log file:

First 6 / 6 lines (100.0%)

Jan 6 04:15:01 newsfeed nnrpd[2981]: web.man.lodz.pl rejected Flushing log and syslog files
Jan 6 09:09:35 newsfeed nnrpd[15062]: SERVER perl filter not defined
Jan 6 09:10:17 newsfeed nnrpd[15249]: SERVER perl filter not defined
Jan 6 09:36:26 newsfeed nnrpd[16039]: SERVER perl filter not defined
Jan 6 09:38:08 newsfeed nnrpd[16052]: SERVER perl filter not defined
Jan 7 03:15:03 newsfeed pgpverify[20340]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3855408	98.8%	440.7 MB	98.5%
innd	38260	1.0%	5.6 MB	1.3%
nnrpd	4852	0.1%	503.4 KB	0.1%
innfeed	4850	0.1%	689.3 KB	0.2%
controlchan	3	0.0%	0.3 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 6	3903374	100.0%	447.4 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3847609	96.7%
Negative hits	115068	2.9%
Positive hits	14226	0.4%
Cache misses	114	0.0%
TOTAL: 4	3977017	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:04.345	0.0%	241	0.000	18.029	1358.000
article cleanup	00:00:54.479	0.1%	3855166	0.007	0.014	0.024
article logging	00:02:24.260	0.2%	3855900	0.020	0.037	0.778
article parse	01:35:54.965	6.7%	176350710	0.018	0.033	0.067
article write	00:40:48.325	2.8%	3838896	0.341	0.638	2.439
artlog/artcncl	00:00:00.001	0.0%	215	0.000	0.005	0.143
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:04:25.122	0.3%	197747173	0.001	0.001	0.004
hisgrep/artcncl	00:00:00.227	0.0%	224	0.000	1.013	21.000
hishave/artcncl	00:00:00.045	0.0%	241	0.000	0.187	3.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	00:33:23.994	2.3%	3980367	0.143	0.503	10.059
history sync	00:00:00.012	0.0%	293	0.000	0.041	1.000
history write	00:47:05.633	3.3%	3851065	0.265	0.734	10.851
hiswrite/artcncl	00:00:00.003	0.0%	17	0.000	0.176	1.000
idle	10:51:51.463	45.3%	89408592	0.315	0.437	1.100
nntp read	01:10:46.187	4.9%	173016213	0.017	0.025	0.035
overview write	00:00:24.463	0.0%	3838896	0.002	0.006	0.014
perl filter	07:21:32.618	30.7%	3851063	5.360	6.879	8.735
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:06:48.710	0.5%	7714975	0.034	0.053	0.084
TOTAL: 24:00:16.262	23:16:24.852	97.0%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.201	0.0%	51845	0.000	0.004	0.103
article prepare	00:00:00.022	0.0%	58694	0.000	0.000	0.062
article read	00:00:21.368	0.0%	14755	0.000	1.448	117.778
backlog stats	00:00:00.239	0.0%	224231	0.000	0.001	0.028
callbacks	00:00:00.001	0.0%	54	0.000	0.019	0.111
data read	00:00:01.960	0.0%	119990	0.000	0.016	0.111
data write	00:00:10.528	0.0%	104193	0.000	0.101	0.317
idle	23:59:42.870	100.0%	224231	67.851	385.241	2085.724
status file	00:00:00.684	0.0%	862	0.000	0.794	3.333
TOTAL: 24:00:24.282	24:00:17.873	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	02:22:45.652	99.6%	13416	0.000	638.465	166502.600
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.885	0.0%	28564	0.000	0.031	2.275
readart	00:00:17.465	0.2%	582	0.000	30.009	184.286
TOTAL: 02:23:18.036	02:23:04.002	99.8%	-	-	-	-

Control commands to INND:

Command	Number
L	1
flush	24
flushlogs	2
go	2
logmode	2
mode	148
name	2
pause	2
paused	2
reload	2
reserve	2
TOTAL: 11	189

Control Channel:

Sender	newgroup	rmgroup	Other	Bad PGP	DoIt	OK
moderator@dana.de	0	0	0	1	0	0
TOTAL	0	0	0	1	0	0

Incoming Feeds (INN):

Server		Connects	Offered	Taken	Refused	Reject	%Accpt	Elapsed
1	newsfeed.pionier.net.pl	941	3838551	3827077	7417	4057	99%	502:22:34
2	news.nask.pl	2	26511	11852	2390	12269	44%	24:01:57
3	news.icm.edu.pl	42	221	20	198	3	9%	14:14:28
4	localhost	5	4	4	0	0	100%	00:00:01
TOTAL: 4		990	3865287	3838953	10005	16329	99%	540:39:00

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	1062.8 GB	149.6 MB	152.0 MB	1063.1 GB	99%	291.0 KB
2	news.nask.pl	170.2 MB	955.8 KB	137.7 MB	308.9 MB	55%	13.1 KB
3	news.icm.edu.pl	438.3 KB	8.6 KB	54.8 KB	501.7 KB	87%	21.8 KB
4	localhost	6.8 KB	0.0 KB	0.0 KB	6.8 KB	100%	1.7 KB
TOTAL: 4		1063.0 GB	150.5 MB	289.8 MB	1063.4 GB	99%	289.2 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Jan 6 04:15:01 - 04:59:59	113002	2.9%	41.87	32.0 GB	3.0%	12415.20
Jan 6 05:00:00 - 05:59:59	160287	4.2%	44.52	44.7 GB	4.2%	13033.10
Jan 6 06:00:00 - 06:59:59	159663	4.2%	44.35	44.5 GB	4.2%	12958.32
Jan 6 07:00:00 - 07:59:59	164586	4.3%	45.72	45.4 GB	4.3%	13210.90
Jan 6 08:00:00 - 08:59:59	168669	4.4%	46.85	45.8 GB	4.3%	13352.20
Jan 6 09:00:00 - 09:59:59	163948	4.3%	45.54	45.2 GB	4.3%	13178.80
Jan 6 10:00:00 - 10:59:59	160381	4.2%	44.55	45.1 GB	4.2%	13140.76
Jan 6 11:00:00 - 11:59:59	161662	4.2%	44.91	45.2 GB	4.3%	13176.13
Jan 6 12:00:00 - 12:59:59	161219	4.2%	44.78	45.1 GB	4.2%	13140.57
Jan 6 13:00:00 - 13:59:59	163171	4.3%	45.33	45.1 GB	4.2%	13125.56
Jan 6 14:00:00 - 14:59:59	160583	4.2%	44.61	44.5 GB	4.2%	12965.63
Jan 6 15:00:00 - 15:59:59	160577	4.2%	44.60	45.3 GB	4.3%	13191.42
Jan 6 16:00:00 - 16:59:59	150765	3.9%	41.88	44.6 GB	4.2%	12986.19
Jan 6 17:00:00 - 17:59:59	144173	3.8%	40.05	46.4 GB	4.4%	13527.91
Jan 6 18:00:00 - 18:59:59	141421	3.7%	39.28	47.1 GB	4.4%	13707.82
Jan 6 19:00:00 - 19:59:59	162392	4.2%	45.11	45.9 GB	4.3%	13375.69
Jan 6 20:00:00 - 20:59:59	169843	4.4%	47.18	46.0 GB	4.3%	13398.15
Jan 6 21:00:00 - 21:59:59	172978	4.5%	48.05	45.9 GB	4.3%	13362.78
Jan 6 22:00:00 - 22:59:59	173218	4.5%	48.12	45.7 GB	4.3%	13320.65
Jan 6 23:00:00 - 23:59:59	173808	4.5%	48.28	45.3 GB	4.3%	13185.44
Jan 7 00:00:00 - 00:59:59	131154	3.4%	36.43	35.7 GB	3.4%	10391.40
Jan 7 01:00:00 - 01:59:59	167641	4.4%	46.57	44.5 GB	4.2%	12960.44
Jan 7 02:00:00 - 02:59:59	178736	4.7%	49.65	43.7 GB	4.1%	12741.08
Jan 7 03:00:00 - 03:59:59	136601	3.6%	37.94	35.1 GB	3.3%	10209.85
Jan 7 04:00:00 - 04:15:01	37711	1.0%	41.85	9.5 GB	0.9%	11109.48
TOTAL: 24:00:00	3838189	100.0%	44.42	1063.4 GB	100.0%	12905.38

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	news.nask.pl	12492	28	297	6	12161
2	newsfeed.pionier.net.pl	4506	170	3803	1	532
3	news.icm.edu.pl	5	0	1	0	4
TOTAL: 3		17003	198	4101	7	12697

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	77
it.tlc.cellulari.android	43
comp.mobile.android	18
nl.comp.sys.android	12
it.comp.os.win.windows10	10
uk.rec.cycling.moderated	8
de.comp.lang.php	7
it.discussioni.energia	6
comp.sys.mac.vintage	4
it.comp.os.win.windows7	3
it.comp.os.win.windows8	3
comp.sys.raspberry-pi	2
comp.mobile.ipad	2
de.comp.lang.vbclassic	1
alt.sex.telephones	1
hr.ponuda.hardver.konfiguracije	1
TOTAL: 16	198

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

Groups	Count
pl.praca.oferowana	6
bit.listserv.catholic	1
TOTAL: 2	7

INND Perl filter [Top 20]:

Reason	Count
EMP (md5)	11532
EMP (phl)	152
Binary in non-binary group	148
Scoring filter (8)	52
Scoring filter (9)	37
Scoring filter (10)	11
EMP (fsl)	10
Scoring filter (11)	7
Scoring filter (14)	7
Scoring filter (17)	2
Scoring filter (12)	1
Too many newsgroups	1
Scoring filter (13)	1
TOTAL: 13	11961

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	14740	939	11794	499	166	38211	6%	23:57:57
2	poznan	16604	352	10974	31	0	0	2%	24:04:54
3	icm	40133	42	23074	12613	1	0	0%	24:02:48
4	nask	5661	8	4466	2	0	0	0%	23:54:52
5	okapi	0	0	0	0	0	2523	0%	24:00:01
TOTAL: 5		77138	1341	50308	13145	167	40734	1%	120:00:32

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	3.2 MB	2.9 GB	2.9 GB	34.6 KB/s	236.6 KB	24:02:48
2	panorama	2.9 MB	1.4 MB	4.3 MB	0.1 KB/s	3.1 KB	23:57:57
3	poznan	2.0 MB	114.2 KB	2.1 MB	0.0 KB/s	5.6 KB	24:04:54
4	nask	86.6 KB	9.8 KB	96.4 KB	0.0 KB/s	9.6 KB	23:54:52
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		8.1 MB	2.9 GB	2.9 GB	6.9 KB/s	207.1 KB	120:00:32

Backlog files shrunk by innfeed:

Server	Size
panorama	1.4 MB
okapi	488.3 KB
TOTAL: 2	1.9 MB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	xdsl-4103.lodz.dialog.net.pl	8	546	906.7 KB	6	0	01:49:36
2	web.man.lodz.pl	290	36	31.5 KB	17	0	00:00:22
3	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 3		302	582	938.2 KB	23	4	02:23:17

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.lodz.dialog.net.pl	8	546	906.7 KB	6	0	01:49:36
2	*.man.lodz.pl	678	36	31.5 KB	17	4	00:00:27
3	unresolved	2	0	0.0 KB	0	0	00:32:18
4	*.toya.net.pl	1	0	0.0 KB	0	0	00:00:54
TOTAL: 4		689	582	938.2 KB	23	4	02:23:17

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
xdsl-4103.lodz.dialog.net.pl	0.085	0.342	01:49:36
89.38.41.228	0.626	0.893	00:32:18
staticline60943.toya.net.pl	0.029	0.025	00:00:54
web.man.lodz.pl	0.983	7.120	00:00:22
mon.man.lodz.pl	0.956	6.181	00:00:03
david.man.lodz.pl	0.315	2.062	00:00:01
isvcs01.man.lodz.pl	0.014	0.116	00:00:00
TOTAL: 7	3.008	16.739	02:23:17

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
89.38.41.228	2
staticline60943.toya.net.pl	1
TOTAL: 4	387

NNRP client timeouts [Top 20]:

System	Conn	Peer
xdsl-4103.lodz.dialog.net.pl	2	0
TOTAL: 1	2	0

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	546	93.8%
2	pl	36	6.2%
TOTAL: 2		582	100.0%

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

Newsgroup		Count
1	lodman.info	546
2	pl.comp.pecet	36
TOTAL: 2		582

Daily Usenet report

Jan 6 04:15:01 -- Jan 7 04:15:01