Daily Usenet report

May 15 04:15:02 -- May 16 04:15:01

Unknown entries from news log file:

First 17 / 17 lines (100.0%)

May 15 07:55:50 newsfeed nnrpd[30456]: SERVER perl filter not defined
May 15 07:57:31 newsfeed nnrpd[30605]: SERVER perl filter not defined
May 15 09:19:42 newsfeed nnrpd[963]: SERVER perl filter not defined
May 15 09:20:40 newsfeed nnrpd[1033]: SERVER perl filter not defined
May 15 09:22:14 newsfeed nnrpd[1042]: SERVER perl filter not defined
May 15 10:14:05 newsfeed overchan[2297]: input line too long, skipping
May 15 11:19:33 newsfeed nnrpd[5385]: SERVER perl filter not defined
May 15 11:22:12 newsfeed nnrpd[5459]: SERVER perl filter not defined
May 15 11:26:12 newsfeed nnrpd[5694]: SERVER perl filter not defined
May 15 11:36:57 newsfeed innfeed[2455]: nask:0 connect: Network is unreachable
May 15 11:36:57 newsfeed innfeed[2455]: icm:0 connect: Network is unreachable
May 15 13:20:45 newsfeed nnrpd[8703]: SERVER perl filter not defined
May 15 15:39:02 newsfeed overchan[2456]: input line too long, skipping
May 15 15:42:50 newsfeed overchan[2456]: input line too long, skipping
May 15 15:44:50 newsfeed overchan[2456]: input line too long, skipping
May 15 15:58:20 newsfeed nnrpd[13898]: SERVER perl filter not defined
May 15 17:00:05 newsfeed pgpverify[15971]: pgpverify: verification failed

Log entries by program:

Program nameLines%LinesSize%Size
inn 769273 96.7%86.2 MB 96.1%
innd 11198 1.4%1.7 MB 1.9%
innfeed 7711 1.0%1.1 MB 1.2%
nnrpd 7037 0.9%726.1 KB 0.8%
controlchan 4 0.0%0.5 KB 0.0%
overchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 795228 100.0%89.6 MB100.0%

History cache:

ReasonCount%Count
Do not exist 765830 96.1%
Positive hits 16118 2.0%
Negative hits 14792 1.9%
Cache misses 115 0.0%
TOTAL: 4 796855 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.548 0.0%340 0.000 4.553 181.000
article cleanup00:00:08.968 0.0%769665 0.000 0.012 0.045
article logging00:00:24.821 0.0%769804 0.000 0.032 0.101
article parse00:14:23.343 1.0%28201663 0.016 0.031 0.053
article write00:06:03.710 0.4%765711 0.129 0.475 3.388
artlog/artcncl00:00:00.002 0.0%262 0.000 0.008 0.500
data move00:00:25.230 0.0%32684134 0.000 0.001 0.003
hisgrep/artcncl00:00:00.401 0.0%314 0.000 1.277 85.000
hishave/artcncl00:00:00.022 0.0%340 0.000 0.065 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:05:08.515 0.4%798554 0.044 0.386 3.567
history sync00:00:00.036 0.0%536 0.000 0.067 1.000
history write00:12:49.871 0.9%767824 0.142 1.003 6.006
hiswrite/artcncl00:00:00.019 0.0%26 0.000 0.731 16.000
idle21:24:56.103 89.3%24242053 0.530 3.180 48.578
nntp read00:11:07.690 0.8%27779931 0.013 0.024 0.040
overview write00:00:05.137 0.0%765711 0.000 0.007 0.032
perl filter01:35:02.400 6.6%767822 2.286 7.427 11.726
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:01:46.354 0.1%1567466 0.025 0.068 0.178
TOTAL: 23:59:30.68723:52:24.170 99.5%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.128 0.0%36224 0.000 0.004 0.200
article prepare00:00:00.005 0.0%14340 0.000 0.000 0.125
article read00:00:00.318 0.0%3418 0.000 0.093 1.000
backlog stats00:00:00.154 0.0%286334 0.000 0.001 0.015
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.016 0.0%165612 0.000 0.012 0.065
data write00:00:04.323 0.0%132265 0.000 0.033 0.966
idle23:58:04.650100.0%286334 0.000 301.343 30100.000
status file00:00:00.176 0.0%230 0.000 0.765 2.500
TOTAL: 23:58:17.94423:58:11.770100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.010 0.0%56 0.179 0.179 0.179
idle09:24:18.779 99.9%351819 0.000 96.239 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:12.915 0.0%705479 0.000 0.018 2.556
readart00:00:00.053 0.0%424 0.000 0.125 1.294
TOTAL: 09:25:05.11909:24:31.757 99.9%----

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 258 766247 762682 87 3478 99%105:03:37
2news.nask.pl 2 15986 2262 13489 235 14%23:33:39
3news.icm.edu.pl 47 437 20 205 212 4%17:19:56
4localhost 11 10 10 0 0 100%00:00:00
TOTAL: 4 318 782680 764974 13781 3925 97%145:57:12
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl192.8 GB14.2 MB258.1 MB193.1 GB 99%264.3 KB
2news.nask.pl21.3 MB561.8 KB669.6 KB22.5 MB 94%9.2 KB
3news.icm.edu.pl584.3 KB109.7 KB10.3 MB11.0 MB 5%48.6 KB
4localhost15.9 KB0.0 KB0.0 KB15.9 KB100%1.6 KB
TOTAL: 4192.8 GB14.8 MB269.1 MB193.1 GB 99%263.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 15 04:15:02 - 04:59:59 67870 8.9% 25.1620.1 GB 10.4%7808.97
May 15 05:00:00 - 05:59:59 83549 10.9% 23.2126.0 GB 13.5%7562.62
May 15 06:00:00 - 06:59:59 89683 11.7% 24.9128.3 GB 14.7%8244.44
May 15 07:00:00 - 07:59:59 93180 12.2% 25.8828.6 GB 14.8%8336.91
May 15 08:00:00 - 08:59:59 24186 3.2% 6.725.9 GB 3.1%1726.63
May 15 09:00:00 - 09:59:59 10006 1.3% 2.781.6 GB 0.8% 465.00
May 15 10:00:00 - 10:59:59 12868 1.7% 3.571.5 GB 0.8% 447.83
May 15 11:00:00 - 11:59:59 13532 1.8% 3.761.6 GB 0.8% 458.05
May 15 12:00:00 - 12:59:59 12517 1.6% 3.481.6 GB 0.8% 467.98
May 15 13:00:00 - 13:59:59 11491 1.5% 3.191.5 GB 0.8% 425.08
May 15 14:00:00 - 14:59:59 17753 2.3% 4.932.1 GB 1.1% 605.58
May 15 15:00:00 - 15:59:59 16925 2.2% 4.703.0 GB 1.6% 887.43
May 15 16:00:00 - 16:59:59 11860 1.6% 3.292.0 GB 1.0% 578.06
May 15 17:00:00 - 17:59:59 15741 2.1% 4.373.1 GB 1.6% 894.24
May 15 18:00:00 - 18:59:59 13796 1.8% 3.831.7 GB 0.9% 507.85
May 15 19:00:00 - 19:59:59 12249 1.6% 3.401.6 GB 0.8% 454.39
May 15 20:00:00 - 20:59:59 10488 1.4% 2.911.2 GB 0.6% 341.90
May 15 21:00:00 - 21:59:59 13470 1.8% 3.741.9 GB 1.0% 557.21
May 15 22:00:00 - 22:59:59 15219 2.0% 4.232.7 GB 1.4% 776.93
May 15 23:00:00 - 23:59:59 14764 1.9% 4.102.3 GB 1.2% 656.61
May 16 00:00:00 - 00:59:59 65230 8.5% 18.1219.0 GB 9.9%5540.67
May 16 01:00:00 - 01:59:59 64439 8.4% 17.9016.6 GB 8.6%4835.85
May 16 02:00:00 - 02:59:59 42953 5.6% 11.9311.4 GB 5.9%3317.28
May 16 03:00:00 - 03:59:59 27761 3.6% 7.717.2 GB 3.7%2084.44
May 16 04:00:00 - 04:15:01 3388 0.4% 3.76550.8 MB 0.3% 626.04
TOTAL: 23:59:59 764918 100.0% 8.85192.9 GB 100.0%2340.95
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 3614 275 0 1619 14 0 0 0 1706
2news.nask.pl 245 8 0 216 9 0 0 0 12
3news.icm.edu.pl 234 0 0 6 0 0 0 0 228
TOTAL: 3 4093 283 0 1841 23 0 0 0 1946

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 142
it.tlc.cellulari.android 35
comp.mobile.android 26
de.rec.sf.startrek 22
uk.rec.cycling.moderated 13
comp.sys.raspberry-pi 12
it.comp.os.win.windows8 12
de.comp.lang.vbclassic 5
alt.sex.telephones 3
it.comp.os.win.windows7 3
hr.ponuda.hardver.komponente 3
hr.rec.glazba.hifi 2
hr.ponuda.hardver.konfiguracije 2
hr.ponuda.hardver.periferije 1
comp.sys.mac.vintage 1
comp.mobile.ipad 1
TOTAL: 16 283

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

GroupsCount
pl.praca.oferowana 16
pl.sci.matematyka 3
pl.sci.historia 3
bit.listserv.catholic 1
TOTAL: 4 23

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 1640
EMP (md5) 158
HTML post 3
Scoring filter (13) 1
EMP (fsl) 1
Attached HTML file 1
Scoring filter (14) 1
TOTAL: 7 1805

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 2
TOTAL: 1 2
TOTAL: 1 2

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 47930 1717 34125 181 0 0 3%24:00:00
2icm 50115 898 33285 371 0 176 1%23:47:35
3nask 27129 136 14307 215 0 21 0%23:50:36
4poznan 3464 51 2171 12 0 0 1%24:00:01
5okapi 0 0 0 0 0 2328 0%23:40:02
TOTAL: 5 128638 2802 83888 779 0 2525 2%119:18:14
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm151.8 MB63.6 MB215.5 MB2.6 KB/s173.9 KB23:47:35
2panorama18.3 MB918.4 KB19.2 MB0.2 KB/s10.4 KB24:00:00
3poznan8.0 MB1.4 MB9.3 MB0.1 KB/s151.9 KB24:00:01
4nask799.8 KB51.6 MB52.4 MB0.6 KB/s152.8 KB23:50:36
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:40:02
TOTAL: 5178.9 MB117.5 MB296.4 MB0.7 KB/s84.8 KB119:18:14
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1pc-skrzep2.cm.p.lodz.pl 288 368715.0 KB 288 0 000:00:07
2web.man.lodz.pl 288 5646.7 KB 26 0 000:00:32
3isvcs01.man.lodz.pl 10 00.0 KB 0 10 000:00:00
TOTAL: 3 586 424761.7 KB 314 10 009:25:04

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 288 368715.0 KB 288 0 000:00:07
2*.man.lodz.pl 681 5646.7 KB 26 10 000:00:37
3*.elsnet.pl 6 00.0 KB 0 0 000:00:20
4*.neoplus.adsl.tpnet.pl 2 00.0 KB 0 0 000:02:20
5unresolved 5 00.0 KB 0 0 009:21:35
6*.toya.net.pl 33 00.0 KB 0 0 000:00:01
TOTAL: 61015 424761.7 KB 314 10 009:25:04

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
185.48.195.96 17.959 26.525 0.00009:21:35
dld148.neoplus.adsl.tpnet.pl 0.289 0.057 0.00000:02:20
web.man.lodz.pl 1.215 2.486 0.00000:00:32
host227.elsnet.pl 0.010 0.047 0.00000:00:20
pc-skrzep2.cm.p.lodz.pl 1.328 2.150 0.00000:00:07
mon.man.lodz.pl 1.039 2.040 0.00000:00:03
staticline41435.toya.net.pl 0.156 0.214 0.00000:00:01
david.man.lodz.pl 0.356 0.723 0.00000:00:01
isvcs01.man.lodz.pl 0.088 0.087 0.00000:00:00
TOTAL: 9 22.440 34.329 0.00009:25:04

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 287
david.man.lodz.pl 96
staticline41435.toya.net.pl 33
host227.elsnet.pl 6
185.48.195.96 5
dld148.neoplus.adsl.tpnet.pl 2
TOTAL: 6 429

Newsgroup request counts (by category):

CategoryCountPct
1lodman 368 86.8%
2pl 56 13.2%
TOTAL: 2 424100.0%

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

NewsgroupCount
1lodman.info 368
2pl.comp.pecet 40
3pl.comp.os.ms-windows.winnt 16
TOTAL: 3 424