Daily Usenet report for paganini.linuxd.org

Apr 11 00:00:02 -- Apr 12 00:00:02

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Apr 11 00:00:02 paganini overchan[27969]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Apr 11 17:58:33 paganini overchan[27969]: input line too long, skipping
Apr 12 00:00:02 paganini overchan[13095]: 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 103938 42.7%12.9 MB 38.9%
nnrpd 79830 32.8%9.2 MB 27.8%
inn 40415 16.6%8.5 MB 25.7%
innd 19081 7.8%2.5 MB 7.6%
nocem 68 0.0%11.8 KB 0.0%
overchan 5 0.0%0.4 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 243338 100.0%33.1 MB100.0%

History cache:

ReasonCount%Count
Positive hits 118221 49.6%
Negative hits 80196 33.7%
Do not exist 39765 16.7%
Cache misses 76 0.0%
TOTAL: 4 238258 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.512 0.0%167 0.000 3.066 69.000
article cleanup00:00:00.242 0.0%39967 0.000 0.006 0.022
article logging00:00:00.427 0.0%40264 0.000 0.011 0.307
article parse00:00:01.132 0.0%90350 0.000 0.013 0.037
article write00:00:53.294 0.1%21871 0.235 2.437 14.401
artlog/artcncl00:00:00.002 0.0%165 0.000 0.012 0.500
data move00:00:00.209 0.0%250101 0.000 0.001 0.020
hisgrep/artcncl00:00:00.002 0.0%167 0.000 0.012 1.000
hishave/artcncl00:00:00.004 0.0%167 0.000 0.024 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:17.198 0.0%239666 0.014 0.072 1.386
history sync00:00:00.034 0.0%398 0.000 0.085 4.333
history write00:00:39.418 0.0%39967 0.038 0.986 5.384
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:56:43.853 99.7%251784 227.882 342.372 966.890
nntp read00:00:01.839 0.0%260385 0.002 0.007 0.016
overview write00:00:21.744 0.0%21871 0.000 0.994 4.609
perl filter00:01:30.508 0.1%39967 1.080 2.265 4.885
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.882 0.0%65974 0.002 0.029 0.788
TOTAL: 24:01:01.92724:00:32.300100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.105 0.0%56809 0.000 0.002 0.023
article prepare00:00:00.006 0.0%36426 0.000 0.000 0.005
article read00:00:05.642 0.0%7132 0.000 0.791 20.074
backlog stats00:00:00.238 0.0%359816 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.447 0.0%196641 0.003 0.007 0.015
data write00:00:03.088 0.0%175167 0.007 0.018 0.036
idle23:42:36.036 99.2%359816 28.573 237.221 467.877
status file00:10:30.332 0.7%43454 7.040 14.506 30.439
TOTAL: 23:53:29.93923:53:16.894100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:37:27.659 99.9%80561 36.587 206.647600558.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.581 0.0%163200 0.000 0.010 0.167
readart00:00:00.000 0.0%2 0.000 0.000 0.000
TOTAL: 04:37:48.79904:37:29.240 99.9%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 3 46955 18927 26090 1938 40%48:00:14
2newsfeed.neva.ru 147 82765 2503 64212 16050 3%72:25:46
3news.fcku.it 2 4650 249 4286 115 5%16:26:18
4nntp.pasdenom.info 2 21243 36 21203 4 0%23:42:48
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 179 155613 21715 115791 18107 13%160:35:06
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net57.5 MB0.0 KB12.9 MB70.4 MB 81%3.5 KB
2newsfeed.neva.ru6.5 MB0.0 KB139.3 MB145.8 MB 4%8.0 KB
3news.fcku.it984.3 KB0.0 KB592.8 KB1.5 MB 62%4.3 KB
4nntp.pasdenom.info92.0 KB0.0 KB44.4 KB136.4 KB 67%3.4 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 565.0 MB0.0 KB152.8 MB217.9 MB 29%5.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 11 00:00:02 - 00:59:59 890 4.1% 0.252.8 MB 4.2% 0.79
Apr 11 01:00:00 - 01:59:59 756 3.5% 0.212.2 MB 3.4% 0.63
Apr 11 02:00:00 - 02:59:59 452 2.1% 0.131.3 MB 2.1% 0.38
Apr 11 03:00:00 - 03:59:59 461 2.1% 0.132.5 MB 3.8% 0.70
Apr 11 04:00:00 - 04:59:59 448 2.1% 0.121.4 MB 2.1% 0.39
Apr 11 05:00:00 - 05:59:59 440 2.0% 0.121.6 MB 2.4% 0.45
Apr 11 06:00:00 - 06:59:59 458 2.1% 0.131.9 MB 2.9% 0.53
Apr 11 07:00:00 - 07:59:59 483 2.2% 0.131.7 MB 2.6% 0.48
Apr 11 08:00:00 - 08:59:59 627 2.9% 0.171.8 MB 2.7% 0.50
Apr 11 09:00:00 - 09:59:59 882 4.0% 0.242.6 MB 4.0% 0.73
Apr 11 10:00:00 - 10:59:59 963 4.4% 0.273.0 MB 4.6% 0.85
Apr 11 11:00:00 - 11:59:59 1048 4.8% 0.292.9 MB 4.5% 0.83
Apr 11 12:00:00 - 12:59:59 1000 4.6% 0.282.6 MB 3.9% 0.73
Apr 11 13:00:00 - 13:59:59 1148 5.3% 0.323.0 MB 4.5% 0.84
Apr 11 14:00:00 - 14:59:59 1108 5.1% 0.313.0 MB 4.6% 0.85
Apr 11 15:00:00 - 15:59:59 1160 5.3% 0.323.6 MB 5.5% 1.02
Apr 11 16:00:00 - 16:59:59 1402 6.4% 0.394.4 MB 6.7% 1.24
Apr 11 17:00:00 - 17:59:59 1267 5.8% 0.353.7 MB 5.7% 1.06
Apr 11 18:00:00 - 18:59:59 1183 5.4% 0.333.4 MB 5.3% 0.98
Apr 11 19:00:00 - 19:59:59 1165 5.3% 0.323.3 MB 5.0% 0.93
Apr 11 20:00:00 - 20:59:59 1180 5.4% 0.333.5 MB 5.4% 1.00
Apr 11 21:00:00 - 21:59:59 1170 5.4% 0.333.3 MB 5.0% 0.93
Apr 11 22:00:00 - 22:59:59 1094 5.0% 0.303.0 MB 4.6% 0.85
Apr 11 23:00:00 - 23:59:59 1051 4.8% 0.293.0 MB 4.6% 0.86
Apr 12 00:00:00 - 00:00:02 4 0.0% 2.0012.3 KB 0.0% 6.13
TOTAL: 24:00:00 21840 100.0% 0.2565.2 MB 100.0% 0.77
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 16072 404 0 0 0 0 0 015668
2linuxd-out.news.weretis.net 2216 655 0 0 0 0 0 0 1561
3news.fcku.it 120 8 0 0 0 0 0 0 112
4nntp.pasdenom.info 2 0 0 0 0 0 0 0 2
TOTAL: 4 18410 1067 0 0 0 0 0 017343

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 126
it-alt.sport.calcio.juventus 121
ut.test 114
lada.auto 81
it.annunci.immobiliari 39
it.annunci.varie 38
it.annunci.commerciali 36
mozilla.dev.security.policy 35
mpc.lists.freebsd.x11 27
alt.survival 26
cakewalk.audio 25
alt.windows7.general 24
gmane.linux.alsa.devel 24
it.comp.os.win.windows10 22
spline.eisfair 21
gmane.comp.gis.openstreetmap 20
mozilla.support.seamonkey 15
mozilla.support.thunderbird 14
gmane.comp.gis.openstreetmap.tagging 12
lada.photo 10
TOTAL: 112 1067

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15486
EMP (phn path) 1273
Too many newsgroups 103
EMP (phn nph) 90
User-issued cancel 35
EMP (phl) 20
Binary Payload 12
Binary: non-binary distribution 9
Too many newsgroups (low_xpost) 8
HTML post 6
Binary Image: misplaced jpg 2
Too many newsgroups (meow) 1
TOTAL: 12 17045

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 281
newsfeed.neva.ru 17
TOTAL: 2 298
TOTAL: 1 298

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 52947 3522 35089 1021 0 2854 6%23:57:47
2news.fcku.it 29125 1799 17335 2340 1 174 6%23:59:44
3weretis.net 2974 181 2618 0 0 85 6%23:57:39
4eternal-september 23423 40 18717 0 0 26 0%23:59:53
5neva.ru 27493 24 19101 224 0 45 0%23:59:52
6news.killfile.org 2835 0 0 0 0 24674 0%23:59:54
7vc89.vc.panix.com 2842 0 0 0 0 24681 0%23:59:54
8news.albasani.net 2865 0 0 0 0 24023 0%23:59:54
9news.mixmin.net 0 0 0 0 0 0 0%00:10:00
10news.stack.nl 2858 0 0 0 0 24666 0%23:59:54
11news.netfront.net 2774 0 0 0 0 24563 0%23:59:54
12etla.org 0 0 0 0 0 21840 0%23:59:54
TOTAL: 12 150136 5566 92860 3585 1 147631 3%264:04:19
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info10.9 MB2.1 MB13.0 MB0.2 KB/s2.9 KB23:57:47
2news.fcku.it5.5 MB8.2 MB13.7 MB0.2 KB/s3.4 KB23:59:44
3weretis.net200.0 KB0.0 KB200.0 KB0.0 KB/s1.1 KB23:57:39
4eternal-september173.5 KB0.0 KB173.5 KB0.0 KB/s4.3 KB23:59:53
5neva.ru96.6 KB947.6 KB1.0 MB0.0 KB/s4.2 KB23:59:52
6news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
7vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
8news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
9news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
11news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
12etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 1216.9 MB11.2 MB28.1 MB0.0 KB/s3.1 KB264:04:19
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-094-217-142-075.094.217.pools.vodafone-ip.de 144 22.4 KB 2 0 001:00:14
TOTAL: 1 144 22.4 KB 2 0 004:37:48

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.094.217.pools.vodafone-ip.de 144 22.4 KB 2 0 001:00:14
2*.nielsenmedia.com 8 00.0 KB 0 0 003:37:32
3*.shodan.io 1 00.0 KB 0 0 000:00:01
TOTAL: 3 153 22.4 KB 2 0 004:37:48

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.412 0.032 0.00003:37:32
dslb-094-217-142-075.094.217.pools.vodafone-ip.de 15.292 3.888 0.00001:00:14
census8.shodan.io 0.056 0.004 0.00000:00:01
TOTAL: 3 15.760 3.924 0.00004:37:48

Curious NNRP server explorers [Top 20]:

SystemConn
challenger.nielsenmedia.com 8
census8.shodan.io 1
TOTAL: 2 9

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 7 0
TOTAL: 1 7 0

Newsgroup request counts (by category):

CategoryCountPct
1de 1 50.0%
2alt 1 50.0%
TOTAL: 2 2100.0%

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

NewsgroupCount
1alt.test 1
2de.sci.electronics 1
TOTAL: 2 2