Daily Usenet report

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

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Oct 8 08:48:05 newsfeed overchan[20186]: input line too long, skipping
Oct 8 09:04:25 newsfeed nnrpd[31666]: SERVER perl filter not defined
Oct 8 11:25:04 newsfeed overchan[20186]: input line too long, skipping
Oct 8 12:36:59 newsfeed overchan[20186]: input line too long, skipping
Oct 8 16:39:01 newsfeed overchan[20186]: input line too long, skipping
Oct 8 19:19:19 newsfeed overchan[20186]: input line too long, skipping
Oct 8 19:44:14 newsfeed overchan[20186]: input line too long, skipping
Oct 8 20:00:18 newsfeed overchan[20186]: input line too long, skipping
Oct 8 20:43:32 newsfeed overchan[20186]: input line too long, skipping
Oct 9 00:06:46 newsfeed overchan[20186]: input line too long, skipping
Oct 9 02:17:59 newsfeed overchan[20186]: input line too long, skipping
Oct 9 02:35:17 newsfeed overchan[20186]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2486973 97.5%292.0 MB 97.2%
innfeed 41629 1.6%4.9 MB 1.6%
innd 18132 0.7%2.9 MB 1.0%
nnrpd 5052 0.2%539.6 KB 0.2%
overchan 12 0.0%1.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62551799 100.0%300.4 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2469940 89.4%
Positive hits 194229 7.0%
Negative hits 94953 3.4%
Cache misses 2430 0.1%
TOTAL: 4 2761552 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.361 0.0%84 0.000 28.107 546.000
article cleanup00:00:37.463 0.0%2488211 0.010 0.015 0.020
article logging00:02:13.599 0.2%2488638 0.037 0.054 0.094
article parse00:55:20.475 3.8%83548529 0.019 0.040 0.052
article write00:20:43.539 1.4%2473245 0.252 0.503 0.855
artlog/artcncl00:00:00.003 0.0%83 0.000 0.036 1.000
artlog/artparse00:00:00.000 0.0%2 0.000 0.000 0.000
data move00:03:33.172 0.2%97786488 0.001 0.002 0.004
hisgrep/artcncl00:00:00.375 0.0%78 0.000 4.808 65.000
hishave/artcncl00:00:00.182 0.0%84 0.000 2.167 20.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup01:44:49.836 7.3%2769250 0.268 2.271 8.020
history sync00:00:00.017 0.0%290 0.000 0.059 0.667
history write04:12:57.553 17.6%2476146 1.437 6.130 14.653
hiswrite/artcncl00:00:00.059 0.0%6 0.000 9.833 20.000
idle09:30:49.457 39.6%54329749 0.125 0.630 1.821
nntp read01:14:05.027 5.1%81778834 0.033 0.054 0.079
overview write00:00:14.700 0.0%2473245 0.004 0.006 0.009
perl filter05:19:11.323 22.2%2476144 5.593 7.734 11.388
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:10.872 0.4%5211545 0.050 0.071 0.089
TOTAL: 24:00:03.74023:30:50.013 98.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.654 0.0%497851 0.001 0.003 0.007
article prepare00:00:00.103 0.0%328514 0.000 0.000 0.005
article read00:04:53.252 0.3%72215 0.302 4.061 36.707
backlog stats00:00:02.353 0.0%1942472 0.000 0.001 0.002
callbacks00:00:00.000 0.0%5 0.000 0.000 0.000
data read00:00:24.972 0.0%993671 0.011 0.025 0.041
data write00:04:54.268 0.3%1030426 0.062 0.286 0.530
idle23:38:33.608 99.2%1942472 21.706 43.817 264.490
status file00:00:31.345 0.0%16398 1.007 1.912 7.798
TOTAL: 23:50:26.88023:49:21.555 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:11:29.049 99.5%3329 0.000 1288.390 52215.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.815 0.0%7548 0.000 0.108 3.111
readart00:00:00.470 0.0%87 0.000 5.402 22.000
TOTAL: 01:11:49.41301:11:30.334 99.6%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 192

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 605 24676952461516 2169 4010 99%514:02:48
2news.nask.pl 2 204629 10413 183249 10967 5%24:07:36
3news.icm.edu.pl 51 245 111 99 35 45%16:24:55
4localhost 2 1 1 0 0 100%00:00:00
TOTAL: 4 660 26725702472041 185517 15012 92%554:35:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl750.7 GB676.8 MB888.4 MB752.2 GB 99%319.9 KB
2news.nask.pl3.3 GB4.0 GB4.0 GB11.4 GB 28%556.8 KB
3news.icm.edu.pl2.0 MB106.4 KB678.3 KB2.8 MB 72%19.7 KB
4localhost1.3 KB0.0 KB0.0 KB1.3 KB100%1.3 KB
TOTAL: 4753.9 GB4.7 GB4.9 GB763.5 GB 98%321.9 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 8 04:15:03 - 04:59:59 68145 2.8% 25.2724.0 GB 3.2%9337.69
Oct 8 05:00:00 - 05:59:59 112420 4.5% 31.2339.7 GB 5.3%11569.85
Oct 8 06:00:00 - 06:59:59 103134 4.2% 28.6540.8 GB 5.4%11873.96
Oct 8 07:00:00 - 07:59:59 112964 4.6% 31.3839.6 GB 5.3%11539.51
Oct 8 08:00:00 - 08:59:59 110963 4.5% 30.8240.9 GB 5.4%11911.94
Oct 8 09:00:00 - 09:59:59 107072 4.3% 29.7438.1 GB 5.1%11099.62
Oct 8 10:00:00 - 10:59:59 94386 3.8% 26.2235.2 GB 4.7%10239.45
Oct 8 11:00:00 - 11:59:59 85973 3.5% 23.8829.9 GB 4.0%8701.34
Oct 8 12:00:00 - 12:59:59 86221 3.5% 23.9527.9 GB 3.7%8112.66
Oct 8 13:00:00 - 13:59:59 89875 3.6% 24.9725.7 GB 3.4%7489.52
Oct 8 14:00:00 - 14:59:59 91222 3.7% 25.3431.2 GB 4.1%9090.37
Oct 8 15:00:00 - 15:59:59 79565 3.2% 22.1026.5 GB 3.5%7730.76
Oct 8 16:00:00 - 16:59:59 79693 3.2% 22.1427.5 GB 3.7%8017.38
Oct 8 17:00:00 - 17:59:59 108552 4.4% 30.1527.2 GB 3.6%7921.87
Oct 8 18:00:00 - 18:59:59 117102 4.7% 32.5331.0 GB 4.1%9032.44
Oct 8 19:00:00 - 19:59:59 107662 4.4% 29.9134.7 GB 4.6%10106.24
Oct 8 20:00:00 - 20:59:59 105476 4.3% 29.3023.6 GB 3.1%6885.97
Oct 8 21:00:00 - 21:59:59 129360 5.2% 35.9337.3 GB 4.9%10868.08
Oct 8 22:00:00 - 22:59:59 136894 5.5% 38.0335.0 GB 4.6%10186.06
Oct 8 23:00:00 - 23:59:59 144831 5.9% 40.2331.3 GB 4.1%9107.23
Oct 9 00:00:00 - 00:59:59 129594 5.2% 36.0027.7 GB 3.7%8081.70
Oct 9 01:00:00 - 01:59:59 110277 4.5% 30.6326.3 GB 3.5%7657.26
Oct 9 02:00:00 - 02:59:59 89851 3.6% 24.9625.9 GB 3.4%7538.21
Oct 9 03:00:00 - 03:59:59 60699 2.5% 16.8623.1 GB 3.1%6717.37
Oct 9 04:00:00 - 04:15:02 9581 0.4% 10.623.9 GB 0.5%4525.47
TOTAL: 23:59:59 2471512 100.0% 28.61754.0 GB 100.0%9150.45
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 10991 11 0 8869 2 0 0 0 2109
2newsfeed.pionier.net.pl 4352 257 0 3187 4 0 0 0 904
3news.icm.edu.pl 35 0 0 7 0 0 0 0 28
TOTAL: 3 15378 268 0 12063 6 0 0 0 3041

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 86
it.comp.os.win.windows10 60
it.tlc.cellulari.android 46
comp.mobile.android 29
nl.comp.sys.android 20
it.comp.os.win.windows7 16
comp.sys.raspberry-pi 7
uk.rec.cycling.moderated 1
alt.binaries.novarip 1
de.rec.sf.startrek 1
it.discussioni.energia 1
TOTAL: 11 268

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

GroupsCount
pl.sci.matematyka 4
bit.listserv.catholic 1
pl.praca.oferowana 1
TOTAL: 3 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1944
Binary in non-binary group 604
EMP (phl) 26
Scoring filter (16) 10
Scoring filter (10) 8
Too many newsgroups 6
Scoring filter (8) 6
HTML post 3
Scoring filter (9) 3
Scoring filter (18) 1
EMP (fsl) 1
TOTAL: 11 2612

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 341
news.nask.pl 86
TOTAL: 2 427
TOTAL: 1 427

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 366612 45990 199472 2246 0 2008 12%23:59:57
2panorama 257389 8526 221329 393 7 268042 3%23:59:57
3poznan 5585 18 4098 20 0 0 0%23:50:42
4icm 296226 10 239735 24563 0 0 0%23:59:57
5okapi 0 0 0 0 0 895 0%23:57:50
TOTAL: 5 925812 54544 664634 27222 7 270945 5%119:48:23
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask23.1 GB1.3 GB24.4 GB296.7 KB/s531.4 KB23:59:57
2panorama3.8 GB30.2 MB3.9 GB46.9 KB/s454.3 KB23:59:57
3icm2.0 MB12.0 GB12.0 GB145.2 KB/s510.5 KB23:59:57
4poznan72.6 KB79.2 KB151.8 KB0.0 KB/s4.0 KB23:50:42
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 526.9 GB13.3 GB40.3 GB97.9 KB/s516.5 KB119:48:23
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama1.4 MB
TOTAL: 11.4 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1m225.class145.petrotel.pl 19 5355.7 KB 17 0 1001:10:58
2web.man.lodz.pl 288 3437.2 KB 14 0 000:00:25
3isvcs01.man.lodz.pl 1 00.0 KB 0 1 000:00:00
TOTAL: 3 308 8792.9 KB 31 1 1001:11:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.class145.petrotel.pl 19 5355.7 KB 17 0 1001:10:58
2*.man.lodz.pl 577 3437.2 KB 14 1 000:00:30
3*.neoplus.adsl.tpnet.pl 1 00.0 KB 0 0 000:00:17
4unresolved 96 00.0 KB 0 0 000:00:01
TOTAL: 4 693 8792.9 KB 31 1 1001:11:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
m225.class145.petrotel.pl 0.364 0.456 0.00001:10:58
web.man.lodz.pl 1.904 7.900 0.00000:00:25
acgw185.neoplus.adsl.tpnet.pl 0.008 0.024 0.00000:00:17
mon.man.lodz.pl 1.680 5.956 0.00000:00:05
212.51.192.185 0.556 1.960 0.00000:00:01
isvcs01.man.lodz.pl 0.008 0.032 0.00000:00:00
TOTAL: 6 4.520 16.328 0.00001:11:48

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
acgw185.neoplus.adsl.tpnet.pl 1
TOTAL: 3 385

Newsgroup request counts (by category):

CategoryCountPct
1lodz 46 52.9%
2pl 34 39.1%
3lodzman 4 4.6%
4lodman 3 3.4%
TOTAL: 4 87100.0%

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

NewsgroupCount
1lodz.test 34
2pl.comp.os.ms-windows.winnt 20
3pl.comp.pecet 14
4lodz.ogloszenia 12
5lodzman.test 4
6lodman.info 2
7lodman.test 1
TOTAL: 7 87