Daily Usenet report

Sep 17 04:15:03 -- Sep 18 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Sep 17 11:00:36 newsfeed nnrpd[1315]: SERVER perl filter not defined
Sep 17 12:03:54 newsfeed overchan[17599]: input line too long, skipping
Sep 17 12:47:05 newsfeed nnrpd[5168]: SERVER perl filter not defined
Sep 17 13:10:02 newsfeed pgpverify[6186]: pgpverify: verification failed
Sep 17 13:28:28 newsfeed overchan[17599]: input line too long, skipping
Sep 17 16:10:32 newsfeed nnrpd[12940]: SERVER perl filter not defined
Sep 17 16:23:18 newsfeed nnrpd[13373]: SERVER perl filter not defined
Sep 17 16:32:32 newsfeed nnrpd[13707]: SERVER perl filter not defined
Sep 17 17:08:38 newsfeed overchan[17599]: input line too long, skipping
Sep 17 17:14:05 newsfeed overchan[17599]: input line too long, skipping
Sep 17 20:37:15 newsfeed overchan[17599]: input line too long, skipping
Sep 18 01:40:15 newsfeed overchan[17599]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2439047 97.4%283.1 MB 97.1%
innfeed 40872 1.6%4.9 MB 1.7%
innd 18947 0.8%3.0 MB 1.0%
nnrpd 4776 0.2%505.4 KB 0.2%
overchan 7 0.0%1.1 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72503654 100.0%291.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2407922 86.4%
Positive hits 271335 9.7%
Negative hits 105653 3.8%
Cache misses 2518 0.1%
TOTAL: 4 2787428 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:04.960 0.0%129 0.000 38.450 211.000
article cleanup00:00:34.397 0.0%2437500 0.010 0.014 0.020
article logging00:02:02.411 0.1%2438044 0.032 0.050 0.078
article parse00:52:59.631 3.7%78918991 0.016 0.040 0.055
article write00:19:41.947 1.4%2418047 0.227 0.489 0.835
artlog/artcncl00:00:00.002 0.0%128 0.000 0.016 0.250
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:02:33.476 0.2%92568432 0.001 0.002 0.003
hisgrep/artcncl00:00:01.028 0.0%124 0.000 8.290 33.800
hishave/artcncl00:00:00.404 0.0%129 0.000 3.132 29.400
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:20:53.292 9.8%2805247 0.744 3.013 6.884
history sync00:00:00.013 0.0%291 0.000 0.045 0.500
history write04:58:37.890 20.7%2420994 1.784 7.401 13.321
hiswrite/artcncl00:00:00.017 0.0%5 0.000 3.400 17.000
idle08:28:21.583 35.3%49606012 0.162 0.615 2.624
nntp read01:09:59.026 4.9%77157974 0.035 0.054 0.071
overview write00:00:13.503 0.0%2418047 0.003 0.006 0.008
perl filter05:10:09.450 21.5%2420994 4.290 7.687 10.932
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:06:00.335 0.4%5206742 0.046 0.069 0.089
TOTAL: 24:00:08.05023:32:13.365 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:02.264 0.0%649909 0.001 0.003 0.008
article prepare00:00:00.146 0.0%479284 0.000 0.000 0.002
article read00:13:06.366 0.9%110115 0.358 7.141 29.495
backlog stats00:00:02.609 0.0%2581660 0.000 0.001 0.002
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:34.578 0.0%1326830 0.014 0.026 0.037
data write00:07:20.198 0.5%1366242 0.121 0.322 0.918
idle23:27:34.974 98.4%2581660 12.413 32.713 169.792
status file00:00:21.316 0.0%15937 0.758 1.338 5.300
TOTAL: 23:50:26.91823:49:02.451 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:26.504 59.2%3030 0.000 8.747 9541.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.370 0.8%6643 0.000 0.056 1.263
readart00:00:00.014 0.0%12 0.000 1.167 7.000
TOTAL: 00:00:44.77900:00:26.888 60.0%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
newsmaster@carnet.hr000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 793 24178692412221 2343 3305 99%491:28:47
2news.nask.pl 85 278578 5867 256610 16101 2%48:59:09
3news.icm.edu.pl 45 222 91 102 29 40%16:03:21
4localhost 6 5 5 0 0 100%00:00:00
TOTAL: 4 929 26966742418184 259055 19435 89%556:31:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl732.0 GB629.0 MB708.8 MB733.3 GB 99%318.3 KB
2news.nask.pl1.0 GB5.7 GB5.7 GB12.4 GB 8%592.5 KB
3news.icm.edu.pl1.4 MB58.9 KB655.4 KB2.1 MB 66%17.6 KB
4localhost7.1 KB0.0 KB0.0 KB7.1 KB100%1.4 KB
TOTAL: 4733.0 GB6.3 GB6.4 GB745.7 GB 98%320.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 17 04:15:03 - 04:59:59 74053 3.1% 27.4626.6 GB 3.6%10337.11
Sep 17 05:00:00 - 05:59:59 112180 4.6% 31.1636.7 GB 5.0%10684.60
Sep 17 06:00:00 - 06:59:59 94375 3.9% 26.2237.3 GB 5.1%10865.30
Sep 17 07:00:00 - 07:59:59 71236 2.9% 19.7927.3 GB 3.7%7941.96
Sep 17 08:00:00 - 08:59:59 83507 3.5% 23.2029.5 GB 4.0%8589.80
Sep 17 09:00:00 - 09:59:59 93326 3.9% 25.9231.5 GB 4.3%9173.34
Sep 17 10:00:00 - 10:59:59 93369 3.9% 25.9430.7 GB 4.2%8943.98
Sep 17 11:00:00 - 11:59:59 101078 4.2% 28.0823.0 GB 3.1%6695.00
Sep 17 12:00:00 - 12:59:59 120007 5.0% 33.3426.9 GB 3.7%7843.28
Sep 17 13:00:00 - 13:59:59 96612 4.0% 26.8432.0 GB 4.4%9319.60
Sep 17 14:00:00 - 14:59:59 102596 4.2% 28.5027.1 GB 3.7%7905.90
Sep 17 15:00:00 - 15:59:59 75357 3.1% 20.9324.3 GB 3.3%7063.72
Sep 17 16:00:00 - 16:59:59 79768 3.3% 22.1624.8 GB 3.4%7228.41
Sep 17 17:00:00 - 17:59:59 106372 4.4% 29.5537.6 GB 5.1%10946.95
Sep 17 18:00:00 - 18:59:59 111366 4.6% 30.9339.1 GB 5.3%11384.57
Sep 17 19:00:00 - 19:59:59 102686 4.2% 28.5228.7 GB 3.9%8359.10
Sep 17 20:00:00 - 20:59:59 82764 3.4% 22.9927.4 GB 3.7%7988.70
Sep 17 21:00:00 - 21:59:59 99779 4.1% 27.7237.4 GB 5.1%10906.13
Sep 17 22:00:00 - 22:59:59 119260 4.9% 33.1338.5 GB 5.2%11210.13
Sep 17 23:00:00 - 23:59:59 136486 5.6% 37.9133.0 GB 4.5%9619.20
Sep 18 00:00:00 - 00:59:59 131312 5.4% 36.4822.4 GB 3.1%6517.18
Sep 18 01:00:00 - 01:59:59 136250 5.6% 37.8531.7 GB 4.3%9224.62
Sep 18 02:00:00 - 02:59:59 94425 3.9% 26.2326.6 GB 3.6%7757.12
Sep 18 03:00:00 - 03:59:59 80764 3.3% 22.4326.3 GB 3.6%7652.00
Sep 18 04:00:00 - 04:15:02 19994 0.8% 22.176.9 GB 0.9%8018.96
TOTAL: 23:59:59 2418922 100.0% 28.00733.2 GB 100.0%8899.02
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 16198 7 0 13789 2 0 0 0 2400
2newsfeed.pionier.net.pl 3773 274 0 2713 3 0 0 0 783
3news.icm.edu.pl 26 0 0 6 0 0 0 0 20
TOTAL: 3 19997 281 0 16508 5 0 0 0 3203

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 132
it.tlc.cellulari.android 80
it.comp.os.win.windows10 31
comp.sys.raspberry-pi 7
uk.rec.cycling.moderated 7
comp.mobile.android 6
comp.sys.mac.vintage 4
nl.comp.os.linux.techniek 4
de.comm.software.mailreader 4
uk.net.providers.gradwell 3
it.comp.os.linux.ubuntu 2
comp.mobile.ipad 1
TOTAL: 12 281

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

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

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2217
Binary in non-binary group 354
EMP (phl) 38
Scoring filter (8) 19
Scoring filter (9) 18
Scoring filter (16) 7
Scoring filter (10) 4
Scoring filter (18) 2
Attached HTML file 1
TOTAL: 9 2660

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 462
news.nask.pl 80
news.icm.edu.pl 1
TOTAL: 3 543
TOTAL: 1 543

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 515338 72287 277679 7882 0 21117 14%23:59:55
2panorama 296376 16516 251765 713 10 364838 5%23:59:56
3poznan 4949 81 3655 25 0 0 1%23:59:56
4icm 417672 67 347551 22323 0 3 0%23:59:56
5okapi 0 0 0 0 0 1033 0%23:57:49
TOTAL: 51234335 88951 880650 30943 10 386991 7%119:57:32
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask35.1 GB3.9 GB39.1 GB474.0 KB/s510.8 KB23:59:55
2panorama7.6 GB162.1 MB7.7 GB93.7 KB/s469.7 KB23:59:56
3icm11.9 MB10.1 GB10.1 GB122.2 KB/s471.5 KB23:59:56
4poznan298.0 KB62.8 KB360.8 KB0.0 KB/s3.4 KB23:59:56
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:49
TOTAL: 542.7 GB14.2 GB56.8 GB138.0 KB/s497.1 KB119:57:32
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama11.0 MB
okapi488.3 KB
TOTAL: 211.4 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1215.6 KB 6 0 000:00:23
2isvcs01.man.lodz.pl 5 00.0 KB 0 5 000:00:03
TOTAL: 2 293 1215.6 KB 6 5 000:00:43

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 677 1215.6 KB 6 5 000:00:33
2unresolved 3 00.0 KB 0 0 000:00:10
TOTAL: 2 680 1215.6 KB 6 5 000:00:43

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.700 6.004 0.00000:00:23
107.170.204.240 0.004 0.024 0.00000:00:09
mon.man.lodz.pl 1.548 5.648 0.00000:00:04
isvcs01.man.lodz.pl 0.040 0.148 0.00000:00:03
david.man.lodz.pl 0.472 1.884 0.00000:00:01
71.6.199.23 0.008 0.004 0.00000:00:00
122.228.10.50 0.008 0.024 0.00000:00:00
TOTAL: 7 3.780 13.736 0.00000:00:43

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
107.170.204.240 1
122.228.10.50 1
71.6.199.23 1
TOTAL: 5 387

NNRP unrecognized commands (by host):

SystemConn
122.228.10.50 5
TOTAL: 1 5

NNRP unrecognized commands (by command):

CommandCount
Connection: keep-alive 1
GET / HTTP/1.1 1
User-Agent: Mozilla/5.0 (Macintosh; Inte... 1
Accept: */* 1
Host: 212.191.2.21:119 1
TOTAL: 5 5

Newsgroup request counts (by category):

CategoryCountPct
1pl 12100.0%
TOTAL: 1 12100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 12
TOTAL: 1 12