Daily Usenet report for paganini.linuxd.org

May 3 00:00:01 -- May 4 00:00:01

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 3 00:37:41 paganini overchan[5042]: input line too long, skipping
May 3 17:28:24 paganini overchan[5042]: input line too long, skipping
May 3 18:37:06 paganini overchan[5042]: input line too long, skipping
May 3 19:25:04 paganini overchan[5042]: input line too long, skipping
May 4 00:00:01 paganini overchan[17525]: 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 104563 46.3%12.8 MB 41.5%
nnrpd 63233 28.0%7.3 MB 23.6%
inn 39479 17.5%8.3 MB 26.9%
innd 18565 8.2%2.5 MB 8.0%
nocem 99 0.0%15.5 KB 0.0%
overchan 6 0.0%0.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 225946 100.0%30.9 MB100.0%

History cache:

ReasonCount%Count
Positive hits 116821 48.9%
Negative hits 77202 32.3%
Do not exist 38771 16.2%
Cache misses 6150 2.6%
TOTAL: 4 238944 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.718 0.0%190 0.000 3.779 90.500
article cleanup00:00:00.256 0.0%38969 0.000 0.007 0.033
article logging00:00:00.389 0.0%39277 0.000 0.010 0.126
article parse00:00:01.085 0.0%91009 0.000 0.012 0.033
article write00:00:52.761 0.1%21431 0.103 2.462 15.057
artlog/artcncl00:00:00.002 0.0%177 0.000 0.011 0.100
data move00:00:00.191 0.0%255479 0.000 0.001 0.005
hisgrep/artcncl00:00:00.003 0.0%187 0.000 0.016 0.500
hishave/artcncl00:00:00.005 0.0%190 0.000 0.026 0.125
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:25.577 0.0%240014 0.015 0.107 8.384
history sync00:00:00.061 0.0%403 0.000 0.151 14.333
history write00:00:33.641 0.0%38946 0.043 0.864 3.093
hiswrite/artcncl00:00:00.000 0.0%3 0.000 0.000 0.000
idle23:56:46.333 99.7%255296 123.142 337.672 980.633
nntp read00:00:01.962 0.0%264123 0.002 0.007 0.026
overview write00:00:21.794 0.0%21431 0.022 1.017 3.439
perl filter00:01:24.879 0.1%38938 1.168 2.180 3.707
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.351 0.0%64465 0.000 0.021 0.073
TOTAL: 24:01:11.81224:00:31.008100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.089 0.0%54842 0.000 0.002 0.022
article prepare00:00:00.004 0.0%32192 0.000 0.000 0.005
article read00:00:02.574 0.0%6664 0.000 0.386 4.133
backlog stats00:00:00.253 0.0%354477 0.000 0.001 0.002
callbacks00:00:00.000 0.0%5 0.000 0.000 0.000
data read00:00:01.509 0.0%192702 0.002 0.008 0.018
data write00:00:03.115 0.0%171420 0.009 0.018 0.031
idle23:42:13.496 99.2%354477 39.038 240.731 431.059
status file00:10:35.991 0.7%43736 8.690 14.542 27.265
TOTAL: 23:53:29.38123:52:57.031100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle04:54:45.081 99.9%64000 37.959 276.329600342.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.260 0.0%130062 0.000 0.010 0.017
readart00:00:00.001 0.0%10 0.000 0.100 1.000
TOTAL: 04:55:06.42604:54:46.342 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 4 46389 18442 25829 2118 39%48:01:12
2newsfeed.neva.ru 144 80560 2474 62964 15122 3%71:44:53
3news.fcku.it 1 7777 319 7297 161 4%22:58:29
4nntp.pasdenom.info 7 14940 31 14893 16 0%13:41:07
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 181 149666 21266 110983 17417 14%156:25:41
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net58.7 MB30.0 KB13.7 MB72.4 MB 81%3.6 KB
2newsfeed.neva.ru6.5 MB111.3 KB136.6 MB143.2 MB 4%8.3 KB
3news.fcku.it1.1 MB0.0 KB861.7 KB2.0 MB 57%4.2 KB
4nntp.pasdenom.info66.9 KB7.9 KB47.5 KB122.4 KB 54%2.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 566.4 MB149.3 KB151.2 MB217.7 MB 30%5.8 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 3 00:00:01 - 00:59:59 798 3.7% 0.222.3 MB 3.4% 0.65
May 3 01:00:00 - 01:59:59 708 3.3% 0.202.6 MB 3.9% 0.74
May 3 02:00:00 - 02:59:59 555 2.6% 0.151.9 MB 2.9% 0.55
May 3 03:00:00 - 03:59:59 472 2.2% 0.131.6 MB 2.3% 0.44
May 3 04:00:00 - 04:59:59 415 1.9% 0.121.3 MB 2.0% 0.37
May 3 05:00:00 - 05:59:59 400 1.9% 0.111.4 MB 2.1% 0.40
May 3 06:00:00 - 06:59:59 542 2.5% 0.151.9 MB 2.8% 0.53
May 3 07:00:00 - 07:59:59 520 2.4% 0.141.6 MB 2.4% 0.45
May 3 08:00:00 - 08:59:59 752 3.5% 0.214.7 MB 7.1% 1.34
May 3 09:00:00 - 09:59:59 794 3.7% 0.222.0 MB 3.0% 0.56
May 3 10:00:00 - 10:59:59 922 4.3% 0.262.3 MB 3.5% 0.66
May 3 11:00:00 - 11:59:59 897 4.2% 0.252.4 MB 3.5% 0.67
May 3 12:00:00 - 12:59:59 928 4.3% 0.262.6 MB 3.9% 0.74
May 3 13:00:00 - 13:59:59 958 4.5% 0.272.8 MB 4.1% 0.78
May 3 14:00:00 - 14:59:59 1173 5.5% 0.334.7 MB 7.0% 1.33
May 3 15:00:00 - 15:59:59 1102 5.1% 0.312.8 MB 4.2% 0.80
May 3 16:00:00 - 16:59:59 1236 5.8% 0.343.7 MB 5.5% 1.05
May 3 17:00:00 - 17:59:59 1324 6.2% 0.373.8 MB 5.6% 1.07
May 3 18:00:00 - 18:59:59 1359 6.3% 0.384.1 MB 6.1% 1.17
May 3 19:00:00 - 19:59:59 1169 5.5% 0.323.7 MB 5.6% 1.06
May 3 20:00:00 - 20:59:59 1252 5.8% 0.353.7 MB 5.5% 1.04
May 3 21:00:00 - 21:59:59 1102 5.1% 0.312.8 MB 4.2% 0.80
May 3 22:00:00 - 22:59:59 1086 5.1% 0.303.3 MB 5.0% 0.94
May 3 23:00:00 - 23:59:59 930 4.3% 0.262.8 MB 4.3% 0.81
May 4 00:00:00 - 00:00:01 20 0.1% 20.0080.7 KB 0.1% 80.73
TOTAL: 24:00:00 21414 100.0% 0.2566.8 MB 100.0% 0.79
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 15264 430 0 16 0 0 0 014818
2linuxd-out.news.weretis.net 2419 560 0 4 0 0 0 0 1855
3news.fcku.it 169 11 0 0 0 0 0 0 158
4nntp.pasdenom.info 20 1 0 3 0 8 0 0 8
TOTAL: 4 17872 1002 0 23 0 8 0 016839

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 151
ut.test 102
lada.auto 89
it-alt.sport.calcio.juventus 63
spline.eisfair 47
mpc.lists.freebsd.ports.bugs 40
it.annunci.commerciali 30
it.annunci.varie 29
it.comp.os.win.windows10 21
cakewalk.audio 19
it.annunci.immobiliari 18
alt.windows7.general 18
alt.survival 18
mozilla.support.thunderbird 16
lada.commerce 16
mozilla.support.firefox 15
mozilla.dev.security.policy 14
mpc.lists.freebsd.gnome 13
mozilla.dev.telemetry-alerts 12
gmane.linux.alsa.devel 12
TOTAL: 121 1002

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14685
EMP (phn path) 1536
EMP (phl) 152
Too many newsgroups 46
User-issued cancel 40
EMP (phn nph) 29
Binary Payload 23
HTML post 5
Binary: non-binary distribution 5
Binary Image: misplaced jpg 3
Binary: misplaced nzb 3
Too many newsgroups (low_xpost) 2
Too many newsgroups (meow) 1
TOTAL: 13 16530

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 292
newsfeed.neva.ru 14
news.fcku.it 3
TOTAL: 3 309
TOTAL: 1 309

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 50775 2833 33104 1384 18 3273 5%23:57:39
2news.fcku.it 28731 1427 17509 2146 0 83 4%23:59:41
3weretis.net 3012 121 2743 0 0 6 4%23:59:46
4eternal-september 23334 15 18386 1 0 14 0%23:59:44
5neva.ru 27352 15 18753 135 0 55 0%23:59:46
6news.killfile.org 2851 0 0 0 0 24264 0%23:59:46
7news.albasani.net 2861 0 0 0 0 23504 0%23:59:46
8news.netfront.net 2766 0 0 0 0 24138 0%23:59:45
9news.stack.nl 2857 0 0 0 0 24238 0%23:59:46
10vc89.vc.panix.com 2842 0 0 0 0 24255 0%23:59:46
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12etla.org 0 0 0 0 0 21414 0%23:59:46
TOTAL: 12 147381 4411 90495 3666 18 145244 2%264:05:11
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info9.6 MB2.4 MB12.1 MB0.1 KB/s2.9 KB23:57:39
2news.fcku.it3.8 MB7.8 MB11.6 MB0.1 KB/s3.3 KB23:59:41
3weretis.net171.6 KB0.0 KB171.6 KB0.0 KB/s1.4 KB23:59:46
4eternal-september47.0 KB2.7 KB49.7 KB0.0 KB/s3.1 KB23:59:44
5neva.ru31.5 KB553.1 KB584.6 KB0.0 KB/s3.9 KB23:59:46
6news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
7news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
8news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
9news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
10vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:46
TOTAL: 1213.6 MB10.8 MB24.5 MB0.0 KB/s3.1 KB264:05:11
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-188-099-023-198.188.099.pools.vodafone-ip.de 114 935.2 KB 6 0 000:52:34
2challenger.nielsenmedia.com 10 13.2 KB 0 0 003:32:30
TOTAL: 2 124 1038.4 KB 6 0 004:55:05

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.188.099.pools.vodafone-ip.de 114 935.2 KB 6 0 000:52:34
2*.nielsenmedia.com 10 13.2 KB 0 0 003:32:30
3*.drr02.clwr.fl.frontiernet.net 1 00.0 KB 0 0 000:30:01
TOTAL: 3 125 1038.4 KB 6 0 004:55:05

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.636 0.020 0.00003:32:30
dslb-188-099-023-198.188.099.pools.vodafone-ip.de 12.180 3.140 0.00000:52:34
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.052 0.004 0.00000:30:01
TOTAL: 3 12.868 3.164 0.00004:55:05

Curious NNRP server explorers [Top 20]:

SystemConn
47-192-187-101.drr02.clwr.fl.frontiernet.net 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

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

Newsgroup request counts (by category):

CategoryCountPct
1de 6 66.7%
2control 2 22.2%
3alt 1 11.1%
TOTAL: 3 9100.0%

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

NewsgroupCount
1de.talk.tagesgeschehen 4
2control.cancel 2
3de.soc.subkultur.bdsm 1
4de.talk.jokes 1
5alt.test 1
TOTAL: 5 9