Daily Usenet report

Aug 24 04:15:01 -- Aug 25 04:15:01

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

Aug 24 10:18:11 newsfeed overchan[439153]: input line too long, skipping
Aug 24 10:37:09 newsfeed overchan[439153]: input line too long, skipping
Aug 24 12:30:55 newsfeed overchan[439153]: input line too long, skipping
Aug 24 13:01:52 newsfeed overchan[439153]: input line too long, skipping
Aug 24 18:02:15 newsfeed overchan[439153]: input line too long, skipping
Aug 24 19:38:14 newsfeed overchan[439153]: input line too long, skipping
Aug 24 19:45:03 newsfeed overchan[439153]: input line too long, skipping
Aug 24 19:45:44 newsfeed overchan[439153]: input line too long, skipping
Aug 24 19:49:30 newsfeed overchan[439153]: input line too long, skipping
Aug 24 19:56:48 newsfeed overchan[439153]: input line too long, skipping
Aug 24 20:10:47 newsfeed overchan[439153]: input line too long, skipping
Aug 24 21:08:57 newsfeed overchan[439153]: input line too long, skipping
Aug 24 21:55:34 newsfeed overchan[439153]: input line too long, skipping
Aug 25 00:03:09 newsfeed overchan[439153]: input line too long, skipping
Aug 25 00:08:06 newsfeed overchan[439153]: input line too long, skipping
Aug 25 00:08:18 newsfeed overchan[439153]: input line too long, skipping
Aug 25 00:09:17 newsfeed overchan[439153]: input line too long, skipping
Aug 25 02:44:24 newsfeed overchan[439153]: input line too long, skipping
Aug 25 03:35:14 newsfeed overchan[439153]: input line too long, skipping
Aug 25 03:47:41 newsfeed overchan[439153]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 172285 94.0%19.6 MB 94.5%
nnrpd 7640 4.2%771.9 KB 3.6%
innd 2042 1.1%238.8 KB 1.1%
innfeed 1352 0.7%151.5 KB 0.7%
overchan 20 0.0%2.3 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 6 183340 100.0%20.7 MB100.0%

History cache:

ReasonCount%Count
Do not exist 171089 98.4%
Negative hits 2258 1.3%
Positive hits 427 0.2%
Cache misses 28 0.0%
TOTAL: 4 173802 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.394 0.0%52 0.000 7.577 20.167
article cleanup00:00:00.932 0.0%172165 0.000 0.005 0.013
article logging00:00:03.905 0.0%172435 0.011 0.023 0.039
article parse00:00:05.821 0.0%1054357 0.003 0.006 0.010
article write00:00:23.656 0.0%172016 0.054 0.138 2.991
artlog/artcncl00:00:00.002 0.0%52 0.000 0.038 1.000
data move00:00:01.219 0.0%1421392 0.000 0.001 0.003
hisgrep/artcncl00:00:00.067 0.0%51 0.000 1.314 5.000
hishave/artcncl00:00:00.069 0.0%52 0.000 1.327 4.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:05:02.120 0.3%174811 0.372 1.728 17.812
history sync00:00:00.015 0.0%302 0.000 0.050 0.500
history write00:10:16.346 0.7%172149 1.199 3.580 23.478
hiswrite/artcncl00:00:00.013 0.0%1 13.000 13.000 13.000
idle23:40:47.876 98.6%1199546 16.179 71.067 252.994
nntp read00:00:13.703 0.0%1044362 0.009 0.013 0.026
overview write00:00:00.532 0.0%172016 0.000 0.003 0.008
perl filter00:02:44.260 0.2%172149 0.586 0.954 1.407
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:04.709 0.0%356348 0.006 0.013 0.020
TOTAL: 24:00:18.67423:59:45.639100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.025 0.0%12308 0.000 0.002 0.034
article prepare00:00:00.000 0.0%192 0.000 0.000 0.000
article read00:00:00.003 0.0%48 0.000 0.062 1.000
backlog stats00:00:00.042 0.0%61832 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.275 0.0%31532 0.000 0.009 0.028
data write00:00:00.827 0.0%19259 0.000 0.043 0.115
idle23:58:18.751100.0%61832 692.115 1395.697 2933.976
status file00:00:00.202 0.0%368 0.000 0.549 3.000
TOTAL: 23:58:21.09323:58:20.125100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:30.900 53.4%4165 0.000 7.419 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.141 0.2%10092 0.000 0.014 0.500
readart00:00:00.000 0.0%8 0.000 0.000 0.000
TOTAL: 00:00:57.87000:00:31.041 53.6%----

Control commands to INND:

CommandNumber
flush 27
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 193

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 90 172182 172044 4 134 99%46:55:02
2news.icm.edu.pl 36 419 20 383 16 4%19:43:49
3localhost 1 0 0 0 0 0%00:00:00
TOTAL: 3 127 172601 172064 387 150 99%66:38:51
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl2.5 GB345.8 KB735.2 KB2.5 GB 99%15.5 KB
2news.icm.edu.pl881.5 KB9.2 KB373.3 KB1.2 MB 69%35.1 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 32.5 GB354.9 KB1.1 MB2.5 GB 99%15.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 24 04:15:01 - 04:59:59 5345 3.1% 1.9873.6 MB 2.8% 27.94
Aug 24 05:00:00 - 05:59:59 5592 3.3% 1.5579.8 MB 3.1% 22.69
Aug 24 06:00:00 - 06:59:59 4568 2.7% 1.2784.3 MB 3.2% 23.99
Aug 24 07:00:00 - 07:59:59 5181 3.0% 1.4466.9 MB 2.6% 19.03
Aug 24 08:00:00 - 08:59:59 8424 4.9% 2.34106.0 MB 4.1% 30.16
Aug 24 09:00:00 - 09:59:59 8668 5.0% 2.41105.2 MB 4.1% 29.92
Aug 24 10:00:00 - 10:59:59 9279 5.4% 2.58108.5 MB 4.2% 30.85
Aug 24 11:00:00 - 11:59:59 8218 4.8% 2.28107.3 MB 4.1% 30.53
Aug 24 12:00:00 - 12:59:59 8357 4.9% 2.32164.4 MB 6.3% 46.78
Aug 24 13:00:00 - 13:59:59 9762 5.7% 2.71230.8 MB 8.9% 65.66
Aug 24 14:00:00 - 14:59:59 8064 4.7% 2.2480.4 MB 3.1% 22.86
Aug 24 15:00:00 - 15:59:59 6490 3.8% 1.8066.1 MB 2.5% 18.79
Aug 24 16:00:00 - 16:59:59 8706 5.1% 2.42136.2 MB 5.2% 38.75
Aug 24 17:00:00 - 17:59:59 7371 4.3% 2.0589.9 MB 3.5% 25.56
Aug 24 18:00:00 - 18:59:59 8721 5.1% 2.42132.3 MB 5.1% 37.63
Aug 24 19:00:00 - 19:59:59 7784 4.5% 2.1695.0 MB 3.7% 27.01
Aug 24 20:00:00 - 20:59:59 6623 3.9% 1.84135.1 MB 5.2% 38.44
Aug 24 21:00:00 - 21:59:59 7817 4.5% 2.17259.6 MB 10.0% 73.85
Aug 24 22:00:00 - 22:59:59 3732 2.2% 1.0436.6 MB 1.4% 10.41
Aug 24 23:00:00 - 23:59:59 4581 2.7% 1.2782.5 MB 3.2% 23.45
Aug 25 00:00:00 - 00:59:59 5514 3.2% 1.5397.8 MB 3.8% 27.83
Aug 25 01:00:00 - 01:59:59 7551 4.4% 2.10116.1 MB 4.5% 33.02
Aug 25 02:00:00 - 02:59:59 7484 4.4% 2.0877.1 MB 3.0% 21.93
Aug 25 03:00:00 - 03:59:59 7296 4.2% 2.0356.7 MB 2.2% 16.13
Aug 25 04:00:00 - 04:15:01 687 0.4% 0.768.0 MB 0.3% 9.15
TOTAL: 24:00:00 171815 100.0% 1.992.5 GB 100.0% 30.77
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 402 118 0 16 0 0 0 0 268
2news.icm.edu.pl 16 0 0 1 0 0 0 0 15
TOTAL: 2 418 118 0 17 0 0 0 0 283

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 72
comp.sys.raspberry-pi 15
nl.comp.os.linux.techniek 12
de.comp.lang.vbclassic 6
it.comp.os.win.windows10 5
alt.comp.software.firefox 3
comp.mobile.android 2
it.tlc.cellulari.android 1
it.discussioni.energia 1
it.tlc.cellulari.iphone 1
TOTAL: 10 118

INND Perl filter [Top 20]:

ReasonCount
Binary in non-binary group 7
EMP (md5) 7
Scoring filter (8) 1
TOTAL: 3 15

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 268
TOTAL: 1 268
TOTAL: 1 268

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 19236 32 12079 15 0 0 0%23:57:37
2poznan 29 1 18 0 0 0 3%03:09:34
3okapi 0 0 0 0 0 672 0%23:50:02
4panorama 0 0 0 0 0 12267 0%23:50:02
5nask 0 0 0 0 0 8204 0%23:50:02
TOTAL: 5 19265 33 12097 15 0 21143 0%98:37:17
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm131.0 KB44.3 KB175.4 KB0.0 KB/s3.7 KB23:57:37
2poznan15.8 KB0.0 KB15.8 KB0.0 KB/s15.8 KB03:09:34
3okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
4panorama0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
5nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
TOTAL: 5146.8 KB44.3 KB191.2 KB0.0 KB/s4.0 KB98:37:17
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama976.7 KB
TOTAL: 1976.7 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 88.9 KB 4 0 000:00:08
TOTAL: 1 287 88.9 KB 4 0 000:00:58

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl1727 88.9 KB 4 0 000:00:22
2unresolved 106 00.0 KB 0 0 000:00:34
3*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:01
4*.149.216.162.bc.googleusercontent.com 1 00.0 KB 0 0 000:00:00
TOTAL: 41835 88.9 KB 4 0 000:00:58

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
nms2.man.lodz.pl 4.043 6.374 0.00000:00:13
107.155.55.64 0.010 0.017 0.00000:00:11
194.165.16.37 0.005 0.002 0.00000:00:10
139.162.225.221 0.007 0.022 0.00000:00:10
web.man.lodz.pl 0.983 1.209 0.00000:00:08
80.111.142.213 0.017 0.010 0.00000:00:02
scanner-29.ch1.censys-scanner.com 0.009 0.000 0.00000:00:01
212.51.192.185 0.311 0.428 0.00000:00:01
198.199.113.46 0.000 0.006 0.00000:00:00
65.149.216.162.bc.googleusercontent.com 0.003 0.003 0.00000:00:00
TOTAL: 10 5.388 8.071 0.00000:00:58

Curious NNRP server explorers [Top 20]:

SystemConn
nms2.man.lodz.pl 1440
212.51.192.185 96
80.111.142.213 4
107.155.55.64 3
194.165.16.37 1
65.149.216.162.bc.googleusercontent.com 1
198.199.113.46 1
scanner-29.ch1.censys-scanner.com 1
139.162.225.221 1
TOTAL: 9 1548

NNRP unrecognized commands (by host):

SystemConn
65.149.216.162.bc.googleusercontent.com 3
107.155.55.64 2
scanner-29.ch1.censys-scanner.com 1
198.199.113.46 1
194.165.16.37 1
TOTAL: 5 8

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
Host: 212.191.2.21:119 1
GET / HTTP/1.1 1
MGLNDD_212.191.2.21_119 1
#023#003#023#001#023#002#001 1
#003 1
#026#003#001#001#011#001 1
User-Agent: Expanse, a Palo Alto Network... 1
TOTAL: 8 8

NNRP client timeouts [Top 20]:

SystemConnPeer
194.165.16.37 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 8100.0%
TOTAL: 1 8100.0%

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

NewsgroupCount
1pl.comp.pecet 6
2pl.comp.os.ms-windows.winnt 2
TOTAL: 2 8