Daily Usenet report

Nov 1 04:15:02 -- Nov 2 04:15:02

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Nov 1 04:36:03 newsfeed overchan[16245]: input line too long, skipping
Nov 1 10:42:37 newsfeed overchan[16245]: input line too long, skipping
Nov 1 10:54:42 newsfeed overchan[16245]: input line too long, skipping
Nov 1 14:33:02 newsfeed overchan[16245]: input line too long, skipping
Nov 1 14:46:11 newsfeed overchan[16245]: input line too long, skipping
Nov 1 14:47:48 newsfeed overchan[16245]: input line too long, skipping
Nov 1 15:12:11 newsfeed overchan[16245]: input line too long, skipping
Nov 1 21:11:13 newsfeed overchan[16245]: input line too long, skipping
Nov 1 21:40:14 newsfeed overchan[16245]: input line too long, skipping
Nov 2 01:38:29 newsfeed overchan[16245]: input line too long, skipping
Nov 2 01:38:44 newsfeed overchan[16245]: input line too long, skipping
Nov 2 01:45:32 newsfeed overchan[16245]: input line too long, skipping
Nov 2 02:57:37 newsfeed overchan[16245]: input line too long, skipping
Nov 2 03:00:43 newsfeed overchan[16245]: input line too long, skipping
Nov 2 03:00:45 newsfeed overchan[16245]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn3086888 98.0%360.7 MB 97.7%
innfeed 38977 1.2%4.6 MB 1.2%
innd 19988 0.6%3.3 MB 0.9%
nnrpd 4886 0.2%520.0 KB 0.1%
overchan 16 0.0%2.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 63150756 100.0%369.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 3051849 93.1%
Positive hits 129151 3.9%
Negative hits 96017 2.9%
Cache misses 1581 0.0%
TOTAL: 4 3278598 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.897 0.0%121 0.000 23.942 160.500
article cleanup00:00:41.434 0.0%3096415 0.008 0.013 0.018
article logging00:02:21.219 0.2%3096936 0.026 0.046 0.067
article parse00:54:26.243 3.8%88146513 0.017 0.037 0.057
article write00:20:14.984 1.4%3082212 0.162 0.394 0.561
artlog/artcncl00:00:00.008 0.0%107 0.000 0.075 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:03:22.447 0.2%104085921 0.001 0.002 0.004
hisgrep/artcncl00:00:00.562 0.0%119 0.000 4.723 37.500
hishave/artcncl00:00:00.262 0.0%121 0.000 2.165 44.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:02:59.083 8.5%3314479 0.790 2.226 5.213
history sync00:00:00.023 0.0%290 0.000 0.079 1.000
history write03:45:20.804 15.6%3083820 1.657 4.384 9.184
hiswrite/artcncl00:00:00.001 0.0%2 0.000 0.500 1.000
idle09:39:34.684 40.2%56984906 0.220 0.610 1.369
nntp read01:10:55.988 4.9%85832091 0.030 0.050 0.062
overview write00:00:16.682 0.0%3082212 0.003 0.005 0.009
perl filter05:26:10.445 22.7%3083819 3.639 6.346 8.502
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:53.308 0.5%6376261 0.036 0.065 0.098
TOTAL: 24:00:03.01423:33:21.074 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.413 0.0%448902 0.000 0.003 0.009
article prepare00:00:00.067 0.0%327038 0.000 0.000 0.004
article read00:01:48.269 0.1%72305 0.179 1.497 35.385
backlog stats00:00:01.595 0.0%1604513 0.000 0.001 0.002
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:18.185 0.0%836844 0.011 0.022 0.035
data write00:03:03.190 0.2%815190 0.077 0.225 0.459
idle23:44:19.986 99.6%1604513 8.442 53.262 167.467
status file00:00:18.727 0.0%16172 0.333 1.158 4.000
TOTAL: 23:50:45.33723:49:51.432 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:22.076 58.9%3053 0.000 7.231 1650.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.284 0.8%6840 0.000 0.042 1.000
readart00:00:00.004 0.0%72 0.000 0.056 0.500
TOTAL: 00:00:37.50200:00:22.364 59.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 654 30691333063254 1999 3880 99%526:30:12
2news.nask.pl 2 135449 7686 117467 10296 5%23:57:57
3news.icm.edu.pl 51 202 96 83 23 47%16:11:07
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 708 32047843071036 119549 14199 95%566:39:16
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl777.4 GB421.9 MB425.2 MB778.2 GB 99%266.1 KB
2news.nask.pl1.1 GB2.4 GB2.5 GB6.0 GB 17%348.0 KB
3news.icm.edu.pl1.6 MB42.0 KB522.4 KB2.2 MB 74%18.9 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4778.5 GB2.9 GB2.9 GB784.2 GB 99%266.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Nov 1 04:15:02 - 04:59:59 91962 3.0% 34.0928.0 GB 3.6%10883.32
Nov 1 05:00:00 - 05:59:59 128296 4.2% 35.6438.4 GB 4.9%11190.73
Nov 1 06:00:00 - 06:59:59 129040 4.2% 35.8439.2 GB 5.0%11422.76
Nov 1 07:00:00 - 07:59:59 125989 4.1% 35.0037.1 GB 4.8%10819.23
Nov 1 08:00:00 - 08:59:59 124794 4.1% 34.6637.7 GB 4.8%10983.18
Nov 1 09:00:00 - 09:59:59 121066 3.9% 33.6334.8 GB 4.5%10149.02
Nov 1 10:00:00 - 10:59:59 126861 4.1% 35.2432.2 GB 4.1%9385.95
Nov 1 11:00:00 - 11:59:59 151091 4.9% 41.9735.4 GB 4.5%10299.74
Nov 1 12:00:00 - 12:59:59 141023 4.6% 39.1727.3 GB 3.5%7946.93
Nov 1 13:00:00 - 13:59:59 106261 3.5% 29.5232.3 GB 4.1%9412.81
Nov 1 14:00:00 - 14:59:59 156714 5.1% 43.5335.6 GB 4.6%10379.33
Nov 1 15:00:00 - 15:59:59 163165 5.3% 45.3239.3 GB 5.0%11451.96
Nov 1 16:00:00 - 16:59:59 156621 5.1% 43.5139.8 GB 5.1%11597.12
Nov 1 17:00:00 - 17:59:59 138810 4.5% 38.5642.6 GB 5.5%12412.01
Nov 1 18:00:00 - 18:59:59 180761 5.9% 50.2141.4 GB 5.3%12056.94
Nov 1 19:00:00 - 19:59:59 108806 3.5% 30.2224.8 GB 3.2%7217.87
Nov 1 20:00:00 - 20:59:59 85401 2.8% 23.7222.6 GB 2.9%6594.83
Nov 1 21:00:00 - 21:59:59 87043 2.8% 24.1824.4 GB 3.1%7110.68
Nov 1 22:00:00 - 22:59:59 95533 3.1% 26.5427.8 GB 3.6%8111.37
Nov 1 23:00:00 - 23:59:59 106736 3.5% 29.6526.3 GB 3.4%7646.04
Nov 2 00:00:00 - 00:59:59 132487 4.3% 36.8021.1 GB 2.7%6137.27
Nov 2 01:00:00 - 01:59:59 149228 4.9% 41.4532.2 GB 4.1%9369.09
Nov 2 02:00:00 - 02:59:59 131774 4.3% 36.6026.4 GB 3.4%7677.42
Nov 2 03:00:00 - 03:59:59 116464 3.8% 32.3527.9 GB 3.6%8137.98
Nov 2 04:00:00 - 04:15:02 16157 0.5% 17.914.6 GB 0.6%5379.85
TOTAL: 24:00:00 3072083 100.0% 35.56779.4 GB 100.0%9458.94
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 10410 5 0 8974 3 0 0 0 1428
2newsfeed.pionier.net.pl 4264 222 0 3598 3 0 0 0 441
3news.icm.edu.pl 24 0 0 3 0 0 0 0 21
TOTAL: 3 14698 227 0 12575 6 0 0 0 1890

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 117
it.tlc.cellulari.android 57
it.comp.os.win.windows10 26
comp.mobile.android 7
uk.rec.cycling.moderated 6
it.comp.os.win.windows7 5
de.comm.software.mailreader 4
it.comp.os.win.windows8 2
de.comp.lang.vbclassic 2
it.comp.os.linux.ubuntu 1
TOTAL: 10 227

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

GroupsCount
pl.praca.oferowana 5
bit.listserv.catholic 1
TOTAL: 2 6

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1196
Binary in non-binary group 76
Scoring filter (8) 27
Scoring filter (16) 24
EMP (phl) 21
Scoring filter (9) 8
HTML post 7
Scoring filter (18) 5
Scoring filter (10) 4
EMP (fsl) 2
Scoring filter (17) 1
TOTAL: 11 1371

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 388
news.nask.pl 129
news.icm.edu.pl 1
TOTAL: 3 518
TOTAL: 1 518

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 277829 65487 132093 1079 0 4239 23%23:59:57
2panorama 265981 2708 235172 1008 7 207064 1%23:59:57
3poznan 6552 139 4532 16 0 0 2%23:59:57
4icm 237795 13 198704 12499 0 5 0%23:59:57
5okapi 0 0 0 0 0 1016 0%23:57:50
TOTAL: 5 788157 68347 570501 14602 7 212324 8%119:57:38
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask22.0 GB370.7 MB22.4 GB271.6 KB/s352.5 KB23:59:57
2panorama507.9 MB6.5 MB514.4 MB6.1 KB/s141.7 KB23:59:57
3icm2.3 MB4.2 GB4.2 GB51.1 KB/s352.7 KB23:59:57
4poznan602.8 KB88.7 KB691.5 KB0.0 KB/s4.5 KB23:59:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:50
TOTAL: 522.5 GB4.6 GB27.1 GB65.8 KB/s342.4 KB119:57:38
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama488.3 KB
TOTAL: 1488.3 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 7290.8 KB 34 0 000:00:27
TOTAL: 1 288 7290.8 KB 34 0 000:00:36

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 576 7290.8 KB 34 0 000:00:31
2*.binaryedge.ninja 1 00.0 KB 0 0 000:00:01
3*.security.ipip.net 2 00.0 KB 0 0 000:00:01
4unresolved 96 00.0 KB 0 0 000:00:01
TOTAL: 4 675 7290.8 KB 34 0 000:00:36

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.612 5.304 0.00000:00:27
mon.man.lodz.pl 1.256 4.592 0.00000:00:04
min-li-ustx-10-04-29750-d-prod.binaryedge.ninja 0.008 0.024 0.00000:00:01
scan-141.security.ipip.net 0.012 0.040 0.00000:00:01
212.51.192.185 0.436 1.488 0.00000:00:01
TOTAL: 5 3.324 11.448 0.00000:00:36

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
scan-141.security.ipip.net 2
min-li-ustx-10-04-29750-d-prod.binaryedge.ninja 1
TOTAL: 4 387

NNRP unrecognized commands (by host):

SystemConn
scan-141.security.ipip.net 8
TOTAL: 1 8

NNRP unrecognized commands (by command):

CommandCount
GET / HTTP/1.1 2
Host: 212.191.2.21:119 2
User-Agent: HTTP Banner Detection (https... 2
Connection: close 2
TOTAL: 4 8

NNRP client timeouts [Top 20]:

SystemConnPeer
scan-141.security.ipip.net 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 72100.0%
TOTAL: 1 72100.0%

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

NewsgroupCount
1pl.comp.pecet 40
2pl.comp.os.ms-windows.winnt 32
TOTAL: 2 72