Daily Usenet report

Jun 25 04:15:03 -- Jun 26 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Jun 25 09:02:48 newsfeed overchan[5694]: input line too long, skipping
Jun 25 09:03:14 newsfeed overchan[5694]: input line too long, skipping
Jun 25 09:03:33 newsfeed overchan[5694]: input line too long, skipping
Jun 25 10:08:01 newsfeed nnrpd[19416]: SERVER perl filter not defined
Jun 25 16:08:45 newsfeed overchan[5694]: input line too long, skipping
Jun 25 16:46:19 newsfeed overchan[5694]: input line too long, skipping
Jun 25 17:07:52 newsfeed overchan[5694]: input line too long, skipping
Jun 25 17:09:06 newsfeed nnrpd[2619]: SERVER perl filter not defined
Jun 25 17:10:27 newsfeed nnrpd[2680]: SERVER perl filter not defined
Jun 25 17:30:45 newsfeed overchan[5694]: input line too long, skipping
Jun 26 00:11:31 newsfeed nnrpd[18280]: SERVER perl filter not defined
Jun 26 00:12:42 newsfeed nnrpd[18283]: SERVER perl filter not defined

Log entries by program:

Program nameLines%LinesSize%Size
inn2396221 97.4%265.6 MB 97.0%
innfeed 41211 1.7%4.8 MB 1.8%
innd 17133 0.7%2.8 MB 1.0%
nnrpd 4969 0.2%528.2 KB 0.2%
overchan 8 0.0%1.4 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62459543 100.0%273.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2313723 60.8%
Negative hits 1266279 33.3%
Positive hits 210574 5.5%
Cache misses 12157 0.3%
TOTAL: 4 3802733 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:03.965 0.0%196 0.000 20.230 932.000
article cleanup00:00:32.583 0.0%2395406 0.008 0.014 0.018
article logging00:01:51.656 0.1%2396008 0.030 0.047 0.111
article parse00:44:38.994 3.1%77007310 0.015 0.035 0.056
article write00:16:40.360 1.2%2321331 0.204 0.431 0.712
artlog/artcncl00:00:00.005 0.0%195 0.000 0.026 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:01:45.034 0.1%90724029 0.001 0.001 0.002
hisgrep/artcncl00:00:01.030 0.0%191 0.000 5.393 81.750
hishave/artcncl00:00:00.037 0.0%196 0.000 0.189 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:20:21.792 1.4%3818295 0.034 0.320 1.438
history sync00:00:00.017 0.0%290 0.000 0.059 0.500
history write01:15:32.904 5.2%2324036 0.215 1.950 6.425
hiswrite/artcncl00:00:00.001 0.0%5 0.000 0.200 1.000
idle15:27:44.354 64.4%64019637 0.433 0.869 1.771
nntp read00:55:32.391 3.9%75642080 0.029 0.044 0.065
overview write00:00:12.340 0.0%2321331 0.003 0.005 0.007
perl filter04:22:19.012 18.2%2324018 3.713 6.772 8.656
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:29.076 0.4%5005726 0.046 0.066 0.093
TOTAL: 24:00:01.87923:32:45.551 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.791 0.0%673515 0.001 0.003 0.006
article prepare00:00:00.013 0.0%59704 0.000 0.000 0.004
article read00:00:41.709 0.0%12140 0.000 3.436 57.059
backlog stats00:00:01.212 0.0%1727962 0.000 0.001 0.001
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:20.134 0.0%1076804 0.011 0.019 0.037
data write00:01:11.461 0.1%745238 0.032 0.096 0.425
idle23:47:21.416 99.8%1727962 24.972 49.562 155.753
status file00:00:16.401 0.0%16801 0.583 0.976 1.885
TOTAL: 23:50:39.17323:49:54.137 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:02:09.382 89.9%3201 0.000 40.419 859.300
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.287 0.2%7038 0.000 0.041 0.500
readart00:00:00.005 0.0%46 0.000 0.109 1.000
TOTAL: 00:02:23.98200:02:09.674 90.1%----

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 346 22712272095383 116037 59807 92%216:22:22
2news.nask.pl 100 277533 226764 36381 14388 81%37:38:00
3news.icm.edu.pl 48 179 52 120 7 29%14:53:29
4localhost 6 5 5 0 0 100%00:00:00
TOTAL: 4 500 25489442322204 152538 74202 91%268:53:51
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl511.5 GB20.3 GB20.3 GB552.2 GB 92%268.7 KB
2news.nask.pl79.7 GB3.5 GB3.5 GB86.7 GB 91%376.8 KB
3news.icm.edu.pl1.2 MB70.9 KB124.9 KB1.4 MB 85%23.5 KB
4localhost9.1 KB0.0 KB0.0 KB9.1 KB100%1.8 KB
TOTAL: 4591.3 GB23.8 GB23.8 GB638.8 GB 92%279.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 25 04:15:03 - 04:59:59 89182 3.8% 33.0725.4 GB 4.3%9872.66
Jun 25 05:00:00 - 05:59:59 111579 4.8% 30.9930.2 GB 5.1%8803.30
Jun 25 06:00:00 - 06:59:59 127949 5.5% 35.5435.2 GB 5.9%10240.60
Jun 25 07:00:00 - 07:59:59 93518 4.0% 25.9825.9 GB 4.4%7555.66
Jun 25 08:00:00 - 08:59:59 123510 5.3% 34.3134.0 GB 5.8%9903.26
Jun 25 09:00:00 - 09:59:59 104891 4.5% 29.1430.1 GB 5.1%8775.68
Jun 25 10:00:00 - 10:59:59 97930 4.2% 27.2025.6 GB 4.3%7447.52
Jun 25 11:00:00 - 11:59:59 93845 4.0% 26.0726.8 GB 4.5%7810.76
Jun 25 12:00:00 - 12:59:59 90241 3.9% 25.0725.3 GB 4.3%7361.25
Jun 25 13:00:00 - 13:59:59 67966 2.9% 18.8817.9 GB 3.0%5222.79
Jun 25 14:00:00 - 14:59:59 103827 4.5% 28.8429.0 GB 4.9%8461.13
Jun 25 15:00:00 - 15:59:59 95022 4.1% 26.3925.3 GB 4.3%7378.62
Jun 25 16:00:00 - 16:59:59 122804 5.3% 34.1132.1 GB 5.4%9341.62
Jun 25 17:00:00 - 17:59:59 68220 2.9% 18.9517.3 GB 2.9%5039.38
Jun 25 18:00:00 - 18:59:59 71650 3.1% 19.9018.2 GB 3.1%5311.15
Jun 25 19:00:00 - 19:59:59 109829 4.7% 30.5113.6 GB 2.3%3954.70
Jun 25 20:00:00 - 20:59:59 104453 4.5% 29.0113.8 GB 2.3%4007.70
Jun 25 21:00:00 - 21:59:59 105107 4.5% 29.2020.2 GB 3.4%5889.42
Jun 25 22:00:00 - 22:59:59 83279 3.6% 23.1322.5 GB 3.8%6563.16
Jun 25 23:00:00 - 23:59:59 88849 3.8% 24.6824.0 GB 4.1%6987.13
Jun 26 00:00:00 - 00:59:59 73807 3.2% 20.5019.5 GB 3.3%5687.96
Jun 26 01:00:00 - 01:59:59 78526 3.4% 21.8120.0 GB 3.4%5828.44
Jun 26 02:00:00 - 02:59:59 100676 4.3% 27.9727.5 GB 4.7%8011.52
Jun 26 03:00:00 - 03:59:59 88202 3.8% 24.5024.1 GB 4.1%7022.87
Jun 26 04:00:00 - 04:15:02 26365 1.1% 29.237.4 GB 1.3%8614.36
TOTAL: 23:59:59 2321227 100.0% 26.87591.1 GB 100.0%7173.43
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 60210 128 0 59623 0 0 0 0 459
2news.nask.pl 14582 9 0 11867 10 0 0 0 2696
3news.icm.edu.pl 7 0 0 2 0 0 0 0 5
TOTAL: 3 74799 137 0 71492 10 0 0 0 3160

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 80
it.tlc.cellulari.android 18
comp.sys.raspberry-pi 13
it.comp.os.win.windows10 12
it.comp.os.win.windows7 6
it.comp.os.linux.ubuntu 3
de.comm.software.mailreader 3
de.rec.sf.startrek 1
xs4all.general 1
TOTAL: 9 137

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

GroupsCount
pl.sci.historia 5
christnet.prayer 2
pl.praca.oferowana 2
bit.listserv.catholic 1
TOTAL: 4 10

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2330
Binary in non-binary group 178
Scoring filter (8) 10
Too many newsgroups 4
Scoring filter (9) 4
Scoring filter (12) 4
HTML post 3
Scoring filter (16) 3
Scoring filter (10) 2
Scoring filter (14) 1
TOTAL: 10 2539

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 404
news.nask.pl 199
TOTAL: 2 603
TOTAL: 1 603

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 155021 7064 122545 33 0 0 4%23:59:54
2panorama 335676 1831 305121 355 10 374391 0%23:59:57
3poznan 10367 432 6161 19 0 0 4%23:59:27
4icm 413062 46 358380 4957 0 0 0%23:59:57
5okapi 0 0 0 0 0 1026 0%23:57:50
TOTAL: 5 914126 9373 792207 5364 10 375417 1%119:57:05
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask2.5 GB10.7 MB2.6 GB31.0 KB/s376.8 KB23:59:54
2panorama222.4 MB14.2 MB236.6 MB2.8 KB/s110.8 KB23:59:57
3icm11.0 MB1.8 GB1.8 GB21.6 KB/s373.3 KB23:59:57
4poznan2.5 MB115.6 KB2.6 MB0.0 KB/s6.0 KB23:59:27
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 52.8 GB1.8 GB4.6 GB11.1 KB/s324.8 KB119:57:05
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama6.2 MB
TOTAL: 16.2 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 3630.7 KB 18 0 000:00:22
20890788407.static.corbina.ru 13 59.1 KB 3 0 000:01:55
3isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:00
TOTAL: 3 306 4139.8 KB 21 5 000:02:23

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 677 3630.7 KB 18 5 000:00:27
2*.static.corbina.ru 13 59.1 KB 3 0 000:01:55
3unresolved 3 00.0 KB 0 0 000:00:00
TOTAL: 3 693 4139.8 KB 21 5 000:02:23

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
0890788407.static.corbina.ru 0.084 0.340 0.00000:01:55
web.man.lodz.pl 1.552 6.136 0.00000:00:22
mon.man.lodz.pl 1.408 6.328 0.00000:00:03
david.man.lodz.pl 0.524 2.072 0.00000:00:01
isvcs01.man.lodz.pl 0.040 0.132 0.00000:00:00
5.101.40.5 0.024 0.036 0.00000:00:00
89.248.174.161 0.004 0.020 0.00000:00:00
? 0.004 0.036 0.00000:00:00
TOTAL: 8 3.640 15.100 0.00000:02:23

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
5.101.40.5 2
89.248.174.161 1
TOTAL: 4 387

NNRP gethostbyaddr failures [Top 20]:

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

NNRP unrecognized commands (by host):

SystemConn
5.101.40.5 2
89.248.174.161 1
TOTAL: 2 3

NNRP unrecognized commands (by command):

CommandCount
#003 3
TOTAL: 1 3

NNRP client timeouts [Top 20]:

SystemConnPeer
5.101.40.5 2 2
89.248.174.161 1 1
TOTAL: 2 3 3

Newsgroup request counts (by category):

CategoryCountPct
1pl 36 87.8%
2lodman 5 12.2%
TOTAL: 2 41100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 24
2pl.comp.pecet 12
3lodman.info 5
TOTAL: 3 41