Daily Usenet report

May 24 04:15:02 -- May 25 04:15:02

Unknown entries from news log file:

First 16 / 16 lines (100.0%)

May 24 06:38:02 newsfeed overchan[23617]: input line too long, skipping
May 24 10:35:01 newsfeed overchan[23617]: input line too long, skipping
May 24 15:07:26 newsfeed overchan[1320]: input line too long, skipping
May 24 15:24:45 newsfeed overchan[1320]: input line too long, skipping
May 24 15:46:23 newsfeed overchan[1320]: input line too long, skipping
May 24 15:59:22 newsfeed overchan[1320]: input line too long, skipping
May 24 16:07:38 newsfeed overchan[1320]: input line too long, skipping
May 24 16:08:00 newsfeed overchan[1320]: input line too long, skipping
May 24 19:11:37 newsfeed overchan[1320]: input line too long, skipping
May 24 19:31:17 newsfeed overchan[1320]: input line too long, skipping
May 24 19:53:41 newsfeed pgpverify[26727]: pgpverify: verification failed
May 24 19:53:41 newsfeed controlchan[1318]: skipping newgroup y control@usenet-fr.news.eu.org (pgpverify failed) in <newgroup-fr.misc.domotique-1653413713@news.trigofacile.com>
May 24 19:56:10 newsfeed pgpverify[26929]: pgpverify: verification failed
May 24 20:21:36 newsfeed overchan[1320]: input line too long, skipping
May 24 20:27:08 newsfeed overchan[1320]: input line too long, skipping
May 24 22:17:25 newsfeed overchan[1320]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn1060492 94.4%121.6 MB 94.0%
innfeed 46912 4.2%5.5 MB 4.3%
innd 12081 1.1%1.9 MB 1.5%
nnrpd 3305 0.3%309.4 KB 0.2%
controlchan 14 0.0%2.9 KB 0.0%
overchan 13 0.0%2.4 KB 0.0%
pgpverify 2 0.0%0.4 KB 0.0%
TOTAL: 71122819 100.0%129.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 1053122 99.6%
Negative hits 3966 0.4%
Positive hits 204 0.0%
Cache misses 17 0.0%
TOTAL: 4 1057309 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.530 0.0%43 0.000 12.326 69.000
article cleanup00:00:06.788 0.0%1055469 0.004 0.006 0.010
article logging00:00:21.590 0.0%1055837 0.014 0.020 0.029
article parse00:10:35.524 0.7%143979014 0.003 0.004 0.006
article write00:05:02.499 0.4%1055325 0.159 0.287 0.499
artlog/artcncl00:00:00.000 0.0%43 0.000 0.000 0.000
data move00:01:52.710 0.1%151080391 0.001 0.001 0.001
hisgrep/artcncl00:00:00.102 0.0%39 0.000 2.615 16.000
hishave/artcncl00:00:00.051 0.0%43 0.000 1.186 7.286
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:14:37.366 1.0%1059610 0.093 0.828 7.596
history sync00:00:00.006 0.0%288 0.000 0.021 1.000
history write00:04:40.601 0.3%1055456 0.152 0.266 5.275
hiswrite/artcncl00:00:00.000 0.0%4 0.000 0.000 0.000
idle21:34:07.501 90.5%141903270 0.405 0.547 0.908
nntp read00:28:23.937 2.0%143359847 0.010 0.012 0.014
overview write00:00:03.456 0.0%1055325 0.002 0.003 0.006
perl filter00:52:07.293 3.6%1055456 2.262 2.963 4.118
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:25.247 0.0%2121031 0.009 0.012 0.015
TOTAL: 23:50:02.78023:32:25.201 98.8%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.136 0.0%99334 0.000 0.001 0.010
article prepare00:00:00.037 0.0%66380 0.000 0.001 0.009
article read00:00:44.093 0.1%16787 0.000 2.627 188.000
backlog stats00:00:00.178 0.0%176222 0.000 0.001 0.004
callbacks00:00:00.003 0.0%109 0.000 0.028 0.031
data read00:00:00.779 0.0%87703 0.000 0.009 0.025
data write00:00:01.595 0.0%77927 0.004 0.020 0.055
idle23:42:45.273 99.9%176222 7.990 484.419 1513.151
status file00:00:55.167 0.1%16369 0.000 3.370 241.539
TOTAL: 23:44:31.78223:44:27.261100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:20.174 72.5%2726 0.000 7.401 10040.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.094 0.3%5765 0.000 0.016 0.500
readart00:00:00.002 0.0%8 0.000 0.250 0.500
TOTAL: 00:00:27.83500:00:20.270 72.8%----

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
control@usenet-fr.news.eu.org001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 428 10595881059450 0 138 99%115:12:48
2news.icm.edu.pl 41 192 13 173 6 6%14:30:15
3localhost 1441 0 0 0 0 0%00:00:00
TOTAL: 3 1910 10597801059463 173 144 99%129:43:03
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl349.9 GB1.1 MB1.4 MB349.9 GB 99%346.2 KB
2news.icm.edu.pl1.1 MB0.0 KB209.8 KB1.3 MB 84%69.3 KB
3localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 3349.9 GB1.1 MB1.6 MB349.9 GB 99%346.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 24 04:15:02 - 04:59:59 19145 1.8% 7.109.1 GB 2.6%3535.59
May 24 05:00:00 - 05:59:59 25347 2.4% 7.0412.0 GB 3.4%3498.95
May 24 06:00:00 - 06:59:59 32317 3.0% 8.9813.6 GB 3.9%3972.40
May 24 07:00:00 - 07:59:59 43216 4.1% 12.0014.3 GB 4.1%4159.57
May 24 08:00:00 - 08:59:59 34160 3.2% 9.4913.6 GB 3.9%3967.38
May 24 09:00:00 - 09:59:59 40459 3.8% 11.2414.1 GB 4.0%4118.70
May 24 10:00:00 - 10:59:59 41405 3.9% 11.5014.2 GB 4.0%4135.06
May 24 11:00:00 - 11:59:59 44795 4.2% 12.4415.6 GB 4.5%4557.93
May 24 12:00:00 - 12:59:59 38658 3.6% 10.7415.0 GB 4.3%4374.36
May 24 13:00:00 - 13:59:59 53940 5.1% 14.9815.9 GB 4.5%4635.32
May 24 14:00:00 - 14:59:59 53378 5.0% 14.8317.3 GB 4.9%5035.77
May 24 15:00:00 - 15:59:59 37666 3.6% 10.4614.8 GB 4.2%4322.66
May 24 16:00:00 - 16:59:59 52912 5.0% 14.7015.7 GB 4.5%4580.97
May 24 17:00:00 - 17:59:59 76624 7.2% 21.2817.3 GB 4.9%5049.46
May 24 18:00:00 - 18:59:59 68045 6.4% 18.9017.3 GB 4.9%5036.92
May 24 19:00:00 - 19:59:59 51244 4.8% 14.2314.6 GB 4.2%4249.50
May 24 20:00:00 - 20:59:59 47457 4.5% 13.1814.7 GB 4.2%4286.82
May 24 21:00:00 - 21:59:59 65857 6.2% 18.2916.4 GB 4.7%4785.72
May 24 22:00:00 - 22:59:59 40349 3.8% 11.2115.5 GB 4.4%4504.59
May 24 23:00:00 - 23:59:59 43892 4.1% 12.1914.9 GB 4.2%4329.44
May 25 00:00:00 - 00:59:59 31605 3.0% 8.7812.3 GB 3.5%3569.46
May 25 01:00:00 - 01:59:59 36190 3.4% 10.0513.7 GB 3.9%3975.93
May 25 02:00:00 - 02:59:59 43924 4.1% 12.2012.6 GB 3.6%3683.51
May 25 03:00:00 - 03:59:59 29434 2.8% 8.1813.0 GB 3.7%3773.52
May 25 04:00:00 - 04:15:02 7915 0.7% 8.773.0 GB 0.9%3540.06
TOTAL: 24:00:00 1059934 100.0% 12.27350.7 GB 100.0%4255.86
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 509 124 0 13 2 0 0 0 370
2news.icm.edu.pl 6 0 0 0 0 0 0 0 6
TOTAL: 2 515 124 0 13 2 0 0 0 376

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 44
comp.mobile.android 23
it.tlc.cellulari.android 23
alt.comp.software.firefox 16
it.comp.os.win.windows10 9
alt.comp.software.seamonkey 5
comp.mobile.ipad 4
TOTAL: 7 124

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

GroupsCount
pl.praca.oferowana 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3
Binary in non-binary group 3
TOTAL: 2 6

Miscellaneous innd statistics [Top 10]:

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

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 16266 3090 6622 262 0 0 18%23:50:01
2panorama 91682 1710 51219 11014 388 35360 1%23:59:06
3nask 0 0 0 0 0 7102 0%23:40:01
4poznan 4 0 2 0 0 0 0%00:40:10
5okapi 0 0 0 0 0 786 0%23:40:01
TOTAL: 5 107952 4800 57843 11276 388 43248 4%95:49:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm9.9 MB7.5 MB17.4 MB0.2 KB/s5.3 KB23:50:01
2panorama5.3 MB63.4 MB68.7 MB0.8 KB/s5.5 KB23:59:06
3nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:40:01
4poznan0.0 KB0.0 KB0.0 KB0.0 KB/s000:40:10
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:40:01
TOTAL: 515.2 MB70.9 MB86.1 MB0.3 KB/s5.5 KB95:49:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 288 811.4 KB 4 0 000:00:11
TOTAL: 1 288 811.4 KB 4 0 000:00:27

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 288 811.4 KB 4 0 000:00:11
2unresolved 102 00.0 KB 0 0 000:00:13
3*.zbau.f3netze.de 1 00.0 KB 0 0 000:00:02
4*.ch1.censys-scanner.com 1 00.0 KB 0 0 000:00:00
TOTAL: 4 392 811.4 KB 4 0 000:00:27

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 0.996 1.694 0.00000:00:11
188.166.24.50 0.006 0.006 0.00000:00:10
80.111.142.213 0.008 0.032 0.00000:00:02
tor-exit-5.zbau.f3netze.de 0.008 0.000 0.00000:00:02
212.51.192.185 0.294 0.438 0.00000:00:00
scanner-09.ch1.censys-scanner.com 0.003 0.014 0.00000:00:00
192.241.220.188 0.004 0.004 0.00000:00:00
TOTAL: 7 1.319 2.188 0.00000:00:27

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
80.111.142.213 4
scanner-09.ch1.censys-scanner.com 1
192.241.220.188 1
tor-exit-5.zbau.f3netze.de 1
188.166.24.50 1
TOTAL: 6 104

NNRP unrecognized commands (by host):

SystemConn
scanner-09.ch1.censys-scanner.com 1
192.241.220.188 1
TOTAL: 2 2

NNRP unrecognized commands (by command):

CommandCount
EHLO www.censys.io 1
MGLNDD_212.191.2.21_119 1
TOTAL: 2 2

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