Daily Usenet report

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

Unknown entries from news log file:

First 23 / 23 lines (100.0%)

Oct 10 07:59:11 newsfeed overchan[2219]: input line too long, skipping
Oct 10 08:01:11 newsfeed overchan[2219]: input line too long, skipping
Oct 10 08:19:54 newsfeed overchan[2219]: input line too long, skipping
Oct 10 08:27:56 newsfeed overchan[2219]: input line too long, skipping
Oct 10 10:51:46 newsfeed nnrpd[1723]: SERVER perl filter not defined
Oct 10 10:59:14 newsfeed nnrpd[1811]: SERVER perl filter not defined
Oct 10 15:30:41 newsfeed overchan[2219]: input line too long, skipping
Oct 10 15:42:51 newsfeed nnrpd[10949]: SERVER perl filter not defined
Oct 10 16:04:27 newsfeed nnrpd[11603]: SERVER perl filter not defined
Oct 10 18:46:17 newsfeed overchan[2219]: input line too long, skipping
Oct 10 18:55:41 newsfeed overchan[2219]: input line too long, skipping
Oct 10 19:01:37 newsfeed overchan[2219]: input line too long, skipping
Oct 10 19:07:20 newsfeed nnrpd[17359]: SERVER perl filter not defined
Oct 10 19:48:12 newsfeed overchan[2219]: input line too long, skipping
Oct 10 19:56:52 newsfeed nnrpd[18919]: SERVER perl filter not defined
Oct 10 20:07:33 newsfeed overchan[2219]: input line too long, skipping
Oct 10 20:14:55 newsfeed overchan[2219]: input line too long, skipping
Oct 10 20:25:24 newsfeed overchan[2219]: input line too long, skipping
Oct 10 20:36:40 newsfeed nnrpd[20126]: SERVER perl filter not defined
Oct 10 21:58:36 newsfeed overchan[2219]: input line too long, skipping
Oct 11 01:54:24 newsfeed overchan[2219]: input line too long, skipping
Oct 11 01:55:16 newsfeed overchan[2219]: input line too long, skipping
Oct 11 03:19:26 newsfeed overchan[2219]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2199726 98.4%248.3 MB 98.0%
innd 18655 0.8%3.1 MB 1.2%
nnrpd 10372 0.5%1.1 MB 0.4%
innfeed 6929 0.3%1023.4 KB 0.4%
overchan 16 0.0%2.5 KB 0.0%
TOTAL: 52235698 100.0%253.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2192325 95.3%
Negative hits 69674 3.0%
Positive hits 39104 1.7%
Cache misses 336 0.0%
TOTAL: 4 2301439 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:10.325 0.0%393 0.000 26.272 321.000
article cleanup00:00:30.273 0.0%2199423 0.005 0.014 0.025
article logging00:01:20.406 0.1%2199616 0.016 0.037 0.959
article parse01:08:25.024 4.8%70914151 0.025 0.058 0.095
article write00:24:26.547 1.7%2192213 0.413 0.669 1.451
artlog/artcncl00:00:00.007 0.0%388 0.000 0.018 1.000
artlog/artparse00:00:00.001 0.0%14 0.000 0.071 1.000
data move00:02:07.276 0.1%84067507 0.001 0.002 0.006
hisgrep/artcncl00:00:02.231 0.0%328 0.000 6.802 39.591
hishave/artcncl00:00:01.846 0.0%393 0.000 4.697 275.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup03:33:17.759 14.8%2303517 0.886 5.556 36.107
history sync00:00:00.015 0.0%292 0.000 0.051 1.000
history write04:46:16.586 19.9%2194441 0.148 7.827 37.972
hiswrite/artcncl00:00:00.679 0.0%65 0.000 10.446 107.000
idle07:08:06.688 29.7%34583071 0.233 0.743 3.423
nntp read00:40:24.682 2.8%69120689 0.018 0.035 0.053
overview write00:00:13.710 0.0%2192213 0.001 0.006 0.014
perl filter05:47:55.749 24.2%2194427 6.323 9.513 11.755
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:59.339 0.3%4425788 0.015 0.054 0.078
TOTAL: 24:00:10.37723:37:19.143 98.4%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.163 0.0%41370 0.000 0.004 0.083
article prepare00:00:00.011 0.0%36212 0.000 0.000 0.125
article read00:00:01.295 0.0%8792 0.000 0.147 1.250
backlog stats00:00:00.228 0.0%310644 0.000 0.001 0.019
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.157 0.0%180706 0.000 0.012 1.000
data write00:00:06.119 0.0%159293 0.000 0.038 0.189
idle23:59:54.727100.0%310644 0.000 278.115 17597.000
status file00:00:01.392 0.0%215 0.000 6.474 286.500
TOTAL: 24:00:13.10924:00:06.092100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle51:29:12.202100.0%24424 0.000 7588.937600055.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.919 0.0%51390 0.000 0.018 2.500
readart00:00:00.920 0.0%554 0.000 1.661 21.000
TOTAL: 51:29:56.46451:29:14.041100.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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 905 21860782180378 1202 4498 99%422:48:50
2news.task.gda.pl 11 31181 6277 23594 1310 20%47:05:33
3news.nask.pl 2 16762 5324 9989 1449 31%23:47:24
4localhost 9 8 8 0 0 100%00:00:00
5news.icm.edu.pl 44 240 5 213 22 2%18:26:50
TOTAL: 5 971 22342692191992 34998 7279 98%512:08:37
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl801.7 GB22.9 MB60.2 MB801.7 GB 99%384.8 KB
2news.nask.pl19.6 MB3.7 MB10.6 MB33.9 MB 57%5.1 KB
3news.task.gda.pl13.5 MB67.6 KB4.2 MB17.8 MB 76%2.4 KB
4news.icm.edu.pl191.2 KB16.4 KB684.7 KB892.2 KB 21%33.0 KB
5localhost11.4 KB0.0 KB0.0 KB11.4 KB100%1.4 KB
TOTAL: 5801.7 GB26.7 MB75.7 MB801.8 GB 99%382.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 10 04:15:03 - 04:59:59 70427 3.2% 26.1126.0 GB 3.3%10122.52
Oct 10 05:00:00 - 05:59:59 90180 4.1% 25.0533.5 GB 4.2%9770.26
Oct 10 06:00:00 - 06:59:59 88629 4.0% 24.6234.8 GB 4.3%10138.23
Oct 10 07:00:00 - 07:59:59 92638 4.2% 25.7335.4 GB 4.4%10316.55
Oct 10 08:00:00 - 08:59:59 75188 3.4% 20.8929.0 GB 3.6%8457.71
Oct 10 09:00:00 - 09:59:59 95675 4.4% 26.5837.2 GB 4.6%10832.86
Oct 10 10:00:00 - 10:59:59 88023 4.0% 24.4534.6 GB 4.3%10080.24
Oct 10 11:00:00 - 11:59:59 87531 4.0% 24.3134.6 GB 4.3%10079.10
Oct 10 12:00:00 - 12:59:59 84714 3.9% 23.5332.8 GB 4.1%9563.79
Oct 10 13:00:00 - 13:59:59 87789 4.0% 24.3933.3 GB 4.2%9692.74
Oct 10 14:00:00 - 14:59:59 92294 4.2% 25.6432.9 GB 4.1%9585.06
Oct 10 15:00:00 - 15:59:59 94352 4.3% 26.2133.5 GB 4.2%9769.11
Oct 10 16:00:00 - 16:59:59 85675 3.9% 23.8030.4 GB 3.8%8841.54
Oct 10 17:00:00 - 17:59:59 95262 4.3% 26.4634.8 GB 4.3%10137.37
Oct 10 18:00:00 - 18:59:59 100723 4.6% 27.9834.5 GB 4.3%10042.68
Oct 10 19:00:00 - 19:59:59 99644 4.5% 27.6834.0 GB 4.2%9898.30
Oct 10 20:00:00 - 20:59:59 96360 4.4% 26.7734.5 GB 4.3%10039.27
Oct 10 21:00:00 - 21:59:59 75896 3.5% 21.0826.6 GB 3.3%7735.53
Oct 10 22:00:00 - 22:59:59 99342 4.5% 27.5934.7 GB 4.3%10115.24
Oct 10 23:00:00 - 23:59:59 100139 4.6% 27.8234.5 GB 4.3%10045.11
Oct 11 00:00:00 - 00:59:59 80699 3.7% 22.4228.4 GB 3.5%8264.19
Oct 11 01:00:00 - 01:59:59 89854 4.1% 24.9632.1 GB 4.0%9356.62
Oct 11 02:00:00 - 02:59:59 106055 4.8% 29.4637.5 GB 4.7%10910.62
Oct 11 03:00:00 - 03:59:59 93788 4.3% 26.0533.8 GB 4.2%9851.95
Oct 11 04:00:00 - 04:15:02 21046 1.0% 23.337.6 GB 1.0%8861.71
TOTAL: 23:59:59 2191923 100.0% 25.37801.1 GB 100.0%9722.11
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 4666 268 0 3579 0 0 0 0 819
2news.nask.pl 1472 2 0 1378 0 0 0 0 92
3news.task.gda.pl 1254 1173 0 36 0 0 0 0 45
4news.icm.edu.pl 23 0 0 1 0 0 0 0 22
TOTAL: 4 7415 1443 0 4994 0 0 0 0 978

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 133
mailing.freebsd.svn-ports-all 90
mailing.freebsd.svn-ports-head 90
mailing.freebsd.current 69
mailing.gcc.gcc-cvs 65
mozilla.dev.platform 62
mailing.openbsd.source-changes 57
alt.windows7.general 55
mailing.openbsd.misc 47
mailing.openbsd.ports-changes 38
mailing.freebsd.ports-bugs 38
gmane.linux.alsa.devel 35
mailing.database.pgsql-general 35
mailing.unix.samba 34
mailing.freebsd.svn-src-all 32
fa.freebsd.cvs-all 31
mailing.freebsd.ports 28
it.tlc.cellulari.android 28
uk.rec.cycling.moderated 27
mailing.www.horde-general 25
TOTAL: 81 1443

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 622
Attached HTML file 81
EMP (md5) 40
EMP (phl) 10
HTML post 8
EMP (fsl) 6
Scoring filter (8) 3
Scoring filter (12) 1
TOTAL: 8 771

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 43081 2928 37408 958 0 0 6%23:56:34
2icm 59355 1358 33096 3618 0 4 2%23:57:08
3poznan 13271 68 11323 43 0 0 0%24:02:52
4task 2668 31 1435 6 0 3 1%21:35:11
5nask 18611 14 13276 1 0 0 0%24:00:00
6okapi 0 0 0 0 0 2996 0%24:00:01
TOTAL: 6 136986 4399 96538 4626 0 3003 3%141:31:46
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm23.3 MB1.4 GB1.4 GB17.0 KB/s294.9 KB23:57:08
2panorama8.4 MB3.9 MB12.3 MB0.1 KB/s3.2 KB23:56:34
3poznan195.7 KB82.5 KB278.2 KB0.0 KB/s2.5 KB24:02:52
4task62.2 KB9.2 KB71.4 KB0.0 KB/s1.9 KB21:35:11
5nask29.4 KB1.9 KB31.3 KB0.0 KB/s2.1 KB24:00:00
6okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:01
TOTAL: 632.0 MB1.4 GB1.4 GB2.9 KB/s164.0 KB141:31:46
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1vegas.cm.p.lodz.pl 288 336561.9 KB 288 0 000:00:09
2web.man.lodz.pl 288 10494.4 KB 47 0 000:00:35
3212.191.78.190 294 77138.0 KB 29 4 047:37:14
4lze200.lze.lodz.pl 6 3463.4 KB 6 0 001:28:13
5ip-176.105.135.22.tvsat364.lodz.pl 5 22.0 KB 1 0 001:36:51
6router2.cbmm.lodz.pl 2 11.6 KB 1 0 000:07:02
7kujawiak.man.lodz.pl 4 00.0 KB 0 4 000:00:00
TOTAL: 7 887 554861.3 KB 372 8 051:29:55

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.cm.p.lodz.pl 288 336561.9 KB 288 0 000:00:09
2*.man.lodz.pl 676 10494.4 KB 47 4 000:00:46
3unresolved 296 77138.0 KB 29 4 047:37:23
4*.lze.lodz.pl 6 3463.4 KB 6 0 001:28:13
5*.105.135.22.tvsat364.lodz.pl 5 22.0 KB 1 0 001:36:51
6*.cbmm.lodz.pl 2 11.6 KB 1 0 000:07:02
7*.191.14.62.static.jazztel.es 4 00.0 KB 0 0 000:38:44
8*.neoplus.adsl.tpnet.pl 1 00.0 KB 0 0 000:00:34
9*.internetdsl.tpnet.pl 2 00.0 KB 0 0 000:00:00
10*.toya.net.pl 96 00.0 KB 0 0 000:00:07
TOTAL: 101376 554861.3 KB 372 8 051:29:55

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
212.191.78.190 0.756 4.576 0.00047:37:14
ip-176.105.135.22.tvsat364.lodz.pl 0.036 0.056 0.00001:36:51
lze200.lze.lodz.pl 0.016 0.124 0.00001:28:13
16.191.14.62.static.jazztel.es 1.112 1.688 0.00000:38:44
router2.cbmm.lodz.pl 0.008 0.024 0.00000:07:02
web.man.lodz.pl 0.824 4.408 0.00000:00:35
ebq75.neoplus.adsl.tpnet.pl 0.004 0.012 0.00000:00:34
f-oper-v100.man.lodz.pl 0.656 5.036 0.00000:00:09
vegas.cm.p.lodz.pl 0.632 4.536 0.00000:00:09
212.51.203.199 0.000 0.032 0.00000:00:08
staticline41435.toya.net.pl 0.232 1.464 0.00000:00:07
david.man.lodz.pl 0.164 1.396 0.00000:00:01
kujawiak.man.lodz.pl 0.024 0.084 0.00000:00:00
gcq193.internetdsl.tpnet.pl 0.012 0.028 0.00000:00:00
? 0.004 0.008 0.00000:00:00
TOTAL: 15 4.480 23.472 0.00051:29:55

Curious NNRP server explorers [Top 20]:

SystemConn
f-oper-v100.man.lodz.pl 288
staticline41435.toya.net.pl 96
david.man.lodz.pl 96
16.191.14.62.static.jazztel.es 4
gcq193.internetdsl.tpnet.pl 2
212.51.203.199 2
ebq75.neoplus.adsl.tpnet.pl 1
TOTAL: 7 489

NNRP gethostbyaddr failures [Top 20]:

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

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1lodman 336 60.6%
2pl 217 39.2%
3alt 1 0.2%
TOTAL: 3 554100.0%

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

NewsgroupCount
1lodman.info 336
2pl.comp.pecet 74
3pl.rec.rowery 40
4pl.comp.os.ms-windows.winnt 28
5pl.misc.kolej 27
6pl.rec.dom 13
7pl.misc.telefonia.gsm 10
8pl.rec.kuchnia 8
9pl.regionalne.lodz 8
10pl.rec.gory 3
11pl.soc.prawo 2
12pl.internet.polip 2
13pl.praca.dyskusje 1
14alt.pl.praca.oferowana 1
15pl.comp.os.linux 1
TOTAL: 15 554