Daily Usenet report

Sep 2 04:15:04 -- Sep 3 04:15:02

Unknown entries from news log file:

First 21 / 21 lines (100.0%)

Sep 2 08:10:22 newsfeed overchan[29231]: input line too long, skipping
Sep 2 08:11:06 newsfeed overchan[29231]: input line too long, skipping
Sep 2 10:30:02 newsfeed pgpverify[11378]: pgpverify: verification failed
Sep 2 11:23:26 newsfeed overchan[29231]: input line too long, skipping
Sep 2 12:25:56 newsfeed overchan[29231]: input line too long, skipping
Sep 2 12:26:10 newsfeed overchan[29231]: input line too long, skipping
Sep 2 14:52:38 newsfeed overchan[29231]: input line too long, skipping
Sep 2 15:11:12 newsfeed overchan[29231]: input line too long, skipping
Sep 2 15:42:35 newsfeed overchan[29231]: input line too long, skipping
Sep 2 16:54:43 newsfeed overchan[29231]: input line too long, skipping
Sep 2 19:22:31 newsfeed overchan[29231]: input line too long, skipping
Sep 2 20:28:16 newsfeed overchan[29231]: input line too long, skipping
Sep 2 20:28:48 newsfeed overchan[29231]: input line too long, skipping
Sep 2 20:55:45 newsfeed overchan[29231]: input line too long, skipping
Sep 2 22:17:47 newsfeed overchan[29231]: input line too long, skipping
Sep 3 01:10:23 newsfeed overchan[29231]: input line too long, skipping
Sep 3 01:27:51 newsfeed overchan[29231]: input line too long, skipping
Sep 3 01:35:44 newsfeed overchan[29231]: input line too long, skipping
Sep 3 01:43:43 newsfeed overchan[29231]: input line too long, skipping
Sep 3 01:47:06 newsfeed overchan[29231]: input line too long, skipping
Sep 3 02:25:22 newsfeed overchan[29231]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2342266 97.4%271.5 MB 97.1%
innfeed 39822 1.7%4.7 MB 1.7%
innd 17858 0.7%2.9 MB 1.0%
nnrpd 4732 0.2%501.1 KB 0.2%
overchan 21 0.0%3.7 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 72404704 100.0%279.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2306297 93.1%
Positive hits 115308 4.7%
Negative hits 54822 2.2%
Cache misses 1406 0.1%
TOTAL: 4 2477833 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:09.097 0.0%415 0.000 21.920 238.667
article cleanup00:00:31.007 0.0%2321417 0.009 0.013 0.019
article logging00:01:56.361 0.1%2321896 0.030 0.050 0.226
article parse00:49:28.010 3.4%96977679 0.018 0.031 0.041
article write00:18:33.950 1.3%2316748 0.258 0.481 0.758
artlog/artcncl00:00:00.011 0.0%415 0.000 0.027 0.333
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:04:50.491 0.3%111417251 0.001 0.003 0.004
hisgrep/artcncl00:00:03.607 0.0%412 0.000 8.755 135.667
hishave/artcncl00:00:00.942 0.0%415 0.000 2.270 13.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:36:23.594 2.5%2491088 0.452 0.877 2.675
history sync00:00:00.018 0.0%290 0.000 0.062 1.000
history write01:50:57.643 7.7%2318201 0.685 2.872 8.640
hiswrite/artcncl00:00:00.004 0.0%3 0.000 1.333 4.000
idle13:46:19.107 57.4%74405977 0.457 0.666 2.078
nntp read01:25:09.795 5.9%95297940 0.034 0.054 0.072
overview write00:00:11.570 0.0%2316748 0.003 0.005 0.007
perl filter04:33:41.328 19.0%2318201 4.306 7.084 9.814
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:37.918 0.3%4912415 0.037 0.057 0.076
TOTAL: 24:00:00.96023:32:54.453 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:01.506 0.0%487502 0.000 0.003 0.007
article prepare00:00:00.163 0.0%672592 0.000 0.000 0.001
article read00:04:14.972 0.3%159209 0.358 1.601 21.477
backlog stats00:00:02.808 0.0%2804261 0.000 0.001 0.002
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:19.731 0.0%1046342 0.011 0.019 0.030
data write00:10:49.858 0.8%1782975 0.058 0.364 0.553
idle23:33:32.536 98.8%2804261 12.845 30.244 606.527
status file00:00:19.448 0.0%16953 0.687 1.147 3.024
TOTAL: 23:50:33.42023:49:21.022 99.9%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:24.123 68.1%2995 0.000 8.054 4966.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.272 0.8%6607 0.000 0.041 1.333
readart00:00:00.000 0.0%16 0.000 0.000 0.000
TOTAL: 00:00:35.41000:00:24.395 68.9%----

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
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 1050 23376302333872 2175 1583 99%383:48:18
2news.nask.pl 2 118527 3801 111639 3087 3%24:00:26
3news.icm.edu.pl 35 264 105 157 2 39%15:36:08
4localhost 1 0 0 0 0 0%00:00:00
TOTAL: 4 1088 24564212337778 113971 4672 95%423:24:52
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl765.9 GB17.8 MB47.4 MB766.0 GB 99%343.9 KB
2news.nask.pl131.3 MB148.0 MB155.4 MB434.7 MB 30%64.6 KB
3news.icm.edu.pl2.2 MB0.0 KB145.3 KB2.4 MB 94%22.9 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 4766.1 GB165.8 MB202.9 MB766.4 GB 99%343.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 2 04:15:04 - 04:59:59 60953 2.6% 22.6122.3 GB 2.9%8692.42
Sep 2 05:00:00 - 05:59:59 94860 4.1% 26.3537.3 GB 4.9%10878.96
Sep 2 06:00:00 - 06:59:59 91602 3.9% 25.4535.7 GB 4.7%10387.27
Sep 2 07:00:00 - 07:59:59 73831 3.2% 20.5127.7 GB 3.6%8071.43
Sep 2 08:00:00 - 08:59:59 98879 4.2% 27.4737.7 GB 4.9%10969.36
Sep 2 09:00:00 - 09:59:59 104691 4.5% 29.0838.9 GB 5.1%11316.02
Sep 2 10:00:00 - 10:59:59 116813 5.0% 32.4539.2 GB 5.1%11425.18
Sep 2 11:00:00 - 11:59:59 86976 3.7% 24.1627.3 GB 3.6%7947.15
Sep 2 12:00:00 - 12:59:59 76169 3.3% 21.1626.4 GB 3.4%7685.44
Sep 2 13:00:00 - 13:59:59 86828 3.7% 24.1232.6 GB 4.3%9499.01
Sep 2 14:00:00 - 14:59:59 98074 4.2% 27.2432.1 GB 4.2%9361.67
Sep 2 15:00:00 - 15:59:59 91387 3.9% 25.3934.6 GB 4.5%10084.54
Sep 2 16:00:00 - 16:59:59 128449 5.5% 35.6836.5 GB 4.8%10632.48
Sep 2 17:00:00 - 17:59:59 108950 4.7% 30.2637.6 GB 4.9%10964.21
Sep 2 18:00:00 - 18:59:59 116907 5.0% 32.4737.5 GB 4.9%10935.35
Sep 2 19:00:00 - 19:59:59 106862 4.6% 29.6833.8 GB 4.4%9832.78
Sep 2 20:00:00 - 20:59:59 83134 3.6% 23.0932.1 GB 4.2%9364.16
Sep 2 21:00:00 - 21:59:59 56934 2.4% 15.8121.0 GB 2.7%6120.45
Sep 2 22:00:00 - 22:59:59 94711 4.1% 26.3130.3 GB 4.0%8829.97
Sep 2 23:00:00 - 23:59:59 73698 3.2% 20.4725.4 GB 3.3%7406.43
Sep 3 00:00:00 - 00:59:59 60706 2.6% 16.8621.3 GB 2.8%6198.40
Sep 3 01:00:00 - 01:59:59 119078 5.1% 33.0829.9 GB 3.9%8714.25
Sep 3 02:00:00 - 02:59:59 180324 7.7% 50.0936.3 GB 4.7%10573.66
Sep 3 03:00:00 - 03:59:59 82817 3.5% 23.0025.9 GB 3.4%7555.84
Sep 3 04:00:00 - 04:15:02 43068 1.8% 47.755.8 GB 0.8%6702.13
TOTAL: 23:59:58 2336701 100.0% 27.05765.4 GB 100.0%9289.50
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 3152 5 0 1860 1 0 0 0 1286
2newsfeed.pionier.net.pl 1996 128 0 1356 1 0 0 0 511
3news.icm.edu.pl 2 0 0 0 0 0 0 0 2
TOTAL: 3 5150 133 0 3216 2 0 0 0 1799

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 81
it.tlc.cellulari.android 18
it.comp.os.win.windows10 10
comp.mobile.android 9
uk.net.providers.gradwell 4
de.comp.office-pakete.ms-office.outlook 4
comp.sys.raspberry-pi 2
it.comp.os.win.windows7 2
it.discussioni.energia 1
de.rec.sf.startrek 1
comp.mobile.ipad 1
TOTAL: 11 133

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

GroupsCount
pl.sci.matematyka 1
bit.listserv.catholic 1
TOTAL: 2 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1094
Binary in non-binary group 127
EMP (phl) 58
Scoring filter (8) 14
Scoring filter (16) 13
Scoring filter (9) 5
EMP (fsl) 5
Scoring filter (10) 2
Scoring filter (12) 1
HTML post 1
Too many newsgroups 1
TOTAL: 11 1321

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 413
news.nask.pl 65
TOTAL: 2 478
TOTAL: 1 478

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 347871 154838 113798 17 0 6825 44%23:59:54
2panorama 225483 934 196239 213 4 285190 0%23:59:55
3poznan 5210 172 3538 6 0 0 3%23:59:54
4icm 338354 52 266173 11839 0 3 0%23:59:55
5okapi 0 0 0 0 0 885 0%23:57:48
TOTAL: 5 916918 155996 579748 12075 4 292903 17%119:57:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask95.7 GB8.9 MB95.7 GB1.1 MB/s648.1 KB23:59:54
2panorama116.6 MB21.5 MB138.1 MB1.6 KB/s123.3 KB23:59:55
3icm7.4 MB7.3 GB7.3 GB88.8 KB/s645.0 KB23:59:55
4poznan816.3 KB21.8 KB838.1 KB0.0 KB/s4.7 KB23:59:54
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:48
TOTAL: 595.8 GB7.3 GB103.2 GB250.5 KB/s643.6 KB119:57:26
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama7.6 MB
TOTAL: 17.6 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 1617.2 KB 7 0 000:00:24
TOTAL: 1 288 1617.2 KB 7 0 000:00:34

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 672 1617.2 KB 7 0 000:00:29
2unresolved 1 00.0 KB 0 0 000:00:00
3*.census.shodan.io 1 00.0 KB 0 0 000:00:05
TOTAL: 3 674 1617.2 KB 7 0 000:00:34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.412 4.352 0.00000:00:24
sky.census.shodan.io 0.000 0.008 0.00000:00:05
mon.man.lodz.pl 1.348 4.348 0.00000:00:03
david.man.lodz.pl 0.424 1.440 0.00000:00:01
122.228.10.50 0.000 0.024 0.00000:00:00
TOTAL: 5 3.184 10.172 0.00000:00:34

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
david.man.lodz.pl 96
sky.census.shodan.io 1
122.228.10.50 1
TOTAL: 4 386

NNRP unrecognized commands (by host):

SystemConn
122.228.10.50 5
TOTAL: 1 5

NNRP unrecognized commands (by command):

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

Newsgroup request counts (by category):

CategoryCountPct
1pl 16100.0%
TOTAL: 1 16100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 12
2pl.comp.pecet 4
TOTAL: 2 16