Daily Usenet report

Sep 24 04:16:25 -- Sep 25 04:15:02

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Sep 24 07:53:10 newsfeed overchan[15194]: input line too long, skipping
Sep 24 08:11:50 newsfeed overchan[15194]: input line too long, skipping
Sep 24 08:12:11 newsfeed overchan[15194]: input line too long, skipping
Sep 24 08:23:17 newsfeed nnrpd[25143]: SERVER perl filter not defined
Sep 24 13:16:51 newsfeed overchan[15194]: input line too long, skipping
Sep 24 13:18:13 newsfeed overchan[15194]: input line too long, skipping
Sep 24 13:18:32 newsfeed overchan[15194]: input line too long, skipping
Sep 24 15:49:11 newsfeed nnrpd[9644]: SERVER perl filter not defined
Sep 24 18:07:51 newsfeed overchan[15194]: input line too long, skipping
Sep 24 20:28:39 newsfeed overchan[15194]: input line too long, skipping
Sep 24 21:21:37 newsfeed overchan[15194]: input line too long, skipping
Sep 24 21:41:15 newsfeed overchan[15194]: input line too long, skipping
Sep 24 21:42:16 newsfeed overchan[15194]: input line too long, skipping
Sep 24 21:42:36 newsfeed overchan[15194]: input line too long, skipping
Sep 24 22:09:51 newsfeed overchan[15194]: input line too long, skipping
Sep 24 22:25:47 newsfeed overchan[15194]: input line too long, skipping
Sep 25 02:50:41 newsfeed overchan[15194]: input line too long, skipping
Sep 25 02:55:40 newsfeed overchan[15194]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2480361 97.4%290.5 MB 97.1%
innfeed 43907 1.7%5.2 MB 1.7%
innd 17693 0.7%2.9 MB 1.0%
nnrpd 4770 0.2%504.6 KB 0.2%
overchan 17 0.0%2.9 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 62546749 100.0%299.1 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2450676 88.4%
Positive hits 224901 8.1%
Negative hits 93907 3.4%
Cache misses 3508 0.1%
TOTAL: 4 2772992 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:07.498 0.0%166 0.000 45.169 224.000
article cleanup00:00:32.219 0.0%2472547 0.008 0.013 0.018
article logging00:02:28.879 0.2%2473135 0.034 0.060 4.319
article parse00:53:30.381 3.7%86848780 0.022 0.037 0.065
article write00:20:09.767 1.4%2451925 0.118 0.493 4.114
artlog/artcncl00:00:00.007 0.0%154 0.000 0.045 1.000
artlog/artparse00:00:00.000 0.0%1 0.000 0.000 0.000
data move00:02:25.519 0.2%101528863 0.001 0.001 0.003
hisgrep/artcncl00:00:02.584 0.0%164 0.000 15.756 57.000
hishave/artcncl00:00:00.469 0.0%166 0.000 2.825 23.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:44:46.478 11.4%2781805 0.193 3.554 23.545
history sync00:00:00.015 0.0%290 0.000 0.052 0.667
history write05:43:32.559 23.9%2454035 1.070 8.399 69.148
hiswrite/artcncl00:00:00.013 0.0%2 0.000 6.500 13.000
idle07:43:37.492 32.2%52388554 0.107 0.531 1.634
nntp read01:08:14.802 4.7%85052097 0.037 0.048 0.059
overview write00:00:12.603 0.0%2451925 0.003 0.005 0.008
perl filter04:48:17.151 20.0%2454032 2.360 7.048 9.835
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:05:31.360 0.4%5224831 0.048 0.063 0.086
TOTAL: 24:00:03.76123:33:29.796 98.2%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.920 0.0%639104 0.000 0.003 0.007
article prepare00:00:00.099 0.0%361582 0.000 0.000 0.002
article read00:05:07.137 0.4%75567 0.276 4.064 1965.732
backlog stats00:00:02.081 0.0%2188513 0.000 0.001 0.002
callbacks00:00:00.000 0.0%3 0.000 0.000 0.000
data read00:00:27.636 0.0%1202460 0.015 0.023 0.036
data write00:04:57.462 0.3%1136750 0.039 0.262 0.540
idle23:36:02.720 99.0%2188513 16.642 38.822 338.796
status file00:02:54.147 0.2%17144 0.756 10.158 915.936
TOTAL: 23:50:47.52923:49:33.202 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:24.748 62.4%3049 0.000 8.117 1630.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.471 1.2%6758 0.000 0.070 3.737
readart00:00:00.048 0.1%52 0.000 0.923 11.000
TOTAL: 00:00:39.67200:00:25.267 63.7%----

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 541 24511372446227 1695 3215 99%517:22:23
2news.nask.pl 47 238782 12544 208884 17354 5%33:21:32
3news.icm.edu.pl 49 206 121 74 11 58%16:25:44
4localhost 3 2 2 0 0 100%00:00:01
TOTAL: 4 640 26901272458894 210653 20580 91%567:09:40
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl806.6 GB543.8 MB573.0 MB807.7 GB 99%345.8 KB
2news.nask.pl4.8 GB7.3 GB7.3 GB19.4 GB 24%679.6 KB
3news.icm.edu.pl3.0 MB79.4 KB135.9 KB3.2 MB 93%25.1 KB
4localhost5.0 KB0.0 KB0.0 KB5.0 KB100%2.5 KB
TOTAL: 4811.5 GB7.8 GB7.8 GB827.1 GB 98%349.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 24 04:16:25 - 04:59:59 69835 2.8% 26.7122.5 GB 2.8%9022.57
Sep 24 05:00:00 - 05:59:59 108206 4.4% 30.0638.7 GB 4.8%11275.35
Sep 24 06:00:00 - 06:59:59 104726 4.3% 29.0939.4 GB 4.9%11477.60
Sep 24 07:00:00 - 07:59:59 99214 4.0% 27.5640.3 GB 5.0%11738.95
Sep 24 08:00:00 - 08:59:59 99996 4.1% 27.7841.2 GB 5.1%11993.08
Sep 24 09:00:00 - 09:59:59 82738 3.4% 22.9830.2 GB 3.7%8793.87
Sep 24 10:00:00 - 10:59:59 84410 3.4% 23.4532.3 GB 4.0%9398.88
Sep 24 11:00:00 - 11:59:59 77639 3.2% 21.5727.2 GB 3.3%7908.11
Sep 24 12:00:00 - 12:59:59 69079 2.8% 19.1927.0 GB 3.3%7876.09
Sep 24 13:00:00 - 13:59:59 76059 3.1% 21.1328.0 GB 3.4%8150.15
Sep 24 14:00:00 - 14:59:59 74414 3.0% 20.6726.9 GB 3.3%7822.88
Sep 24 15:00:00 - 15:59:59 88793 3.6% 24.6634.0 GB 4.2%9893.43
Sep 24 16:00:00 - 16:59:59 94223 3.8% 26.1731.6 GB 3.9%9205.63
Sep 24 17:00:00 - 17:59:59 100733 4.1% 27.9840.4 GB 5.0%11774.57
Sep 24 18:00:00 - 18:59:59 119010 4.8% 33.0639.6 GB 4.9%11539.58
Sep 24 19:00:00 - 19:59:59 106650 4.3% 29.6240.1 GB 4.9%11686.33
Sep 24 20:00:00 - 20:59:59 109813 4.5% 30.5040.6 GB 5.0%11839.78
Sep 24 21:00:00 - 21:59:59 135881 5.5% 37.7441.1 GB 5.1%11957.13
Sep 24 22:00:00 - 22:59:59 139696 5.7% 38.8040.9 GB 5.0%11911.13
Sep 24 23:00:00 - 23:59:59 114130 4.6% 31.7033.9 GB 4.2%9876.54
Sep 25 00:00:00 - 00:59:59 115128 4.7% 31.9816.9 GB 2.1%4918.48
Sep 25 01:00:00 - 01:59:59 158793 6.5% 44.1122.2 GB 2.7%6461.40
Sep 25 02:00:00 - 02:59:59 90559 3.7% 25.1630.6 GB 3.8%8917.05
Sep 25 03:00:00 - 03:59:59 108845 4.4% 30.2336.5 GB 4.5%10620.72
Sep 25 04:00:00 - 04:15:02 30391 1.2% 33.699.2 GB 1.1%10690.37
TOTAL: 23:58:37 2458961 100.0% 28.49811.2 GB 100.0%9854.01
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 17539 4 0 15690 8 0 0 0 1837
2newsfeed.pionier.net.pl 3691 195 0 2834 3 0 0 0 659
3news.icm.edu.pl 16 0 0 8 0 0 0 0 8
TOTAL: 3 21246 199 0 18532 11 0 0 0 2504

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 91
it.tlc.cellulari.android 35
it.comp.os.win.windows10 35
comp.sys.raspberry-pi 10
nl.comp.os.linux.techniek 10
de.comp.lang.vbclassic 5
it.comp.os.win.windows7 4
it.discussioni.energia 3
comp.mobile.ipad 2
uk.rec.cycling.moderated 2
de.comp.lang.perl 1
de.comp.office-pakete.ms-office.outlook 1
TOTAL: 12 199

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

GroupsCount
pl.praca.oferowana 5
courts.usa.federal.supreme 2
pl.sci.matematyka 2
clari.usa.law.supreme 1
bit.listserv.catholic 1
TOTAL: 5 11

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1678
Binary in non-binary group 192
EMP (phl) 11
Scoring filter (16) 8
Scoring filter (8) 7
Scoring filter (10) 7
Attached HTML file 3
Scoring filter (9) 3
Scoring filter (14) 2
Too many newsgroups 2
TOTAL: 10 1913

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
news.nask.pl 336
TOTAL: 1 336
Including strange strings
newsfeed.pionier.net.pl 474
news.nask.pl 109
news.icm.edu.pl 5
TOTAL: 3 588
TOTAL: 2 924

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 433445 63370 237361 1249 0 0 14%23:59:02
2panorama 343071 3206 311823 381 11 325027 0%23:59:00
3poznan 4949 52 3515 18 0 0 1%23:34:34
4icm 349601 13 298659 21875 0 0 0%23:59:02
5okapi 0 0 0 0 0 1031 0%23:47:17
TOTAL: 51131066 66641 851358 23523 11 326058 5%119:18:55
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask34.7 GB598.9 MB35.2 GB428.0 KB/s571.9 KB23:59:02
2panorama1.1 GB27.1 MB1.2 GB14.2 KB/s342.1 KB23:59:00
3icm2.2 MB11.7 GB11.7 GB141.9 KB/s559.9 KB23:59:02
4poznan156.9 KB128.9 KB285.7 KB0.0 KB/s4.1 KB23:34:34
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:47:17
TOTAL: 535.8 GB12.3 GB48.1 GB117.4 KB/s559.4 KB119:18:55
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama2.4 MB
TOTAL: 12.4 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 5273.4 KB 23 0 000:00:30
2isvcs01.man.lodz.pl 2 00.0 KB 0 2 000:00:00
TOTAL: 2 290 5273.4 KB 23 2 000:00:38

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 674 5273.4 KB 23 2 000:00:36
2*.binaryedge.ninja 1 00.0 KB 0 0 000:00:01
TOTAL: 2 675 5273.4 KB 23 2 000:00:38

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.516 5.576 0.00000:00:30
mon.man.lodz.pl 1.420 5.248 0.00000:00:04
min-li-usga-08-20-4485-e-prod.binaryedge.ninja 0.004 0.024 0.00000:00:01
david.man.lodz.pl 0.420 1.728 0.00000:00:01
isvcs01.man.lodz.pl 0.024 0.060 0.00000:00:00
TOTAL: 5 3.384 12.636 0.00000:00:38

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
min-li-usga-08-20-4485-e-prod.binaryedge.ninja 1
TOTAL: 3 385

Newsgroup request counts (by category):

CategoryCountPct
1pl 52100.0%
TOTAL: 1 52100.0%

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

NewsgroupCount
1pl.comp.pecet 50
2pl.comp.os.ms-windows.winnt 2
TOTAL: 2 52