Daily Usenet report

Nov 15 04:15:04 -- Nov 16 04:15:01

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Nov 15 11:11:05 newsfeed controlchan[2234]: control_checkgroups, ^.*$ !ka.lists #20131115 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000003CA045F0000006A@, , , utf-8, news.task.gda.pl, mail, 1
Nov 15 11:11:06 newsfeed controlchan[2234]: control_checkgroups, ^.*$ !ka.lists #20131115 usenet@karlsruhe.org usenet@karlsruhe.org @0304425546303500000003CA045F0000006A@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Nov 15 12:12:01 newsfeed nnrpd[1159]: SERVER perl filter not defined
Nov 15 15:08:08 newsfeed nnrpd[7353]: SERVER perl filter not defined
Nov 15 15:11:46 newsfeed nnrpd[7435]: SERVER perl filter not defined
Nov 15 17:00:07 newsfeed pgpverify[11070]: pgpverify: verification failed
Nov 15 21:07:29 newsfeed nnrpd[19220]: SERVER perl filter not defined
Nov 15 22:02:32 newsfeed nnrpd[20969]: SERVER perl filter not defined
Nov 15 22:08:35 newsfeed nnrpd[21215]: SERVER perl filter not defined
Nov 15 23:58:14 newsfeed nnrpd[24813]: SERVER perl filter not defined
Nov 16 00:01:58 newsfeed nnrpd[24940]: SERVER perl filter not defined
Nov 16 00:06:05 newsfeed overchan[2236]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2169603 98.3%243.8 MB 97.9%
innd 19474 0.9%3.2 MB 1.3%
nnrpd 10654 0.5%1.1 MB 0.4%
innfeed 6382 0.3%946.0 KB 0.4%
controlchan 5 0.0%0.8 KB 0.0%
overchan 1 0.0%0.2 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 72206120 100.0%249.0 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2164237 95.3%
Negative hits 67075 3.0%
Positive hits 39066 1.7%
Cache misses 224 0.0%
TOTAL: 4 2270602 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:10.660 0.0%382 0.000 27.906 957.000
article cleanup00:00:28.684 0.0%2168895 0.005 0.013 0.027
article logging00:01:05.521 0.1%2169014 0.015 0.030 0.439
article parse01:05:00.906 4.5%61176503 0.023 0.064 0.090
article write00:20:01.805 1.4%2162786 0.352 0.556 1.354
artlog/artcncl00:00:00.007 0.0%378 0.000 0.019 1.000
artlog/artparse00:00:00.001 0.0%3 0.000 0.333 0.500
data move00:05:49.807 0.4%73841294 0.000 0.005 0.085
hisgrep/artcncl00:00:01.428 0.0%279 0.000 5.118 294.000
hishave/artcncl00:00:00.826 0.0%382 0.000 2.162 279.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:00:14.465 8.4%2271403 0.016 3.176 48.017
history sync00:00:00.014 0.0%291 0.000 0.048 1.000
history write03:33:37.939 14.8%2165103 0.751 5.920 42.947
hiswrite/artcncl00:00:00.930 0.0%103 0.000 9.029 214.000
idle10:05:32.623 42.1%29192913 0.225 1.245 4.871
nntp read00:34:17.596 2.4%59404997 0.015 0.035 0.068
overview write00:00:13.290 0.0%2162786 0.001 0.006 0.021
perl filter05:46:33.124 24.1%2165100 6.509 9.604 12.276
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:16.175 0.3%4365365 0.036 0.059 0.101
TOTAL: 23:59:33.85923:37:25.801 98.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.169 0.0%39787 0.000 0.004 0.167
article prepare00:00:00.005 0.0%15716 0.000 0.000 0.250
article read00:00:00.292 0.0%3886 0.000 0.075 2.375
backlog stats00:00:00.150 0.0%192996 0.000 0.001 1.000
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.378 0.0%121363 0.000 0.011 1.000
data write00:00:03.291 0.0%82726 0.000 0.040 36.000
idle23:58:50.767100.0%192996 0.000 447.319 19080.000
status file00:00:20.933 0.0%254 0.000 82.413 8138.000
TOTAL: 23:59:22.36523:59:16.985100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle56:14:06.886100.0%6890 0.000 29382.712600064.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:08.227 0.0%137114 0.000 0.060 5.556
readart00:00:03.807 0.0%614 0.000 6.200 75.333
TOTAL: 56:15:14.85856:14:18.920100.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
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1159 21561062150938 1242 3926 99%377:09:27
2news.task.gda.pl 15 31359 7378 22602 1379 23%45:54:50
3news.nask.pl 2 16553 4384 11347 822 26%24:01:27
4localhost 10 9 9 0 0 100%00:00:00
TOTAL: 4 1186 22040272162709 35191 6127 98%447:05:44
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl784.5 GB124.5 MB162.7 MB784.8 GB 99%381.9 KB
2news.nask.pl15.5 MB1.9 MB2.0 MB19.4 MB 79%3.8 KB
3news.task.gda.pl14.7 MB73.9 KB4.0 MB18.8 MB 78%2.2 KB
4localhost15.0 KB0.0 KB0.0 KB15.0 KB100%1.7 KB
TOTAL: 4784.5 GB126.4 MB168.8 MB784.8 GB 99%379.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 15 04:15:04 - 04:59:59 65081 3.0% 24.1425.8 GB 3.3%10052.13
Nov 15 05:00:00 - 05:59:59 93551 4.3% 25.9935.7 GB 4.5%10385.31
Nov 15 06:00:00 - 06:59:59 92266 4.3% 25.6337.1 GB 4.7%10801.57
Nov 15 07:00:00 - 07:59:59 93639 4.3% 26.0137.6 GB 4.8%10966.34
Nov 15 08:00:00 - 08:59:59 97172 4.5% 26.9936.0 GB 4.6%10490.15
Nov 15 09:00:00 - 09:59:59 124830 5.8% 34.6738.9 GB 5.0%11329.77
Nov 15 10:00:00 - 10:59:59 89832 4.2% 24.9530.7 GB 3.9%8943.86
Nov 15 11:00:00 - 11:59:59 79670 3.7% 22.1328.8 GB 3.7%8393.47
Nov 15 12:00:00 - 12:59:59 81747 3.8% 22.7129.5 GB 3.8%8588.71
Nov 15 13:00:00 - 13:59:59 109179 5.0% 30.3338.0 GB 4.8%11079.13
Nov 15 14:00:00 - 14:59:59 107679 5.0% 29.9139.1 GB 5.0%11403.12
Nov 15 15:00:00 - 15:59:59 79430 3.7% 22.0631.5 GB 4.0%9182.89
Nov 15 16:00:00 - 16:59:59 78481 3.6% 21.8029.4 GB 3.7%8555.33
Nov 15 17:00:00 - 17:59:59 86784 4.0% 24.1132.1 GB 4.1%9349.31
Nov 15 18:00:00 - 18:59:59 93190 4.3% 25.8933.7 GB 4.3%9804.45
Nov 15 19:00:00 - 19:59:59 94872 4.4% 26.3533.6 GB 4.3%9773.10
Nov 15 20:00:00 - 20:59:59 94174 4.4% 26.1633.2 GB 4.2%9667.34
Nov 15 21:00:00 - 21:59:59 92461 4.3% 25.6830.6 GB 3.9%8917.77
Nov 15 22:00:00 - 22:59:59 87359 4.0% 24.2728.6 GB 3.6%8332.84
Nov 15 23:00:00 - 23:59:59 88517 4.1% 24.5930.2 GB 3.9%8797.52
Nov 16 00:00:00 - 00:59:59 80778 3.7% 22.4428.4 GB 3.6%8280.59
Nov 16 01:00:00 - 01:59:59 90922 4.2% 25.2631.9 GB 4.1%9283.22
Nov 16 02:00:00 - 02:59:59 78419 3.6% 21.7829.3 GB 3.7%8520.56
Nov 16 03:00:00 - 03:59:59 68260 3.2% 18.9628.2 GB 3.6%8211.26
Nov 16 04:00:00 - 04:15:01 14701 0.7% 16.326.4 GB 0.8%7430.47
TOTAL: 23:59:57 2162994 100.0% 25.04784.3 GB 100.0%9518.88
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4026 289 0 2988 1 0 0 0 748
2news.task.gda.pl 1382 1128 0 40 0 0 0 0 214
3news.nask.pl 823 29 0 767 1 0 0 0 26
TOTAL: 3 6231 1446 0 3795 2 0 0 0 988

Unwanted newsgroups [Top 20]:

NewsgroupCount
mailing.freebsd.svn-ports-all 163
mailing.freebsd.svn-ports-head 162
uk.railway 134
mailing.freebsd.ports-bugs 74
comp.mobile.ipad 63
alt.politics.scorched-earth 63
mailing.gcc.gcc-cvs 58
fa.freebsd.cvs-all 47
mailing.freebsd.svn-src-all 44
mailing.openbsd.misc 42
mailing.unix.samba-technical 32
it.tlc.cellulari.android 31
mailing.freebsd.svn-src-head 28
alt.windows7.general 25
comp.sys.raspberry-pi 25
gmane.linux.alsa.devel 24
mailing.openbsd.ports-changes 23
mailing.openbsd.source-changes 22
comp.mobile.android 22
mailing.database.pgsql-general 18
TOTAL: 79 1446

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

GroupsCount
pl.sci.matematyka 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 565
EMP (fsl) 179
Attached HTML file 103
EMP (md5) 10
HTML post 9
TOTAL: 5 866

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 41296 2673 36464 710 0 0 6%23:57:37
2task 2515 60 1299 7 0 0 2%22:19:20
3nask 20888 20 13539 449 0 0 0%24:00:31
4poznan 13543 16 11605 3 0 0 0%23:52:50
5okapi 0 0 0 0 0 2663 0%24:00:01
6icm 0 0 0 0 0 37595 0%24:00:01
TOTAL: 6 78242 2769 62907 1169 0 40258 3%142:10:20
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama7.9 MB2.9 MB10.8 MB0.1 KB/s3.3 KB23:57:37
2poznan135.3 KB6.3 KB141.6 KB0.0 KB/s7.5 KB23:52:50
3task124.9 KB13.3 KB138.2 KB0.0 KB/s2.1 KB22:19:20
4nask45.8 KB170.9 MB170.9 MB2.0 KB/s373.1 KB24:00:31
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
6icm0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
TOTAL: 68.2 MB173.8 MB182.0 MB0.4 KB/s47.3 KB142:10:20
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1vegas.cm.p.lodz.pl 288 288405.3 KB 288 0 000:00:14
2lze200.lze.lodz.pl 10 145290.4 KB 12 0 001:45:05
3212.191.78.190 323 83152.7 KB 47 5 047:34:11
4web.man.lodz.pl 289 7669.6 KB 36 2 000:00:26
5proxy.filmschool.lodz.pl 6 1521.6 KB 4 0 001:08:48
6router2.cbmm.lodz.pl 2 46.1 KB 2 0 000:01:14
7ip-176.105.135.22.tvsat364.lodz.pl 8 23.5 KB 2 0 003:12:56
8pc24.zsk.p.lodz.pl 6 10.7 KB 1 2 200:25:08
TOTAL: 8 932 614949.9 KB 392 9 256:15:13

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 288 288405.3 KB 288 0 000:00:14
2*.lze.lodz.pl 10 145290.4 KB 12 0 001:45:05
3unresolved 326 83152.7 KB 47 5 047:35:18
4*.man.lodz.pl 673 7669.6 KB 36 2 000:00:34
5*.filmschool.lodz.pl 6 1521.6 KB 4 0 001:08:48
6*.cbmm.lodz.pl 2 46.1 KB 2 0 000:01:14
7*.105.135.22.tvsat364.lodz.pl 8 23.5 KB 2 0 003:12:56
8*.zsk.p.lodz.pl 6 10.7 KB 1 2 200:25:08
9*.207.15.62.static.jazztel.es 2 00.0 KB 0 0 000:00:10
10*.p.lodz.pl 1 00.0 KB 0 0 000:02:52
11*.neoplus.adsl.tpnet.pl 1 00.0 KB 0 0 000:00:24
12*.lodz.mm.pl 14 00.0 KB 0 0 002:02:16
13*.toya.net.pl 76 00.0 KB 0 0 000:00:10
TOTAL: 131413 614949.9 KB 392 9 256:15:13

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.191.78.190 0.936 4.328 0.00047:34:11
ip-176.105.135.22.tvsat364.lodz.pl 0.012 0.124 0.00003:12:56
host-89-228-165-199.lodz.mm.pl 0.020 0.192 0.00002:02:16
lze200.lze.lodz.pl 0.040 0.200 0.00001:45:05
proxy.filmschool.lodz.pl 0.244 0.156 0.00001:08:48
pc24.zsk.p.lodz.pl 0.256 0.232 0.00000:25:08
radius2.p.lodz.pl 0.004 0.012 0.00000:02:52
router2.cbmm.lodz.pl 0.004 0.032 0.00000:01:14
212.51.203.199 0.000 0.032 0.00000:01:05
web.man.lodz.pl 0.628 3.864 0.00000:00:26
ecd248.neoplus.adsl.tpnet.pl 0.004 0.016 0.00000:00:24
vegas.cm.p.lodz.pl 0.640 3.936 0.00000:00:14
155.207.15.62.static.jazztel.es 0.000 0.012 0.00000:00:10
f-oper.man.lodz.pl 0.720 3.912 0.00000:00:06
staticline41435.toya.net.pl 0.176 0.980 0.00000:00:05
staticline-31-182-71-141.toya.net.pl 0.012 0.068 0.00000:00:04
david.man.lodz.pl 0.244 1.312 0.00000:00:01
62.29.166.106 0.004 0.012 0.00000:00:01
? 0.004 0.032 0.00000:00:00
TOTAL: 19 3.948 19.452 0.00056:15:13

Curious NNRP server explorers [Top 20]:

SystemConn
f-oper.man.lodz.pl 288
david.man.lodz.pl 96
staticline41435.toya.net.pl 71
host-89-228-165-199.lodz.mm.pl 14
staticline-31-182-71-141.toya.net.pl 5
155.207.15.62.static.jazztel.es 2
212.51.203.199 2
62.29.166.106 1
radius2.p.lodz.pl 1
ecd248.neoplus.adsl.tpnet.pl 1
TOTAL: 10 481

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 3
TOTAL: 1 3

NNRP client timeouts [Top 20]:

SystemConnPeer
ip-176.105.135.22.tvsat364.lodz.pl 6 0
proxy.filmschool.lodz.pl 1 0
TOTAL: 2 7 0

Newsgroup request counts (by category):

CategoryCountPct
1pl 326 53.1%
2lodman 288 46.9%
TOTAL: 2 614100.0%

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

NewsgroupCount
1lodman.info 288
2pl.comp.pecet 119
3pl.misc.telefonia.gsm 34
4pl.misc.kolej 28
5pl.comp.os.ms-windows.winnt 26
6pl.rec.kuchnia 21
7pl.rec.rowery 21
8pl.regionalne.lodz 15
9pl.rec.foto.cyfrowa 15
10pl.soc.prawo 11
11pl.regionalne.warszawa 9
12pl.news.admin 7
13pl.rec.dom 7
14pl.comp.os.linux 4
15pl.comp.os.linux.sieci 3
16pl.rec.mineraly 2
17pl.rec.gory 2
18pl.misc.dieta 1
19pl.test 1
TOTAL: 19 614