Daily Usenet report

Apr 15 04:15:02 -- Apr 16 04:15:03

Unknown entries from news log file:

First 10 / 10 lines (100.0%)

Apr 15 10:09:54 newsfeed nnrpd[22579]: SERVER perl filter not defined
Apr 15 11:11:06 newsfeed controlchan[2324]: control_checkgroups, ^.*$ !ka.lists #20140415 usenet@karlsruhe.org usenet@karlsruhe.org @03044255463035000000054FCEF90000006C@, , , utf-8, news.task.gda.pl, mail, 1
Apr 15 11:11:06 newsfeed controlchan[2324]: control_checkgroups, ^.*$ !ka.lists #20140415 usenet@karlsruhe.org usenet@karlsruhe.org @03044255463035000000054FCEF90000006C@, ^.*$, , utf-8, news.task.gda.pl, drop, -1
Apr 15 12:04:20 newsfeed innfeed[2325]: icm:0 connect: Network is unreachable
Apr 15 12:10:20 newsfeed innfeed[2325]: icm:0 connect: Network is unreachable
Apr 15 13:14:19 newsfeed nnrpd[28842]: SERVER perl filter not defined
Apr 15 13:54:05 newsfeed nnrpd[30162]: SERVER perl filter not defined
Apr 15 13:55:17 newsfeed nnrpd[30206]: SERVER perl filter not defined
Apr 15 15:48:24 newsfeed innfeed[2325]: icm:0 connect: Network is unreachable
Apr 15 17:00:07 newsfeed pgpverify[3995]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn1388359 97.9%157.9 MB 97.4%
innd 13470 1.0%2.3 MB 1.4%
nnrpd 8119 0.6%870.1 KB 0.5%
innfeed 7478 0.5%1.1 MB 0.7%
controlchan 5 0.0%0.7 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 61417432 100.0%162.2 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1380334 93.1%
Negative hits 59108 4.0%
Positive hits 42313 2.9%
Cache misses 517 0.0%
TOTAL: 4 1482272 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:22.756 0.0%730 0.000 31.173 928.000
article cleanup00:00:25.534 0.0%1388055 0.002 0.018 0.039
article logging00:00:50.248 0.1%1388186 0.012 0.036 0.657
article parse01:01:38.864 4.3%133937571 0.003 0.028 0.082
article write00:14:35.767 1.0%1380024 0.232 0.635 2.051
artlog/artcncl00:00:00.017 0.0%714 0.000 0.024 1.000
artlog/artparse00:00:00.000 0.0%4 0.000 0.000 0.000
data move00:03:25.241 0.2%143152397 0.000 0.001 0.030
hisgrep/artcncl00:00:03.630 0.0%414 0.000 8.768 249.000
hishave/artcncl00:00:02.358 0.0%730 0.000 3.230 201.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:10:34.575 9.1%1484259 0.009 5.278 13.465
history sync00:00:00.020 0.0%292 0.000 0.068 1.000
history write02:14:39.079 9.3%1382311 0.503 5.845 17.844
hiswrite/artcncl00:00:01.654 0.0%316 0.000 5.234 273.000
idle13:15:20.381 55.2%92291170 0.175 0.517 1.241
nntp read00:52:34.474 3.6%132845092 0.005 0.024 0.045
overview write00:00:09.499 0.0%1380024 0.000 0.007 0.027
perl filter03:27:08.609 14.4%1382307 3.856 8.991 13.024
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:06.324 0.2%2803255 0.015 0.066 0.145
TOTAL: 24:00:25.67923:24:59.030 97.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.199 0.0%62118 0.000 0.003 0.125
article prepare00:00:00.023 0.0%63638 0.000 0.000 0.083
article read00:01:13.105 0.1%15755 0.000 4.640 161.750
backlog stats00:00:00.316 0.0%363054 0.000 0.001 0.016
callbacks00:00:00.001 0.0%45 0.000 0.022 0.056
data read00:00:02.465 0.0%209128 0.000 0.012 2.000
data write00:00:10.058 0.0%183543 0.000 0.055 6.000
idle23:58:27.895 99.9%363054 0.000 237.727 18087.000
status file00:00:01.571 0.0%289 0.000 5.436 170.000
TOTAL: 24:00:04.83223:59:55.633100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:24.165 0.1%3797 1.667 6.364 10.871
idle11:12:28.570 99.8%9422 0.000 4282.378901791.400
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.750 0.0%20972 0.000 0.036 7.938
readart00:00:03.600 0.0%1246 0.000 2.889 261.000
TOTAL: 11:13:34.41411:12:57.085 99.9%----

Control commands to INND:

CommandNumber
L 1
flush 24
flushlogs 2
go 2
logmode 2
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 190

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 573 13750221368186 1470 5366 99%294:19:21
2news.task.gda.pl 12 29632 6564 21622 1446 22%44:52:04
3news.nask.pl 2 18121 4392 12584 1145 24%23:46:04
4localhost 5 4 4 0 0 100%00:00:00
5news.icm.edu.pl 40 308 3 253 52 0%18:39:28
TOTAL: 5 632 14230871379149 35929 8009 96%381:36:57
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl502.8 GB821.3 MB847.3 MB504.4 GB 99%385.1 KB
2news.nask.pl22.1 MB3.5 MB3.5 MB29.1 MB 75%5.4 KB
3news.task.gda.pl13.8 MB48.9 KB4.0 MB17.9 MB 77%2.3 KB
4news.icm.edu.pl78.4 KB0.0 KB536.0 KB614.4 KB 12%11.2 KB
5localhost10.7 KB0.0 KB0.0 KB10.7 KB100%2.7 KB
TOTAL: 5502.8 GB824.8 MB855.4 MB504.5 GB 99%381.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 15 04:15:02 - 04:59:59 54743 4.0% 20.2922.0 GB 4.4%8568.36
Apr 15 05:00:00 - 05:59:59 74203 5.4% 20.6130.4 GB 6.1%8860.20
Apr 15 06:00:00 - 06:59:59 91160 6.6% 25.3231.2 GB 6.2%9096.94
Apr 15 07:00:00 - 07:59:59 71812 5.2% 19.9530.4 GB 6.0%8842.67
Apr 15 08:00:00 - 08:59:59 73854 5.4% 20.5230.3 GB 6.0%8825.71
Apr 15 09:00:00 - 09:59:59 79039 5.7% 21.9630.0 GB 6.0%8740.14
Apr 15 10:00:00 - 10:59:59 59855 4.3% 16.6320.5 GB 4.1%5980.94
Apr 15 11:00:00 - 11:59:59 61901 4.5% 17.1922.0 GB 4.4%6420.97
Apr 15 12:00:00 - 12:59:59 44268 3.2% 12.3016.5 GB 3.3%4793.70
Apr 15 13:00:00 - 13:59:59 38889 2.8% 10.8013.9 GB 2.8%4039.41
Apr 15 14:00:00 - 14:59:59 46557 3.4% 12.9317.0 GB 3.4%4947.99
Apr 15 15:00:00 - 15:59:59 41120 3.0% 11.4215.4 GB 3.1%4494.50
Apr 15 16:00:00 - 16:59:59 43627 3.2% 12.1215.3 GB 3.0%4453.49
Apr 15 17:00:00 - 17:59:59 39286 2.8% 10.9113.2 GB 2.6%3844.23
Apr 15 18:00:00 - 18:59:59 43665 3.2% 12.1314.8 GB 2.9%4319.06
Apr 15 19:00:00 - 19:59:59 41131 3.0% 11.4314.5 GB 2.9%4210.44
Apr 15 20:00:00 - 20:59:59 35888 2.6% 9.9712.5 GB 2.5%3628.53
Apr 15 21:00:00 - 21:59:59 40222 2.9% 11.1713.2 GB 2.6%3849.68
Apr 15 22:00:00 - 22:59:59 38955 2.8% 10.8213.8 GB 2.7%4019.74
Apr 15 23:00:00 - 23:59:59 36930 2.7% 10.2613.2 GB 2.6%3833.41
Apr 16 00:00:00 - 00:59:59 70872 5.1% 19.6922.0 GB 4.4%6400.36
Apr 16 01:00:00 - 01:59:59 72476 5.3% 20.1326.0 GB 5.2%7587.56
Apr 16 02:00:00 - 02:59:59 77744 5.6% 21.6027.6 GB 5.5%8036.15
Apr 16 03:00:00 - 03:59:59 81663 5.9% 22.6829.2 GB 5.8%8512.85
Apr 16 04:00:00 - 04:15:03 19616 1.4% 21.727.7 GB 1.5%8981.06
TOTAL: 24:00:01 1379476 100.0% 15.97502.7 GB 100.0%6100.47
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 5487 257 0 4588 2 0 0 0 640
2news.task.gda.pl 1463 1110 0 31 0 0 0 0 322
3news.nask.pl 1167 15 0 1131 2 0 0 0 19
4news.icm.edu.pl 52 0 0 0 0 0 0 0 52
TOTAL: 4 8169 1382 0 5750 4 0 0 0 1033

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 129
24hoursupport.helpdesk 91
mailing.openbsd.source-changes 85
mailing.freebsd.ports-bugs 83
mailing.freebsd.svn-ports-all 75
mailing.freebsd.svn-ports-head 74
alt.windows7.general 73
mozilla.dev.platform 57
mailing.openbsd.ports-changes 49
mailing.gcc.gcc-cvs 40
mailing.freebsd.svn-src-all 35
fa.freebsd.cvs-all 35
mozilla.dev.gaia 30
it.tlc.cellulari.android 29
mailing.database.pgsql-general 28
it.comp.os.win.windows8 26
mozilla.dev.tree-management 26
comp.mobile.android 24
mailing.openssl.dev 21
mailing.openbsd.misc 21
TOTAL: 80 1382

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

GroupsCount
pl.sci.historia 2
bit.listserv.catholic 1
pl.sci.matematyka 1
TOTAL: 3 4

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 534
EMP (fsl) 175
EMP (phl) 119
EMP (md5) 55
HTML post 9
Excessively crossposted test article 4
Scoring filter (8) 2
TOTAL: 7 898

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 80650 8468 46989 5896 56 2663 10%23:59:43
2panorama 44420 1535 41468 229 0 0 3%23:57:12
3task 1699 48 856 12 0 0 2%21:48:07
4poznan 12340 27 10729 8 0 0 0%23:56:55
5nask 25868 10 17445 1 0 1 0%23:57:54
6okapi 0 0 0 0 0 2526 0%24:00:05
TOTAL: 6 164977 10088 117487 6146 56 5190 6%141:39:56
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm904.9 MB709.2 MB1.6 GB19.1 KB/s115.1 KB23:59:43
2panorama8.5 MB836.9 KB9.3 MB0.1 KB/s5.4 KB23:57:12
3task105.4 KB26.1 KB131.4 KB0.0 KB/s2.2 KB21:48:07
4poznan70.4 KB15.5 KB85.9 KB0.0 KB/s2.5 KB23:56:55
5nask42.5 KB1.6 KB44.0 KB0.0 KB/s4.0 KB23:57:54
6okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:05
TOTAL: 6913.6 MB710.0 MB1.6 GB3.3 KB/s102.4 KB141:39:56
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1pc-skrzep2.cm.p.lodz.pl 288 326852.1 KB 288 0 000:00:15
2lze200.lze.lodz.pl 11 79159.1 KB 10 0 000:43:26
3web.man.lodz.pl 288 4438.4 KB 21 0 000:00:25
4pc225.geo.uni.lodz.pl 1 1835.2 KB 2 0 000:35:50
5terminal-3-191.retsat1.com.pl 4 65.2 KB 2 0 200:23:52
6router2.cbmm.lodz.pl 1 610.1 KB 1 0 000:01:45
7ip-176.105.135.22.tvsat364.lodz.pl 1 22.6 KB 1 0 000:27:22
8isvcs01.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 8 598 4811.1 MB 325 4 211:13:32

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 288 326852.1 KB 288 0 000:00:15
2*.lze.lodz.pl 11 79159.1 KB 10 0 000:43:26
3*.man.lodz.pl 676 4438.4 KB 21 4 000:00:32
4*.geo.uni.lodz.pl 1 1835.2 KB 2 0 000:35:50
5*.retsat1.com.pl 4 65.2 KB 2 0 200:23:52
6*.cbmm.lodz.pl 1 610.1 KB 1 0 000:01:45
7*.105.135.22.tvsat364.lodz.pl 1 22.6 KB 1 0 000:27:22
8*.pa.nsw.optusnet.com.au 166 00.0 KB 0 0 000:07:41
9*.wroclaw.mm.pl 38 00.0 KB 0 0 005:55:33
10unresolved 2 00.0 KB 0 0 000:00:27
11*.neoplus.adsl.tpnet.pl 1 00.0 KB 0 0 000:25:13
12*.206.15.62.static.jazztel.es 17 00.0 KB 0 0 002:31:28
13*.toya.net.pl 55 00.0 KB 0 0 000:00:04
TOTAL: 131261 4811.1 MB 325 4 211:13:32

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
host-89-231-50-15.wroclaw.mm.pl 0.088 0.364 0.00005:55:33
160.206.15.62.static.jazztel.es 0.288 1.036 0.00002:31:28
lze200.lze.lodz.pl 0.032 0.148 0.00000:43:26
pc225.geo.uni.lodz.pl 0.012 0.040 0.00000:35:50
ip-176.105.135.22.tvsat364.lodz.pl 0.008 0.008 0.00000:27:22
bpv18.neoplus.adsl.tpnet.pl 0.008 0.008 0.00000:25:13
terminal-3-191.retsat1.com.pl 0.020 0.064 0.00000:23:52
pa49-181-246-124.pa.nsw.optusnet.com.au 0.408 1.432 0.00000:07:41
router2.cbmm.lodz.pl 0.000 0.016 0.00000:01:45
212.51.203.199 0.008 0.024 0.00000:00:27
web.man.lodz.pl 0.728 3.084 0.00000:00:25
pc-skrzep2.cm.p.lodz.pl 0.720 2.764 0.00000:00:15
mon.man.lodz.pl 0.680 2.604 0.00000:00:04
staticline41435.toya.net.pl 0.132 0.504 0.00000:00:04
david.man.lodz.pl 0.264 0.828 0.00000:00:01
isvcs01.man.lodz.pl 0.012 0.052 0.00000:00:00
? 0.000 0.008 0.00000:00:00
TOTAL: 17 3.408 12.984 0.00011:13:32

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
pa49-181-246-124.pa.nsw.optusnet.com.au 166
david.man.lodz.pl 96
staticline41435.toya.net.pl 55
host-89-231-50-15.wroclaw.mm.pl 38
160.206.15.62.static.jazztel.es 17
212.51.203.199 2
bpv18.neoplus.adsl.tpnet.pl 1
TOTAL: 8 663

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

SystemConnPeer
160.206.15.62.static.jazztel.es 3 0
pc225.geo.uni.lodz.pl 1 0
TOTAL: 2 4 0

Newsgroup request counts (by category):

CategoryCountPct
1lodman 326 67.8%
2pl 149 31.0%
3lodz 6 1.2%
TOTAL: 3 481100.0%

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

NewsgroupCount
1lodman.info 326
2pl.comp.pecet 91
3pl.rec.kuchnia 23
4pl.rec.rowery 17
5pl.regionalne.lodz 15
6lodz.test 6
7pl.comp.os.ms-windows.winnt 2
8pl.rec.muzyka.gitara 1
TOTAL: 8 481