Daily Usenet report

Oct 9 04:15:02 -- Oct 10 04:15:03

Unknown entries from news log file:

First 24 / 24 lines (100.0%)

Oct 9 09:00:21 newsfeed nnrpd[15859]: SERVER perl filter not defined
Oct 9 09:06:36 newsfeed nnrpd[16087]: SERVER perl filter not defined
Oct 9 10:06:56 newsfeed pgpverify[17980]: pgpverify: verification failed
Oct 9 10:25:04 newsfeed nnrpd[18456]: SERVER perl filter not defined
Oct 9 10:31:54 newsfeed nnrpd[18699]: SERVER perl filter not defined
Oct 9 19:07:06 newsfeed overchan[2219]: input line too long, skipping
Oct 9 19:31:26 newsfeed overchan[2219]: input line too long, skipping
Oct 9 20:02:08 newsfeed overchan[2219]: input line too long, skipping
Oct 9 22:43:55 newsfeed overchan[2219]: input line too long, skipping
Oct 9 22:59:36 newsfeed overchan[2219]: input line too long, skipping
Oct 9 23:10:42 newsfeed overchan[2219]: input line too long, skipping
Oct 9 23:24:06 newsfeed overchan[2219]: input line too long, skipping
Oct 9 23:40:51 newsfeed nnrpd[11250]: SERVER perl filter not defined
Oct 9 23:57:10 newsfeed nnrpd[11872]: SERVER perl filter not defined
Oct 10 01:46:10 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:03:59 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:07:18 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:09:06 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:12:19 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:12:55 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:41:21 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:50:19 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:55:22 newsfeed overchan[2219]: input line too long, skipping
Oct 10 02:59:57 newsfeed overchan[2219]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2314994 98.5%262.2 MB 98.0%
innd 18942 0.8%3.1 MB 1.2%
nnrpd 10375 0.4%1.1 MB 0.4%
innfeed 7030 0.3%1.0 MB 0.4%
overchan 17 0.0%2.2 KB 0.0%
controlchan 3 0.0%0.4 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72351362 100.0%267.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2308343 95.3%
Negative hits 74652 3.1%
Positive hits 39130 1.6%
Cache misses 318 0.0%
TOTAL: 4 2422443 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:10.129 0.0%421 0.000 24.059 642.000
article cleanup00:00:35.097 0.0%2314192 0.005 0.015 0.027
article logging00:01:36.721 0.1%2314355 0.016 0.042 0.951
article parse01:09:51.986 4.9%67541265 0.031 0.062 0.094
article write00:26:39.407 1.9%2307080 0.366 0.693 1.762
artlog/artcncl00:00:00.013 0.0%410 0.000 0.032 1.000
artlog/artparse00:00:00.000 0.0%3 0.000 0.000 0.000
data move00:02:18.908 0.2%80888225 0.001 0.002 0.004
hisgrep/artcncl00:00:01.907 0.0%331 0.000 5.761 251.000
hishave/artcncl00:00:02.118 0.0%421 0.000 5.031 201.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup03:23:33.208 14.1%2423613 0.648 5.039 30.824
history sync00:00:00.047 0.0%292 0.000 0.161 37.000
history write04:45:15.030 19.8%2309463 2.872 7.411 36.305
hiswrite/artcncl00:00:00.316 0.0%90 0.000 3.511 32.000
idle06:44:09.621 28.1%29784782 0.163 0.814 4.039
nntp read00:41:39.181 2.9%65637565 0.023 0.038 0.050
overview write00:00:15.893 0.0%2307080 0.001 0.007 0.019
perl filter06:15:13.940 26.1%2309460 7.250 9.749 11.360
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:39.665 0.3%4657316 0.014 0.060 0.086
TOTAL: 23:59:51.86823:36:03.187 98.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.170 0.0%43147 0.000 0.004 0.100
article prepare00:00:00.007 0.0%35644 0.000 0.000 0.036
article read00:00:01.286 0.0%8392 0.000 0.153 1.077
backlog stats00:00:00.250 0.0%313338 0.000 0.001 0.051
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.512 0.0%184873 0.000 0.014 1.000
data write00:00:06.453 0.0%159450 0.000 0.040 0.667
idle23:58:57.077100.0%313338 0.000 275.540 13063.000
status file00:00:00.790 0.0%228 0.000 3.465 143.500
TOTAL: 23:59:16.15223:59:08.545100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle52:42:27.716100.0%9188 0.000 20651.689600056.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.458 0.0%20868 0.000 0.022 0.667
readart00:00:01.914 0.0%594 0.000 3.222 27.929
TOTAL: 52:43:09.03852:42:30.088100.0%----

Control commands to INND:

CommandNumber
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:

SendernewgrouprmgroupOtherBad PGPDoItOK
hun-mnt@news.sztaki.hu000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 835 23011142295297 1541 4276 99%439:44:02
2news.task.gda.pl 13 29892 6156 22238 1498 20%44:58:03
3news.nask.pl 2 16931 5635 10001 1295 33%24:10:23
4localhost 7 6 6 0 0 100%00:00:00
5news.icm.edu.pl 40 287 5 269 13 1%17:25:07
TOTAL: 5 897 23482302307099 34049 7082 98%526:17:35
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl812.9 GB21.1 MB41.8 MB813.0 GB 99%370.7 KB
2news.nask.pl21.4 MB3.3 MB3.4 MB28.2 MB 75%4.2 KB
3news.task.gda.pl12.8 MB111.2 KB4.4 MB17.3 MB 74%2.3 KB
4news.icm.edu.pl151.9 KB54.2 KB315.6 KB521.6 KB 29%29.0 KB
5localhost9.3 KB0.0 KB0.0 KB9.3 KB100%1.6 KB
TOTAL: 5812.9 GB24.6 MB49.9 MB813.0 GB 99%368.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 9 04:15:02 - 04:59:59 53410 2.3% 19.8020.0 GB 2.5%7772.08
Oct 9 05:00:00 - 05:59:59 84226 3.7% 23.4031.1 GB 3.8%9052.20
Oct 9 06:00:00 - 06:59:59 87399 3.8% 24.2832.7 GB 4.0%9520.86
Oct 9 07:00:00 - 07:59:59 94862 4.1% 26.3535.1 GB 4.3%10222.30
Oct 9 08:00:00 - 08:59:59 91274 4.0% 25.3535.2 GB 4.3%10240.61
Oct 9 09:00:00 - 09:59:59 89493 3.9% 24.8632.2 GB 4.0%9387.27
Oct 9 10:00:00 - 10:59:59 76345 3.3% 21.2126.9 GB 3.3%7843.48
Oct 9 11:00:00 - 11:59:59 106277 4.6% 29.5237.7 GB 4.6%10994.88
Oct 9 12:00:00 - 12:59:59 102729 4.5% 28.5437.8 GB 4.7%11010.29
Oct 9 13:00:00 - 13:59:59 106061 4.6% 29.4639.0 GB 4.8%11346.10
Oct 9 14:00:00 - 14:59:59 101429 4.4% 28.1737.5 GB 4.6%10909.57
Oct 9 15:00:00 - 15:59:59 97753 4.2% 27.1535.1 GB 4.3%10234.91
Oct 9 16:00:00 - 16:59:59 98539 4.3% 27.3735.8 GB 4.4%10439.09
Oct 9 17:00:00 - 17:59:59 103539 4.5% 28.7636.7 GB 4.5%10688.99
Oct 9 18:00:00 - 18:59:59 106174 4.6% 29.4937.8 GB 4.7%11011.37
Oct 9 19:00:00 - 19:59:59 86627 3.8% 24.0630.6 GB 3.8%8917.27
Oct 9 20:00:00 - 20:59:59 88551 3.8% 24.6031.8 GB 3.9%9270.12
Oct 9 21:00:00 - 21:59:59 101422 4.4% 28.1736.5 GB 4.5%10645.87
Oct 9 22:00:00 - 22:59:59 102039 4.4% 28.3435.8 GB 4.4%10422.77
Oct 9 23:00:00 - 23:59:59 105505 4.6% 29.3137.9 GB 4.7%11026.91
Oct 10 00:00:00 - 00:59:59 102075 4.4% 28.3527.9 GB 3.4%8119.22
Oct 10 01:00:00 - 01:59:59 105141 4.6% 29.2126.4 GB 3.3%7690.94
Oct 10 02:00:00 - 02:59:59 92075 4.0% 25.5830.9 GB 3.8%9005.62
Oct 10 03:00:00 - 03:59:59 101214 4.4% 28.1135.6 GB 4.4%10378.08
Oct 10 04:00:00 - 04:15:03 23149 1.0% 25.648.2 GB 1.0%9523.44
TOTAL: 24:00:01 2307308 100.0% 26.70812.3 GB 100.0%9857.90
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4417 299 0 3416 0 0 0 0 702
2news.task.gda.pl 1539 1295 0 56 1 0 0 0 187
3news.nask.pl 1307 17 0 1254 0 0 0 0 36
4news.icm.edu.pl 13 0 0 4 0 0 0 0 9
TOTAL: 4 7276 1611 0 4730 1 0 0 0 934

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 148
mailing.freebsd.svn-ports-all 101
mailing.freebsd.svn-ports-head 100
fr.rec.loisirs-creatifs 89
mailing.freebsd.svn-src-all 61
fa.freebsd.cvs-all 60
mailing.openbsd.misc 59
mailing.freebsd.current 48
mozilla.dev.platform 47
mailing.freebsd.ports-bugs 44
mailing.database.pgsql-general 38
mailing.gcc.gcc-cvs 36
mailing.freebsd.svn-src-head 36
comp.sys.raspberry-pi 33
mailing.unix.samba-technical 32
mailing.freebsd.ports 32
it.tlc.cellulari.android 32
mailing.openbsd.source-changes 31
it.comp.os.win.windows7 30
alt.windows7.general 30
TOTAL: 86 1611

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

GroupsCount
fr.comp.sys.mac.annonces 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 585
EMP (fsl) 158
EMP (md5) 18
Attached HTML file 3
HTML post 2
Scoring filter (8) 1
Scoring filter (10) 1
TOTAL: 7 768

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 44199 3192 39116 811 0 0 7%23:56:37
2icm 60009 996 33722 3589 0 0 1%24:06:21
3task 2654 135 1307 13 0 0 5%21:54:13
4poznan 13507 69 11484 51 0 0 0%24:02:56
5nask 19534 19 14186 48 0 0 0%23:57:18
6okapi 0 0 0 0 0 2844 0%24:00:00
TOTAL: 6 139903 4411 99815 4512 0 2844 3%141:57:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm16.9 MB1.2 GB1.3 GB15.3 KB/s288.6 KB24:06:21
2panorama8.8 MB3.8 MB12.5 MB0.1 KB/s3.2 KB23:56:37
3task354.6 KB23.8 KB378.4 KB0.0 KB/s2.6 KB21:54:13
4poznan202.4 KB113.3 KB315.8 KB0.0 KB/s2.6 KB24:02:56
5nask39.4 KB30.4 MB30.4 MB0.4 KB/s465.1 KB23:57:18
6okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
TOTAL: 626.2 MB1.3 GB1.3 GB2.7 KB/s153.3 KB141:57:25
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1vegas.cm.p.lodz.pl 288 348601.9 KB 288 0 000:00:11
2212.191.78.190 307 104219.3 KB 38 1 047:38:50
3lze200.lze.lodz.pl 4 78129.3 KB 2 0 000:36:12
4web.man.lodz.pl 288 5051.8 KB 23 0 000:00:21
5router2.cbmm.lodz.pl 5 923.1 KB 3 0 000:19:23
6212.191.196.1 2 513.8 KB 1 0 000:15:14
7kujawiak.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 7 899 5941.0 MB 355 6 052:43:08

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 288 348601.9 KB 288 0 000:00:11
2unresolved 309 109233.0 KB 39 1 047:54:04
3*.lze.lodz.pl 4 78129.3 KB 2 0 000:36:12
4*.man.lodz.pl 677 5051.8 KB 23 5 000:00:33
5*.cbmm.lodz.pl 5 923.1 KB 3 0 000:19:23
6*.191.14.62.static.jazztel.es 5 00.0 KB 0 0 000:09:26
7*.lodz.mm.pl 6 00.0 KB 0 0 000:48:06
8*.neoplus.adsl.tpnet.pl 1 00.0 KB 0 0 000:00:21
9*.internetdsl.tpnet.pl 3 00.0 KB 0 0 000:00:00
10*.105.135.22.tvsat364.lodz.pl 14 00.0 KB 0 0 002:54:42
11*.toya.net.pl 63 00.0 KB 0 0 000:00:04
TOTAL: 111375 5941.0 MB 355 6 052:43:08

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.191.78.190 0.908 4.880 0.00047:38:50
ip-176.105.135.22.tvsat364.lodz.pl 0.048 0.236 0.00002:54:42
host-89-228-165-199.lodz.mm.pl 0.016 0.100 0.00000:48:06
lze200.lze.lodz.pl 0.016 0.096 0.00000:36:12
router2.cbmm.lodz.pl 0.016 0.084 0.00000:19:23
212.191.196.1 0.004 0.032 0.00000:15:14
16.191.14.62.static.jazztel.es 0.204 0.356 0.00000:09:26
web.man.lodz.pl 0.848 4.688 0.00000:00:21
eby224.neoplus.adsl.tpnet.pl 0.012 0.012 0.00000:00:21
vegas.cm.p.lodz.pl 0.656 4.188 0.00000:00:11
f-oper-v100.man.lodz.pl 0.752 5.088 0.00000:00:10
staticline41435.toya.net.pl 0.180 0.984 0.00000:00:04
david.man.lodz.pl 0.184 1.472 0.00000:00:01
kujawiak.man.lodz.pl 0.036 0.080 0.00000:00:00
gcq193.internetdsl.tpnet.pl 0.000 0.044 0.00000:00:00
TOTAL: 15 3.880 22.340 0.00052:43:08

Curious NNRP server explorers [Top 20]:

SystemConn
f-oper-v100.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 63
ip-176.105.135.22.tvsat364.lodz.pl 14
host-89-228-165-199.lodz.mm.pl 6
16.191.14.62.static.jazztel.es 5
gcq193.internetdsl.tpnet.pl 3
eby224.neoplus.adsl.tpnet.pl 1
TOTAL: 8 476

NNRP client timeouts [Top 20]:

SystemConnPeer
ip-176.105.135.22.tvsat364.lodz.pl 3 0
lze200.lze.lodz.pl 2 0
TOTAL: 2 5 0

Newsgroup request counts (by category):

CategoryCountPct
1lodman 348 58.6%
2pl 245 41.2%
3alt 1 0.2%
TOTAL: 3 594100.0%

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

NewsgroupCount
1lodman.info 348
2pl.misc.telefonia.gsm 78
3pl.rec.rowery 54
4pl.comp.os.ms-windows.winnt 32
5pl.misc.kolej 26
6pl.comp.pecet 18
7pl.regionalne.lodz 13
8pl.soc.prawo 7
9pl.regionalne.warszawa 7
10pl.misc.paranauki 5
11pl.praca.dyskusje 2
12pl.sci.chemia 2
13pl.rec.gory 1
14alt.pl.praca.oferowana 1
TOTAL: 14 594