Daily Usenet report

May 15 04:15:40 -- May 16 04:15:02

Unknown entries from news log file:

First 20 / 20 lines (100.0%)

May 15 10:22:23 newsfeed overchan[11930]: input line too long, skipping
May 15 13:40:08 newsfeed overchan[11930]: input line too long, skipping
May 15 14:20:24 newsfeed overchan[11930]: input line too long, skipping
May 15 15:25:13 newsfeed overchan[11930]: input line too long, skipping
May 15 15:40:08 newsfeed overchan[11930]: input line too long, skipping
May 15 16:22:12 newsfeed overchan[11930]: input line too long, skipping
May 15 16:30:36 newsfeed overchan[11930]: input line too long, skipping
May 15 16:43:03 newsfeed overchan[11930]: input line too long, skipping
May 15 16:59:31 newsfeed overchan[11930]: input line too long, skipping
May 15 17:00:06 newsfeed pgpverify[32238]: pgpverify: verification failed
May 15 17:01:08 newsfeed overchan[11930]: input line too long, skipping
May 15 17:10:36 newsfeed overchan[11930]: input line too long, skipping
May 15 17:50:22 newsfeed overchan[11930]: input line too long, skipping
May 15 18:07:57 newsfeed overchan[11930]: input line too long, skipping
May 15 19:02:41 newsfeed overchan[11930]: input line too long, skipping
May 15 19:40:31 newsfeed overchan[11930]: input line too long, skipping
May 15 20:01:40 newsfeed overchan[11930]: input line too long, skipping
May 15 20:13:37 newsfeed overchan[11930]: input line too long, skipping
May 16 00:37:44 newsfeed overchan[11930]: input line too long, skipping
May 16 02:43:24 newsfeed overchan[11930]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 570808 92.9%62.1 MB 92.1%
innfeed 30254 4.9%3.6 MB 5.3%
innd 8590 1.4%1.3 MB 1.9%
nnrpd 4930 0.8%522.7 KB 0.8%
overchan 20 0.0%3.2 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 614607 100.0%67.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 564897 98.4%
Negative hits 8688 1.5%
Positive hits 217 0.0%
Cache misses 40 0.0%
TOTAL: 4 573842 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:08.307 0.0%80 0.000 103.838 1271.000
article cleanup00:00:07.402 0.0%566367 0.009 0.013 0.020
article logging00:00:27.602 0.0%566891 0.038 0.049 0.059
article parse00:15:59.794 1.1%35125653 0.021 0.027 0.035
article write00:06:48.946 0.5%565865 0.541 0.723 1.398
artlog/artcncl00:00:00.007 0.0%80 0.000 0.087 1.000
data move00:00:34.907 0.0%39191783 0.001 0.001 0.005
hisgrep/artcncl00:00:01.701 0.0%73 0.000 23.301 95.222
hishave/artcncl00:00:00.617 0.0%80 0.000 7.713 70.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup03:06:35.391 13.0%575965 15.205 19.438 64.063
history sync00:00:00.013 0.0%288 0.000 0.045 0.500
history write02:50:19.125 11.9%566324 13.551 18.045 78.288
hiswrite/artcncl00:00:00.066 0.0%7 0.000 9.429 17.500
idle15:33:40.448 65.3%33335500 1.267 1.681 3.823
nntp read00:27:36.308 1.9%34786141 0.037 0.048 0.061
overview write00:00:02.928 0.0%565865 0.002 0.005 0.008
perl filter01:15:10.537 5.3%566324 6.188 7.965 10.653
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:33.276 0.0%1147211 0.023 0.029 0.188
TOTAL: 23:50:19.28423:38:07.375 99.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.140 0.0%45440 0.000 0.003 0.016
article prepare00:00:00.003 0.0%4876 0.000 0.001 0.031
article read00:00:17.519 0.0%1221 0.000 14.348 298.000
backlog stats00:00:00.088 0.0%95303 0.000 0.001 0.005
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:00.929 0.0%50329 0.000 0.018 0.036
data write00:00:02.334 0.0%35059 0.017 0.067 0.118
idle23:53:11.524 99.9%95303 639.755 902.296 1862.716
status file00:01:03.182 0.1%10636 0.672 5.940 340.365
TOTAL: 23:54:49.96223:54:35.719100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:13:11.847 98.5%3119 0.000 253.878 64440.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.229 0.0%7054 0.000 0.032 0.500
readart00:00:00.004 0.0%112 0.000 0.036 1.000
TOTAL: 00:13:23.52200:13:12.080 98.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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
group-admin@isc.org000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 137 569984 569550 0 434 99%111:21:50
2news.icm.edu.pl 46 245 36 141 68 14%15:34:30
3localhost 1441 0 0 0 0 0%00:00:28
TOTAL: 3 1624 570229 569586 141 502 99%126:56:48
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl259.6 GB994.8 KB2.0 MB259.6 GB 99%477.7 KB
2news.icm.edu.pl1.3 MB179.3 KB1.8 MB3.3 MB 39%32.1 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 3259.6 GB1.1 MB3.9 MB259.6 GB 99%477.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 15 04:15:40 - 04:59:59 14774 2.6% 5.556.9 GB 2.7%2738.59
May 15 05:00:00 - 05:59:59 23190 4.1% 6.4410.6 GB 4.1%3093.76
May 15 06:00:00 - 06:59:59 26545 4.7% 7.3711.7 GB 4.5%3405.23
May 15 07:00:00 - 07:59:59 26304 4.6% 7.3111.4 GB 4.4%3312.63
May 15 08:00:00 - 08:59:59 26092 4.6% 7.2511.9 GB 4.6%3454.29
May 15 09:00:00 - 09:59:59 23689 4.2% 6.5812.2 GB 4.7%3553.75
May 15 10:00:00 - 10:59:59 24592 4.3% 6.8312.2 GB 4.7%3557.75
May 15 11:00:00 - 11:59:59 26241 4.6% 7.2912.6 GB 4.9%3683.26
May 15 12:00:00 - 12:59:59 23981 4.2% 6.6611.2 GB 4.3%3248.36
May 15 13:00:00 - 13:59:59 22640 4.0% 6.2911.2 GB 4.3%3263.99
May 15 14:00:00 - 14:59:59 20121 3.5% 5.599.4 GB 3.6%2745.23
May 15 15:00:00 - 15:59:59 21168 3.7% 5.889.9 GB 3.8%2884.70
May 15 16:00:00 - 16:59:59 21431 3.8% 5.9510.6 GB 4.1%3099.50
May 15 17:00:00 - 17:59:59 25036 4.4% 6.9511.9 GB 4.6%3469.00
May 15 18:00:00 - 18:59:59 24508 4.3% 6.8111.1 GB 4.3%3223.64
May 15 19:00:00 - 19:59:59 24171 4.2% 6.7111.1 GB 4.2%3220.05
May 15 20:00:00 - 20:59:59 24252 4.3% 6.7411.0 GB 4.2%3210.44
May 15 21:00:00 - 21:59:59 25069 4.4% 6.9610.7 GB 4.1%3118.63
May 15 22:00:00 - 22:59:59 21262 3.7% 5.918.9 GB 3.4%2589.64
May 15 23:00:00 - 23:59:59 24263 4.3% 6.749.9 GB 3.8%2879.24
May 16 00:00:00 - 00:59:59 24667 4.3% 6.8510.4 GB 4.0%3031.09
May 16 01:00:00 - 01:59:59 27475 4.8% 7.6312.1 GB 4.6%3512.59
May 16 02:00:00 - 02:59:59 21635 3.8% 6.019.2 GB 3.5%2674.77
May 16 03:00:00 - 03:59:59 20856 3.7% 5.799.5 GB 3.6%2753.03
May 16 04:00:00 - 04:15:02 5739 1.0% 6.362.8 GB 1.1%3217.37
TOTAL: 23:59:22 569701 100.0% 6.60260.3 GB 100.0%3160.32
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 959 400 0 31 3 0 0 0 525
2news.icm.edu.pl 68 0 0 12 0 0 0 0 56
TOTAL: 2 1027 400 0 43 3 0 0 0 581

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 276
it.comp.os.win.windows10 56
it.tlc.cellulari.android 36
comp.mobile.android 16
nl.comp.os.linux.techniek 8
it.discussioni.energia 4
it.comp.os.win.windows7 3
de.rec.sf.startrek 1
TOTAL: 8 400

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

GroupsCount
pl.sci.matematyka 2
pl.praca.oferowana 1
TOTAL: 2 3

INND Perl filter [Top 20]:

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

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1panorama 31603 579 13683 148 4 31196 1%23:59:44
2icm 17588 162 15151 21 0 0 0%24:06:40
3nask 0 0 0 0 0 10331 0%24:01:20
4poznan 33 0 24 0 0 0 0%03:43:57
5okapi 0 0 0 0 0 1034 0%24:00:45
TOTAL: 5 49224 741 28858 169 4 42561 1%99:52:26
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1panorama2.1 MB559.5 KB2.6 MB0.0 KB/s3.7 KB23:59:44
2icm431.8 KB1.3 MB1.7 MB0.0 KB/s9.8 KB24:06:40
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s024:01:20
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s003:43:57
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:45
TOTAL: 52.5 MB1.9 MB4.4 MB0.0 KB/s4.9 KB99:52:26
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 112109.6 KB 46 0 000:00:18
TOTAL: 1 288 112109.6 KB 46 0 000:13:23

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 576 112109.6 KB 46 0 000:00:21
2unresolved 100 00.0 KB 0 0 000:00:04
3*.play-internet.pl 2 00.0 KB 0 0 000:12:53
4*.security.ipip.net 1 00.0 KB 0 0 000:00:00
5*.shodan.io 1 00.0 KB 0 0 000:00:02
TOTAL: 5 680 112109.6 KB 46 0 000:13:23

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
user-5-173-180-123.play-internet.pl 0.012 0.016 0.00000:12:53
web.man.lodz.pl 1.452 2.696 0.00000:00:18
80.111.142.213 0.016 0.040 0.00000:00:03
mon.man.lodz.pl 1.208 2.376 0.00000:00:03
census8.shodan.io 0.008 0.012 0.00000:00:02
212.51.192.185 0.456 0.816 0.00000:00:01
scan-141.security.ipip.net 0.008 0.008 0.00000:00:00
TOTAL: 7 3.160 5.964 0.00000:13:23

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 288
212.51.192.185 96
80.111.142.213 4
user-5-173-180-123.play-internet.pl 2
scan-141.security.ipip.net 1
census8.shodan.io 1
TOTAL: 6 392

NNRP unrecognized commands (by host):

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

NNRP unrecognized commands (by command):

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

Newsgroup request counts (by category):

CategoryCountPct
1pl 112100.0%
TOTAL: 1 112100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 66
2pl.comp.pecet 46
TOTAL: 2 112