Daily Usenet report for paganini.linuxd.org

May 1 00:00:04 -- May 2 00:00:04

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

May 1 02:02:34 paganini pgpverify[26742]: pgpverify: verification failed
May 1 17:20:10 paganini overchan[13148]: input line too long, skipping
May 1 21:00:23 paganini overchan[13148]: input line too long, skipping
May 2 00:00:04 paganini overchan[25718]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 115517 47.6%14.3 MB 43.6%
nnrpd 69901 28.8%8.1 MB 24.5%
inn 38442 15.8%8.0 MB 24.3%
innd 18849 7.8%2.5 MB 7.5%
nocem 124 0.1%21.4 KB 0.1%
controlchan 6 0.0%0.9 KB 0.0%
overchan 4 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 8 242844 100.0%32.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 107901 48.3%
Negative hits 78227 35.0%
Do not exist 37344 16.7%
Cache misses 87 0.0%
TOTAL: 4 223559 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.402 0.0%265 0.000 5.291 54.889
article cleanup00:00:00.250 0.0%37639 0.000 0.007 0.029
article logging00:00:00.332 0.0%38029 0.000 0.009 0.027
article parse00:00:01.091 0.0%89996 0.000 0.012 0.038
article write00:00:48.835 0.1%20179 0.126 2.420 14.304
artlog/artcncl00:00:00.003 0.0%262 0.000 0.011 0.091
data move00:00:00.202 0.0%243266 0.000 0.001 0.024
hisgrep/artcncl00:00:00.002 0.0%263 0.000 0.008 0.100
hishave/artcncl00:00:00.001 0.0%265 0.000 0.004 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.673 0.0%225041 0.014 0.047 0.869
history sync00:00:00.057 0.0%395 0.000 0.144 10.667
history write00:00:33.669 0.0%37637 0.049 0.895 2.989
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:46:51.276 99.7%242445 212.089 353.116 1093.177
nntp read00:00:02.030 0.0%251126 0.001 0.008 0.031
overview write00:00:20.440 0.0%20179 0.000 1.013 5.667
perl filter00:01:26.084 0.1%37637 0.983 2.287 4.943
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.246 0.0%60749 0.000 0.021 0.051
TOTAL: 23:50:58.72823:50:17.593100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.089 0.0%36539 0.000 0.002 0.017
article prepare00:00:00.005 0.0%16444 0.000 0.000 0.009
article read00:00:01.724 0.0%3793 0.000 0.455 27.050
backlog stats00:00:00.227 0.0%288772 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.270 0.0%155129 0.001 0.008 0.019
data write00:00:02.772 0.0%134968 0.009 0.021 0.043
idle23:41:40.280 99.2%288772 207.783 295.390 450.136
status file00:11:19.717 0.8%48375 9.042 14.051 42.397
TOTAL: 23:53:21.53123:53:06.084100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:17:16.680 99.8%70700 38.098 218.341600878.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:10.286 0.1%234998 0.000 0.044 0.105
readart00:00:00.002 0.0%10 0.000 0.200 0.222
TOTAL: 04:17:50.53304:17:26.968 99.8%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 171

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
us-control@lists.killfile.org000100
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 5 43541 17766 23881 1894 40%48:24:46
2newsfeed.neva.ru 143 76147 2050 58710 15387 2%72:07:47
3news.fcku.it 1 7382 448 6788 146 6%23:46:51
4nntp.pasdenom.info 2 17747 97 17642 8 0%23:55:41
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 176 144817 20361 107021 17435 14%168:15:05
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net60.2 MB0.0 KB12.5 MB72.8 MB 82%3.8 KB
2newsfeed.neva.ru5.4 MB20.6 KB137.2 MB142.6 MB 3%8.4 KB
3news.fcku.it4.3 MB0.0 KB3.0 MB7.4 MB 59%12.7 KB
4nntp.pasdenom.info216.3 KB0.0 KB190.1 KB406.4 KB 53%3.9 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 570.2 MB20.6 KB152.9 MB223.1 MB 31%6.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 1 00:00:04 - 00:59:59 838 4.1% 0.233.6 MB 5.2% 1.03
May 1 01:00:00 - 01:59:59 574 2.8% 0.161.9 MB 2.7% 0.53
May 1 02:00:00 - 02:59:59 466 2.3% 0.131.5 MB 2.1% 0.41
May 1 03:00:00 - 03:59:59 410 2.0% 0.111.6 MB 2.3% 0.45
May 1 04:00:00 - 04:59:59 456 2.2% 0.131.4 MB 2.0% 0.40
May 1 05:00:00 - 05:59:59 425 2.1% 0.121.3 MB 1.8% 0.36
May 1 06:00:00 - 06:59:59 529 2.6% 0.152.2 MB 3.2% 0.63
May 1 07:00:00 - 07:59:59 462 2.3% 0.131.6 MB 2.4% 0.46
May 1 08:00:00 - 08:59:59 556 2.7% 0.152.2 MB 3.2% 0.63
May 1 09:00:00 - 09:59:59 771 3.8% 0.212.7 MB 3.9% 0.76
May 1 10:00:00 - 10:59:59 823 4.1% 0.232.2 MB 3.2% 0.63
May 1 11:00:00 - 11:59:59 815 4.0% 0.233.0 MB 4.4% 0.86
May 1 12:00:00 - 12:59:59 830 4.1% 0.233.1 MB 4.6% 0.89
May 1 13:00:00 - 13:59:59 815 4.0% 0.233.1 MB 4.6% 0.90
May 1 14:00:00 - 14:59:59 1012 5.0% 0.283.9 MB 5.6% 1.11
May 1 15:00:00 - 15:59:59 1021 5.0% 0.283.8 MB 5.5% 1.07
May 1 16:00:00 - 16:59:59 1232 6.1% 0.343.6 MB 5.2% 1.03
May 1 17:00:00 - 17:59:59 1202 5.9% 0.333.4 MB 5.0% 0.98
May 1 18:00:00 - 18:59:59 1172 5.8% 0.333.6 MB 5.2% 1.03
May 1 19:00:00 - 19:59:59 1208 6.0% 0.343.8 MB 5.4% 1.07
May 1 20:00:00 - 20:59:59 1302 6.4% 0.363.8 MB 5.5% 1.08
May 1 21:00:00 - 21:59:59 1166 5.7% 0.323.5 MB 5.1% 1.00
May 1 22:00:00 - 22:59:59 1047 5.2% 0.293.9 MB 5.6% 1.11
May 1 23:00:00 - 23:59:59 1134 5.6% 0.324.3 MB 6.2% 1.22
May 2 00:00:00 - 00:00:04 27 0.1% 6.75109.5 KB 0.2% 27.38
TOTAL: 24:00:00 20293 100.0% 0.2369.1 MB 100.0% 0.82
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 15500 258 0 2 0 0 0 015240
2linuxd-out.news.weretis.net 2254 404 0 0 0 0 0 0 1850
3news.fcku.it 150 1 0 0 1 0 0 0 148
4nntp.pasdenom.info 3 0 0 0 0 0 0 0 3
TOTAL: 4 17907 663 0 2 1 0 0 017241

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 124
mpc.lists.freebsd.ports.bugs 49
alt.windows7.general 45
spline.eisfair 37
it.comp.os.win.windows10 31
it-alt.sport.calcio.juventus 31
lada.talk 24
gmane.comp.gis.openstreetmap.tagging 21
mozilla.dev.security.policy 20
lada.commerce 18
mpc.lists.freebsd.gnome 18
alt.survival 15
uk.radio.amateur.moderated 15
cakewalk.audio 14
mozilla.support.thunderbird 14
alt.pl.nieruchomosci.gielda 12
alt.religion.christian.catholic 8
lada.auto 8
spline.eisfair.dev 7
mozilla.test 7
TOTAL: 98 663

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

GroupsCount
alt.culture.china 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15161
EMP (phn path) 1455
EMP (phl) 91
EMP (phn nph) 56
User-issued cancel 30
Too many newsgroups 29
Binary Payload 15
Binary: non-binary distribution 7
HTML post 3
Too many newsgroups (meow) 2
TOTAL: 10 16849

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 368
newsfeed.neva.ru 24
TOTAL: 2 392
TOTAL: 1 392

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 26103 1265 16610 2086 0 50 4%24:05:24
2nntp.pasdenom.info 8329 469 3134 74 0 18685 5%24:01:41
3weretis.net 2753 84 2510 0 0 4 3%24:08:03
4neva.ru 26353 29 18211 124 0 37 0%24:09:33
5eternal-september 22591 21 17771 0 0 12 0%24:09:33
6news.stack.nl 2858 0 0 0 0 23137 0%23:59:53
7news.albasani.net 2858 0 0 0 0 22335 0%24:00:08
8news.mixmin.net 0 0 0 0 0 0 0%00:10:00
9news.netfront.net 2768 0 0 0 0 23024 0%23:59:52
10etla.org 0 0 0 0 0 20446 0%24:09:34
11vc89.vc.panix.com 2841 0 0 0 0 23161 0%23:59:59
12news.killfile.org 2848 0 0 0 0 23169 0%24:00:04
TOTAL: 12 100302 1868 58236 2284 0 154060 1%264:53:44
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it3.8 MB6.3 MB10.1 MB0.1 KB/s3.1 KB24:05:24
2nntp.pasdenom.info1.6 MB299.6 KB1.8 MB0.0 KB/s3.5 KB24:01:41
3weretis.net234.2 KB0.0 KB234.2 KB0.0 KB/s2.8 KB24:08:03
4neva.ru80.8 KB700.6 KB781.4 KB0.0 KB/s5.1 KB24:09:33
5eternal-september57.2 KB0.0 KB57.2 KB0.0 KB/s2.7 KB24:09:33
6news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
7news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:08
8news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
9news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
10etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:09:34
11vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:59
12news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:04
TOTAL: 125.7 MB7.3 MB13.0 MB0.0 KB/s3.2 KB264:53:44
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-188-099-023-198.188.099.pools.vodafone-ip.de 127 1026.1 KB 2 0 001:03:58
TOTAL: 1 127 1026.1 KB 2 0 004:17:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.188.099.pools.vodafone-ip.de 127 1026.1 KB 2 0 001:03:58
2*.census.shodan.io 1 00.0 KB 0 0 000:00:01
3*.drr02.clwr.fl.frontiernet.net 5 00.0 KB 0 0 001:41:58
4unresolved 2 00.0 KB 0 0 000:00:13
5*.nielsenmedia.com 3 00.0 KB 0 0 001:31:38
TOTAL: 5 138 1026.1 KB 2 0 004:17:50

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.268 0.016 0.00001:41:58
challenger.nielsenmedia.com 0.172 0.004 0.00001:31:38
dslb-188-099-023-198.188.099.pools.vodafone-ip.de 13.516 3.560 0.00001:03:58
67.43.172.252 0.172 0.048 0.00000:00:13
sky.census.shodan.io 0.056 0.000 0.00000:00:01
TOTAL: 5 14.184 3.628 0.00004:17:50

Curious NNRP server explorers [Top 20]:

SystemConn
47-192-187-101.drr02.clwr.fl.frontiernet.net 5
challenger.nielsenmedia.com 3
67.43.172.252 2
sky.census.shodan.io 1
TOTAL: 4 11

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 3 0
47-192-187-101.drr02.clwr.fl.frontiernet.net 3 0
TOTAL: 2 6 0

Newsgroup request counts (by category):

CategoryCountPct
1de 9 90.0%
2control 1 10.0%
TOTAL: 2 10100.0%

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

NewsgroupCount
1de.talk.tagesgeschehen 9
2control.cancel 1
TOTAL: 2 10