Daily Usenet report: Dec 17 04:15:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Dec 17 04:15:02 newsfeed nnrpd[9895]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 17 04:15:02 newsfeed nnrpd[9896]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 17 04:15:02 newsfeed nnrpd[9904]: web.man.lodz.pl rejected Flushing log and syslog files
Dec 17 08:36:50 newsfeed nnrpd[19226]: SERVER perl filter not defined
Dec 17 13:10:06 newsfeed pgpverify[28163]: pgpverify: verification failed
Dec 17 16:34:18 newsfeed nnrpd[2315]: SERVER perl filter not defined
Dec 17 23:38:58 newsfeed overchan[10555]: input line too long, skipping

Log entries by program:

Program name	Lines	%Lines	Size	%Size
inn	3512873	98.4%	393.9 MB	98.0%
innd	26562	0.7%	4.3 MB	1.1%
nnrpd	22189	0.6%	2.4 MB	0.6%
innfeed	7443	0.2%	1.1 MB	0.3%
controlchan	3	0.0%	0.4 KB	0.0%
overchan	1	0.0%	0.1 KB	0.0%
pgpverify	1	0.0%	0.2 KB	0.0%
TOTAL: 7	3569072	100.0%	401.8 MB	100.0%

History cache:

Reason	Count	%Count
Do not exist	3504548	97.3%
Negative hits	56410	1.6%
Positive hits	41322	1.1%
Cache misses	385	0.0%
TOTAL: 4	3602665	100.0%

INND timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article cancel	00:00:10.960	0.0%	717	0.000	15.286	577.000
article cleanup	00:00:38.656	0.0%	3512577	0.003	0.011	0.037
article logging	00:01:32.250	0.1%	3512705	0.011	0.026	0.280
article parse	00:55:51.109	3.9%	175688044	0.006	0.019	0.070
article write	00:15:40.406	1.1%	3501971	0.057	0.269	24.069
artlog/artcncl	00:00:00.009	0.0%	691	0.000	0.013	1.000
artlog/artparse	00:00:00.000	0.0%	4	0.000	0.000	0.000
data move	00:03:34.640	0.2%	184810109	0.000	0.001	0.132
hisgrep/artcncl	00:00:01.256	0.0%	349	0.000	3.599	208.000
hishave/artcncl	00:00:00.596	0.0%	717	0.000	0.831	11.000
history grep	00:00:00.000	0.0%	0	0.000	0.000	0.000
history lookup	01:12:02.195	5.0%	3603854	0.014	1.199	25.612
history sync	00:00:00.014	0.0%	292	0.000	0.048	1.000
history write	02:33:55.754	10.7%	3505848	0.357	2.634	38.945
hiswrite/artcncl	00:00:01.206	0.0%	368	0.000	3.277	18.500
idle	12:47:46.187	53.3%	131579315	0.014	0.350	2.894
nntp read	00:55:35.863	3.9%	173594238	0.007	0.019	0.042
overview write	00:00:19.022	0.0%	3501971	0.000	0.005	0.017
perl filter	04:19:17.174	18.0%	3505844	1.146	4.437	12.808
python filter	00:00:00.000	0.0%	0	0.000	0.000	0.000
site send	00:05:57.295	0.4%	7042509	0.028	0.051	0.160
TOTAL: 24:00:19.654	23:12:24.592	96.7%	-	-	-	-

INNfeed timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
article new	00:00:00.151	0.0%	38708	0.000	0.004	0.111
article prepare	00:00:00.003	0.0%	38054	0.000	0.000	0.050
article read	00:00:01.863	0.0%	9116	0.000	0.204	380.000
backlog stats	00:00:00.207	0.0%	297330	0.000	0.001	0.016
callbacks	00:00:00.000	0.0%	1	0.000	0.000	0.000
data read	00:00:01.892	0.0%	171901	0.000	0.011	0.083
data write	00:00:04.941	0.0%	145953	0.000	0.034	21.000
idle	23:59:31.185	100.0%	297330	0.000	290.489	15075.000
status file	00:00:12.012	0.0%	229	0.000	52.454	3697.667
TOTAL: 23:59:58.959	23:59:52.254	100.0%	-	-	-	-

nnrpd timer:

Code region	Time	Pct	Invoked	Min(ms)	Avg(ms)	Max(ms)
idle	56:39:39.815	99.9%	41700	0.000	4891.602	450577.500
newnews	00:00:00.000	0.0%	0	0.000	0.000	0.000
nntpwrite	00:00:01.896	0.0%	88020	0.000	0.022	13.417
readart	00:00:01.667	0.0%	491	0.000	3.395	102.000
TOTAL: 56:41:44.212	56:39:43.378	99.9%	-	-	-	-

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
newsmaster@carnet.hr	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	835	3499715	3490605	906	8204	99%	333:41:38
2	news.nask.pl	2	18082	6790	10395	897	37%	23:40:05
3	news.task.gda.pl	11	30804	6665	22623	1516	21%	45:50:23
4	news.icm.edu.pl	42	326	2	320	4	0%	20:09:03
5	localhost	3	2	2	0	0	100%	00:00:00
TOTAL: 5		893	3548929	3504064	34244	10621	98%	423:21:09

Incoming Volume (INN):

Server		AcceptVol	DupVol	RejVol	TotalVol	%Acc	Vol/Art
1	newsfeed.pionier.net.pl	530.3 GB	30.0 MB	509.2 MB	530.8 GB	99%	159.1 KB
2	news.nask.pl	26.6 MB	2.3 MB	2.4 MB	31.4 MB	84%	4.2 KB
3	news.task.gda.pl	14.3 MB	84.8 KB	4.8 MB	19.2 MB	74%	2.4 KB
4	news.icm.edu.pl	97.2 KB	0.0 KB	44.2 KB	141.4 KB	68%	23.6 KB
5	localhost	3.0 KB	0.0 KB	0.0 KB	3.0 KB	100%	1.5 KB
TOTAL: 5		530.4 GB	32.4 MB	516.4 MB	530.9 GB	99%	158.4 KB

Incoming articles:

Date	Articles	%Arts	Art/sec	Size	%Size	KB/sec
Dec 17 04:15:02 - 04:59:59	115253	3.3%	42.72	17.4 GB	3.3%	6781.18
Dec 17 05:00:00 - 05:59:59	168154	4.8%	46.71	24.4 GB	4.6%	7094.19
Dec 17 06:00:00 - 06:59:59	177025	5.1%	49.17	24.7 GB	4.7%	7200.27
Dec 17 07:00:00 - 07:59:59	177922	5.1%	49.42	25.6 GB	4.8%	7469.75
Dec 17 08:00:00 - 08:59:59	171892	4.9%	47.75	23.7 GB	4.5%	6892.79
Dec 17 09:00:00 - 09:59:59	164860	4.7%	45.79	25.2 GB	4.8%	7330.93
Dec 17 10:00:00 - 10:59:59	181166	5.2%	50.32	29.9 GB	5.6%	8711.31
Dec 17 11:00:00 - 11:59:59	191347	5.5%	53.15	30.0 GB	5.7%	8747.52
Dec 17 12:00:00 - 12:59:59	172254	4.9%	47.85	21.8 GB	4.1%	6348.96
Dec 17 13:00:00 - 13:59:59	168106	4.8%	46.70	22.0 GB	4.2%	6408.06
Dec 17 14:00:00 - 14:59:59	149674	4.3%	41.58	14.6 GB	2.8%	4244.67
Dec 17 15:00:00 - 15:59:59	154813	4.4%	43.00	15.1 GB	2.8%	4391.10
Dec 17 16:00:00 - 16:59:59	149221	4.3%	41.45	16.7 GB	3.1%	4858.99
Dec 17 17:00:00 - 17:59:59	139731	4.0%	38.81	16.9 GB	3.2%	4923.45
Dec 17 18:00:00 - 18:59:59	129988	3.7%	36.11	15.7 GB	3.0%	4567.11
Dec 17 19:00:00 - 19:59:59	224720	6.4%	62.42	16.0 GB	3.0%	4662.49
Dec 17 20:00:00 - 20:59:59	237240	6.8%	65.90	16.3 GB	3.1%	4757.46
Dec 17 21:00:00 - 21:59:59	181725	5.2%	50.48	16.4 GB	3.1%	4771.42
Dec 17 22:00:00 - 22:59:59	59948	1.7%	16.65	15.4 GB	2.9%	4495.59
Dec 17 23:00:00 - 23:59:59	58298	1.7%	16.19	16.0 GB	3.0%	4669.66
Dec 18 00:00:00 - 00:59:59	66492	1.9%	18.47	26.0 GB	4.9%	7573.76
Dec 18 01:00:00 - 01:59:59	72866	2.1%	20.24	28.6 GB	5.4%	8320.62
Dec 18 02:00:00 - 02:59:59	90280	2.6%	25.08	34.0 GB	6.4%	9892.41
Dec 18 03:00:00 - 03:59:59	81651	2.3%	22.68	30.9 GB	5.8%	8993.92
Dec 18 04:00:00 - 04:15:02	16817	0.5%	18.64	6.5 GB	1.2%	7520.41
TOTAL: 24:00:00	3501443	100.0%	40.53	529.7 GB	100.0%	6428.87

Sites sending bad articles:

Server		Total	Group	Duplic	Unapp	Other
1	newsfeed.pionier.net.pl	8280	274	5813	2	2191
2	news.task.gda.pl	1515	1250	35	0	230
3	news.nask.pl	940	16	886	6	32
4	news.icm.edu.pl	4	0	0	0	4
TOTAL: 4		10739	1540	6734	8	2457

Unwanted newsgroups [Top 20]:

Newsgroup	Count
mailing.freebsd.svn-ports-all	119
uk.railway	103
mailing.freebsd.svn-ports-head	99
mailing.www.horde-bugs	91
mailing.freebsd.ports-bugs	74
alt.windows7.general	62
mozilla.dev.tree-management	62
mailing.freebsd.current	56
mailing.freebsd.ports	46
mailing.freebsd.svn-src-all	45
fa.freebsd.cvs-all	45
mailing.gcc.gcc-cvs	38
it.tlc.cellulari.android	38
alt.politics.scorched-earth	37
fr.usenet.abus.nocem	36
mailing.openbsd.misc	33
mailing.freebsd.svn-src-head	32
mailing.unix.samba-technical	31
mailing.unix.samba	28
gmane.linux.alsa.devel	27
TOTAL: 89	1540

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

Groups	Count
pl.sci.historia	8
TOTAL: 1	8

INND Perl filter [Top 20]:

Reason	Count
Binary in non-binary group	2041
EMP (fsl)	204
Attached HTML file	71
EMP (md5)	9
TOTAL: 4	2325

Outgoing Feeds (innfeed) by Articles:

Server		Offered	Taken	Refused	Reject	Miss	Spool	%Took	Elapsed
1	panorama	41650	3489	34594	482	1	0	8%	23:59:33
2	icm	60308	2914	32307	2395	16	139	4%	24:00:03
3	poznan	16532	97	13225	56	0	0	0%	23:58:08
4	nask	20339	23	11991	9	0	0	0%	24:02:04
5	task	1929	11	1028	1	0	2	0%	22:28:00
6	okapi	0	0	0	0	0	2560	0%	24:00:00
TOTAL: 6		140758	6534	93145	2943	17	2701	4%	142:27:48

Outgoing Feeds (innfeed) by Volume:

Server		AcceptVol	RejectVol	TotalVol	Volume/sec	Vol/Art	Elapsed
1	icm	40.2 MB	289.0 MB	329.3 MB	3.9 KB/s	63.5 KB	24:00:03
2	panorama	14.7 MB	1.5 MB	16.2 MB	0.2 KB/s	4.2 KB	23:59:33
3	poznan	365.5 KB	111.2 KB	476.7 KB	0.0 KB/s	3.1 KB	23:58:08
4	nask	89.5 KB	3.1 MB	3.2 MB	0.0 KB/s	102.3 KB	24:02:04
5	task	19.3 KB	2.9 KB	22.2 KB	0.0 KB/s	1.9 KB	22:28:00
6	okapi	0.0 KB	0.0 KB	0.0 KB	0.0 KB/s	0	24:00:00
TOTAL: 6		55.4 MB	293.8 MB	349.2 MB	0.7 KB/s	37.7 KB	142:27:48

Backlog files shrunk by innfeed:

Server	Size
okapi	488.4 KB
TOTAL: 1	488.4 KB

NNRP readership statistics:

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	vegas.cm.p.lodz.pl	288	312	446.4 KB	288	0	0	00:00:13
2	web.man.lodz.pl	292	98	98.8 KB	42	0	0	00:00:23
3	212.191.78.190	306	28	47.2 KB	10	0	0	47:28:39
4	h-87-199-44-244.dolsat.pl	6	21	17.0 KB	5	0	4	00:13:19
5	abmk183.neoplus.adsl.tpnet.pl	26	18	15.7 KB	4	0	5	00:35:13
6	proxy.filmschool.lodz.pl	2	10	40.4 KB	1	0	0	00:05:53
7	aanq25.neoplus.adsl.tpnet.pl	10	3	2.4 KB	3	0	2	01:06:34
8	staticline-31-182-159-97.toya.net.pl	1	1	0.8 KB	1	0	0	00:00:52
9	cde8.neoplus.adsl.tpnet.pl	6	0	0.0 KB	0	0	4	00:19:43
10	kujawiak.man.lodz.pl	2	0	0.0 KB	0	2	0	00:00:00
TOTAL: 10		939	491	668.6 KB	354	2	15	56:41:40

NNRP readership statistics (by domain):

System		Conn	Arts	Size	Groups	Post	Rej	Elapsed
1	*.cm.p.lodz.pl	288	312	446.4 KB	288	0	0	00:00:13
2	*.man.lodz.pl	676	98	98.8 KB	42	2	0	00:00:29
3	unresolved	2608	28	47.2 KB	10	0	0	48:36:29
4	*.neoplus.adsl.tpnet.pl	42	21	18.1 KB	7	0	11	02:01:31
5	*.dolsat.pl	6	21	17.0 KB	5	0	4	00:13:19
6	*.filmschool.lodz.pl	2	10	40.4 KB	1	0	0	00:05:53
7	*.toya.net.pl	96	1	0.8 KB	1	0	0	00:01:00
8	*.cbmm.lodz.pl	2	0	0.0 KB	0	0	0	00:00:03
9	*.wroclaw.mm.pl	14	0	0.0 KB	0	0	0	02:12:44
10	*.181.14.62.static.jazztel.es	2	0	0.0 KB	0	0	0	01:40:56
11	*.105.135.22.tvsat364.lodz.pl	7	0	0.0 KB	0	0	0	01:48:59
TOTAL: 11		3743	491	668.6 KB	354	2	15	56:41:40

NNRP total resource statistics [Top 20]:

System	User(ms)	System(ms)	Elapsed
212.191.78.190	0.764	4.504	47:28:39
host-89-231-50-15.wroclaw.mm.pl	0.032	0.184	02:12:44
ip-176.105.135.22.tvsat364.lodz.pl	0.016	0.088	01:48:59
219.181.14.62.static.jazztel.es	1.408	1.644	01:40:56
aanq25.neoplus.adsl.tpnet.pl	0.036	0.172	01:06:34
abmk183.neoplus.adsl.tpnet.pl	0.056	0.424	00:35:13
122.150.26.44	1.968	14.556	00:28:54
cde8.neoplus.adsl.tpnet.pl	0.056	0.132	00:19:43
122.151.48.39	0.924	7.564	00:16:39
h-87-199-44-244.dolsat.pl	0.044	0.116	00:13:19
122.150.25.222	0.748	5.236	00:09:13
122.150.26.192	0.572	3.984	00:07:54
proxy.filmschool.lodz.pl	0.004	0.028	00:05:53
122.151.55.37	0.332	2.348	00:04:40
staticline-31-182-159-97.toya.net.pl	0.004	0.024	00:00:52
122.150.27.84	0.032	0.212	00:00:27
web.man.lodz.pl	0.640	4.884	00:00:23
vegas.cm.p.lodz.pl	0.624	4.780	00:00:13
staticline41435.toya.net.pl	0.200	1.372	00:00:07
mon.man.lodz.pl	0.592	4.308	00:00:04
TOTAL: 23	9.240	58.064	56:41:40

Curious NNRP server explorers [Top 20]:

System	Conn
122.150.26.44	1001
122.151.48.39	510
122.150.25.222	351
mon.man.lodz.pl	288
122.150.26.192	270
122.151.55.37	155
staticline41435.toya.net.pl	95
david.man.lodz.pl	94
122.150.27.84	15
host-89-231-50-15.wroclaw.mm.pl	14
ip-176.105.135.22.tvsat364.lodz.pl	7
219.181.14.62.static.jazztel.es	2
router2.cbmm.lodz.pl	2
TOTAL: 13	2804

NNRP client timeouts [Top 20]:

System	Conn	Peer
aanq25.neoplus.adsl.tpnet.pl	1	0
ip-176.105.135.22.tvsat364.lodz.pl	1	0
TOTAL: 2	2	2

Newsgroup request counts (by category):

Category		Count	Pct
1	lodman	312	63.5%
2	pl	136	27.7%
3	lodz	40	8.1%
4	lodzman	3	0.6%
TOTAL: 4		491	100.0%

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

Newsgroup		Count
1	lodman.info	312
2	pl.comp.pecet	68
3	lodz.test	40
4	pl.comp.os.ms-windows.winnt	30
5	pl.comp.os.linux	11
6	pl.rec.foto.cyfrowa	10
7	pl.rec.gory	7
8	pl.rec.rowery	5
9	pl.praca.dyskusje	3
10	lodzman.test	3
11	pl.soc.prawo	2
TOTAL: 11		491

Daily Usenet report

Dec 17 04:15:02 -- Dec 18 04:15:02