Daily Usenet report: Dec 31 04:15:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

Dec 31 16:20:12 newsfeed nnrpd[17186]: SERVER perl filter not defined
Dec 31 16:23:44 newsfeed nnrpd[17237]: SERVER perl filter not defined
Dec 31 16:47:36 newsfeed nnrpd[18120]: SERVER perl filter not defined
Dec 31 18:26:05 newsfeed nnrpd[21406]: SERVER perl filter not defined
Jan 1 01:00:05 newsfeed pgpverify[2025]: pgpverify: verification failed

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	1924397	98.5%	217.5 MB	98.0%
innd	16046	0.8%	2.7 MB	1.2%
nnrpd	7087	0.4%	742.4 KB	0.3%
innfeed	6764	0.3%	1013.4 KB	0.4%
controlchan	3	0.0%	0.4 KB	0.0%
pgpverify	1	0.0%	0.1 KB	0.0%
TOTAL: 6	1954298	100.0%	221.9 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	1872471	97.7%
Negative hits	27101	1.4%
Positive hits	16727	0.9%
Cache misses	190	0.0%
TOTAL: 4	1916489	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:15.112	0.0%	353	0.000	42.810	945.000
article cleanup	00:00:33.107	0.0%	1877182	0.004	0.018	0.036
article logging	00:00:51.898	0.1%	1923390	0.010	0.027	0.135
article parse	01:13:57.496	5.1%	129516405	0.009	0.034	0.050
article write	00:19:12.922	1.3%	1872311	0.316	0.616	2.790
artlog/artcncl	00:00:00.008	0.0%	345	0.000	0.023	1.000
artlog/artparse	00:00:00.000	0.0%	2	0.000	0.000	0.000
data move	00:02:35.872	0.2%	140900822	0.000	0.001	0.006
hisgrep/artcncl	00:00:01.941	0.0%	275	0.000	7.058	272.000
hishave/artcncl	00:00:00.864	0.0%	353	0.000	2.448	76.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:42:21.527	7.1%	1916874	0.003	3.204	10.843
history sync	00:00:00.014	0.0%	291	0.000	0.048	2.000
history write	02:58:09.546	12.4%	1873055	0.102	5.707	30.189
hiswrite/artcncl	00:00:00.236	0.0%	78	0.000	3.026	34.000
idle	11:29:17.578	47.9%	77363667	0.353	0.535	2.025
nntp read	00:59:45.338	4.2%	127931227	0.010	0.028	0.037
overview write	00:00:12.376	0.0%	1872311	0.000	0.007	0.017
perl filter	04:27:38.514	18.6%	1873053	5.067	8.573	10.337
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:03:44.984	0.3%	3792257	0.036	0.059	0.111
TOTAL: 23:59:27.057	23:18:39.333	97.2%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.212	0.0%	47669	0.000	0.004	1.000
article prepare	00:00:00.006	0.0%	26028	0.000	0.000	0.250
article read	00:00:00.930	0.0%	6469	0.000	0.144	2.000
backlog stats	00:00:00.169	0.0%	273438	0.000	0.001	0.023
callbacks	00:00:00.000	0.0%	0	0.000	0.000	0.000
data read	00:00:01.877	0.0%	176095	0.000	0.011	0.087
data write	00:00:06.745	0.0%	134652	0.000	0.050	2.000
idle	24:00:32.351	100.0%	273438	0.000	316.095	10907.000
status file	00:00:00.884	0.0%	203	0.000	4.355	149.500
TOTAL: 24:00:49.796	24:00:43.174	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	01:25:36.411	99.6%	4209	0.000	1220.340	239910.000
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:00.235	0.0%	10214	0.000	0.023	1.000
readart	00:00:01.072	0.0%	419	0.000	2.558	223.000
TOTAL: 01:25:57.204	01:25:37.718	99.6%	-	-	-	-

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
control@usenet.org.uk	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	698	1872794	1868753	296	3745	99%	343:10:18
2	news.nask.pl	2	16594	3472	11993	1129	20%	24:03:57
3	news.icm.edu.pl	41	219	31	171	17	14%	18:07:14
4	localhost	5	4	4	0	0	100%	00:00:00
TOTAL: 4		746	1889611	1872260	12460	4891	99%	385:21:29

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	590.5 GB	48.2 MB	129.0 MB	590.7 GB	99%	330.8 KB
2	news.nask.pl	14.5 MB	3.1 MB	3.2 MB	20.8 MB	69%	4.6 KB
3	news.icm.edu.pl	905.6 KB	199.0 KB	2.3 MB	3.4 MB	26%	71.7 KB
4	localhost	6.0 KB	0.0 KB	0.0 KB	6.0 KB	100%	1.5 KB
TOTAL: 4		590.5 GB	51.5 MB	134.5 MB	590.7 GB	99%	330.0 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 31 04:15:02 - 04:59:59	39107	2.1%	14.49	12.3 GB	2.1%	4763.42
Dec 31 05:00:00 - 05:59:59	57319	3.1%	15.92	17.5 GB	3.0%	5093.69
Dec 31 06:00:00 - 06:59:59	77576	4.1%	21.55	26.0 GB	4.4%	7559.22
Dec 31 07:00:00 - 07:59:59	79621	4.3%	22.12	28.0 GB	4.7%	8149.49
Dec 31 08:00:00 - 08:59:59	80733	4.3%	22.43	28.4 GB	4.8%	8284.06
Dec 31 09:00:00 - 09:59:59	81567	4.4%	22.66	27.7 GB	4.7%	8060.85
Dec 31 10:00:00 - 10:59:59	79536	4.2%	22.09	27.3 GB	4.6%	7957.01
Dec 31 11:00:00 - 11:59:59	98856	5.3%	27.46	27.5 GB	4.7%	8010.44
Dec 31 12:00:00 - 12:59:59	70514	3.8%	19.59	19.0 GB	3.2%	5539.40
Dec 31 13:00:00 - 13:59:59	92166	4.9%	25.60	26.6 GB	4.5%	7737.72
Dec 31 14:00:00 - 14:59:59	70103	3.7%	19.47	19.9 GB	3.4%	5784.91
Dec 31 15:00:00 - 15:59:59	76341	4.1%	21.21	22.6 GB	3.8%	6594.97
Dec 31 16:00:00 - 16:59:59	66557	3.6%	18.49	17.3 GB	2.9%	5036.30
Dec 31 17:00:00 - 17:59:59	76778	4.1%	21.33	21.1 GB	3.6%	6143.29
Dec 31 18:00:00 - 18:59:59	85825	4.6%	23.84	28.5 GB	4.8%	8306.22
Dec 31 19:00:00 - 19:59:59	88466	4.7%	24.57	28.8 GB	4.9%	8400.70
Dec 31 20:00:00 - 20:59:59	90103	4.8%	25.03	29.7 GB	5.0%	8663.02
Dec 31 21:00:00 - 21:59:59	87970	4.7%	24.44	29.6 GB	5.0%	8624.78
Dec 31 22:00:00 - 22:59:59	82669	4.4%	22.96	28.8 GB	4.9%	8400.16
Dec 31 23:00:00 - 23:59:59	84768	4.5%	23.55	29.1 GB	4.9%	8485.51
Jan 1 00:00:00 - 00:59:59	58558	3.1%	16.27	18.1 GB	3.1%	5285.80
Jan 1 01:00:00 - 01:59:59	79302	4.2%	22.03	25.3 GB	4.3%	7381.21
Jan 1 02:00:00 - 02:59:59	71542	3.8%	19.87	23.1 GB	3.9%	6736.85
Jan 1 03:00:00 - 03:59:59	82095	4.4%	22.80	23.8 GB	4.0%	6931.70
Jan 1 04:00:00 - 04:15:02	14899	0.8%	16.52	4.5 GB	0.8%	5174.42
TOTAL: 24:00:00	1872971	100.0%	21.68	590.6 GB	100.0%	7168.07

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	49928	182	3016	1	46729
2	news.nask.pl	1136	5	1104	2	25
3	news.icm.edu.pl	17	0	9	0	8
TOTAL: 3		51081	187	4129	3	46762

Unwanted newsgroups [Top 20]:

Newsgroup	Count
uk.railway	102
it.tlc.cellulari.android	29
it.comp.os.win.windows7	13
comp.mobile.android	8
de.comm.provider.telefonie	5
it.comp.os.win.windows8	5
it.comp.virtualizzazione	4
nl.comp.os.linux.techniek	4
comp.sys.raspberry-pi	3
alt.sex.telephones	2
it.discussioni.energia	2
hr.ponuda.hardver.komponente	2
de.rec.sf.startrek	1
hr.alt.cellular.gsm.android	1
de.comp.lang.vbclassic	1
comp.mobile.ipad	1
de.comm.software.mailreader	1
de.comp.lang.perl	1
uk.rec.cycling.moderated	1
hr.alt.cellular.gsm.iphone	1
TOTAL: 20	187

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

Groups	Count
pl.sci.historia	2
pl.sci.matematyka	1
TOTAL: 2	3

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	532
HTML post	15
EMP (md5)	3
Attached HTML file	1
Scoring filter (10)	1
TOTAL: 5	552

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Spool	%Took	Elapsed
1	panorama	48377	1379	46053	219	0	2%	24:00:31
2	icm	49489	590	36780	4091	19	1%	24:05:46
3	nask	21894	157	13685	68	0	0%	24:00:35
4	poznan	4542	9	3259	3	0	0%	24:03:21
5	okapi	0	0	0	0	1371	0%	24:00:01
TOTAL: 5		124302	2135	99777	4381	1390	1%	120:10:14

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	panorama	5.7 MB	641.9 KB	6.3 MB	0.1 KB/s	4.1 KB	24:00:31
2	nask	1.8 MB	39.4 MB	41.2 MB	0.5 KB/s	187.6 KB	24:00:35
3	icm	1.5 MB	875.6 MB	877.2 MB	10.4 KB/s	191.9 KB	24:05:46
4	poznan	18.8 KB	7.5 KB	26.3 KB	0.0 KB/s	2.2 KB	24:03:21
5	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:01
TOTAL: 5		9.0 MB	915.7 MB	924.7 MB	2.2 KB/s	145.3 KB	120:10:14

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Elapsed
1	pc-skrzep2.cm.p.lodz.pl	288	336	512.1 KB	288	0	00:00:07
2	web.man.lodz.pl	288	50	54.0 KB	21	0	00:00:24
3	lze200.lze.lodz.pl	9	33	60.3 KB	6	0	01:15:10
4	isvcs01.man.lodz.pl	4	0	0.0 KB	0	4	00:00:00
TOTAL: 4		589	419	626.4 KB	315	4	01:25:56

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Elapsed
1	*.cm.p.lodz.pl	288	336	512.1 KB	288	0	00:00:07
2	*.man.lodz.pl	676	50	54.0 KB	21	4	00:00:31
3	*.lze.lodz.pl	9	33	60.3 KB	6	0	01:15:10
4	unresolved	3	0	0.0 KB	0	0	00:09:49
5	*.toya.net.pl	47	0	0.0 KB	0	0	00:00:17
TOTAL: 5		1023	419	626.4 KB	315	4	01:25:56

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
lze200.lze.lodz.pl	0.052	0.179	01:15:10
194.33.77.50	0.000	0.022	00:09:34
web.man.lodz.pl	1.019	5.337	00:00:24
staticline41435.toya.net.pl	0.170	0.864	00:00:17
212.51.203.199	0.008	0.034	00:00:15
pc-skrzep2.cm.p.lodz.pl	1.086	5.402	00:00:07
mon.man.lodz.pl	0.892	5.332	00:00:04
david.man.lodz.pl	0.355	1.763	00:00:01
isvcs01.man.lodz.pl	0.028	0.080	00:00:00
?	0.003	0.014	00:00:00
TOTAL: 10	3.613	19.027	01:25:56

Curious NNRP server explorers [Top 20]:

System	Conn
mon.man.lodz.pl	288
david.man.lodz.pl	96
staticline41435.toya.net.pl	47
212.51.203.199	2
194.33.77.50	1
TOTAL: 5	434

NNRP gethostbyaddr failures [Top 20]:

System	Conn
? (can't getpeername)	1
TOTAL: 1	1

NNRP client timeouts [Top 20]:

System	Conn	Peer
lze200.lze.lodz.pl	5	1
TOTAL: 1	5	1

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	336	80.2%
2	pl	83	19.8%
TOTAL: 2		419	100.0%

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

Newsgroup		Count
1	lodman.info	336
2	pl.comp.pecet	53
3	pl.comp.os.ms-windows.winnt	18
4	pl.misc.telefonia.gsm	12
TOTAL: 4		419

Daily Usenet report

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