Daily Usenet report for paganini.linuxd.org

Jul 10 00:00:02 -- Jul 11 00:00:02

Unknown entries from news log file:

First 11 / 11 lines (100.0%)

Jul 10 00:00:02 paganini overchan[19959]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Jul 10 00:12:58 paganini overchan[19959]: input line too long, skipping
Jul 10 05:05:15 paganini overchan[19959]: input line too long, skipping
Jul 10 10:42:44 paganini overchan[19959]: input line too long, skipping
Jul 10 13:21:44 paganini overchan[19959]: input line too long, skipping
Jul 10 14:14:17 paganini overchan[19959]: input line too long, skipping
Jul 10 14:37:44 paganini overchan[19959]: input line too long, skipping
Jul 10 16:23:21 paganini overchan[19959]: input line too long, skipping
Jul 10 20:57:55 paganini overchan[19959]: input line too long, skipping
Jul 10 21:13:59 paganini overchan[19959]: input line too long, skipping
Jul 11 00:00:02 paganini overchan[5248]: 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 101266 63.8%12.5 MB 53.6%
inn 33814 21.3%7.7 MB 32.9%
innd 12696 8.0%1.9 MB 8.3%
nnrpd 10883 6.9%1.2 MB 5.1%
nocem 70 0.0%11.6 KB 0.0%
overchan 12 0.0%1.7 KB 0.0%
controlchan 1 0.0%0.2 KB 0.0%
TOTAL: 7 158742 100.0%23.3 MB100.0%

History cache:

ReasonCount%Count
Positive hits 100992 51.4%
Negative hits 62532 31.8%
Do not exist 33076 16.8%
Cache misses 55 0.0%
TOTAL: 4 196655 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.318 0.0%123 0.000 2.585 24.500
article cleanup00:00:00.231 0.0%33211 0.000 0.007 0.036
article logging00:00:00.356 0.0%33703 0.000 0.011 0.039
article parse00:00:00.833 0.0%65884 0.000 0.013 0.032
article write00:00:56.438 0.1%19689 0.094 2.866 45.650
artlog/artcncl00:00:00.002 0.0%123 0.000 0.016 0.125
data move00:00:00.113 0.0%233731 0.000 0.000 0.003
hisgrep/artcncl00:00:00.004 0.0%122 0.000 0.033 0.500
hishave/artcncl00:00:00.002 0.0%123 0.000 0.016 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.151 0.0%197305 0.016 0.046 0.245
history sync00:00:00.115 0.0%406 0.000 0.283 15.333
history write00:00:43.396 0.1%33202 0.055 1.307 5.972
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:57:02.771 99.7%218017 47.871 395.486 951.491
nntp read00:00:01.698 0.0%222240 0.001 0.008 0.015
overview write00:00:17.968 0.0%19689 0.030 0.913 5.294
perl filter00:01:13.634 0.1%33202 0.583 2.218 6.547
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.086 0.0%59193 0.000 0.018 0.062
TOTAL: 24:01:10.23424:00:28.116100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.099 0.0%33822 0.000 0.003 0.018
article prepare00:00:00.003 0.0%20220 0.000 0.000 0.006
article read00:00:01.382 0.0%4261 0.000 0.324 13.255
backlog stats00:00:00.158 0.0%297313 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.293 0.0%155236 0.002 0.008 0.016
data write00:00:02.069 0.0%135450 0.006 0.015 0.026
idle23:43:24.693 99.3%297313 192.241 287.255 533.177
status file00:09:42.998 0.7%42317 8.030 13.777 24.453
TOTAL: 23:53:26.34523:53:12.695100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:14:17.846 96.9%22865 30.000 509.855450807.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:05:25.303 2.7%46127 0.000 7.052 32461.200
readart00:00:04.442 0.0%9811 0.160 0.453 2.051
TOTAL: 03:20:30.99403:19:47.591 99.6%----

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 5 53820 16814 30842 6164 31%48:20:45
2newsfeed.neva.ru 142 73631 1982 64417 7232 2%71:47:32
3news.fcku.it 1 6587 874 5529 184 13%22:57:55
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 2 0 0 0 0 0%00:20:03
TOTAL: 5 175 134038 19670 100788 13580 14%143:26:15
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net60.2 MB0.0 KB67.0 MB127.2 MB 47%5.7 KB
2newsfeed.neva.ru5.3 MB27.7 KB28.4 MB33.8 MB 15%3.8 KB
3news.fcku.it4.1 MB0.0 KB5.6 MB9.6 MB 42%9.3 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 569.6 MB27.7 KB101.0 MB170.6 MB 40%5.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 10 00:00:02 - 00:59:59 715 3.6% 0.202.1 MB 3.0% 0.59
Jul 10 01:00:00 - 01:59:59 566 2.9% 0.161.6 MB 2.4% 0.47
Jul 10 02:00:00 - 02:59:59 504 2.6% 0.141.5 MB 2.2% 0.44
Jul 10 03:00:00 - 03:59:59 440 2.2% 0.121.5 MB 2.2% 0.44
Jul 10 04:00:00 - 04:59:59 468 2.4% 0.131.5 MB 2.1% 0.41
Jul 10 05:00:00 - 05:59:59 476 2.4% 0.131.3 MB 1.9% 0.37
Jul 10 06:00:00 - 06:59:59 411 2.1% 0.111.3 MB 1.9% 0.37
Jul 10 07:00:00 - 07:59:59 478 2.4% 0.133.7 MB 5.3% 1.05
Jul 10 08:00:00 - 08:59:59 639 3.2% 0.184.2 MB 6.0% 1.20
Jul 10 09:00:00 - 09:59:59 621 3.2% 0.171.9 MB 2.8% 0.55
Jul 10 10:00:00 - 10:59:59 768 3.9% 0.212.6 MB 3.8% 0.74
Jul 10 11:00:00 - 11:59:59 1010 5.1% 0.283.4 MB 4.8% 0.96
Jul 10 12:00:00 - 12:59:59 853 4.3% 0.242.7 MB 3.8% 0.76
Jul 10 13:00:00 - 13:59:59 895 4.5% 0.252.3 MB 3.3% 0.65
Jul 10 14:00:00 - 14:59:59 877 4.5% 0.242.7 MB 3.9% 0.76
Jul 10 15:00:00 - 15:59:59 940 4.8% 0.263.3 MB 4.7% 0.93
Jul 10 16:00:00 - 16:59:59 1124 5.7% 0.313.4 MB 4.9% 0.96
Jul 10 17:00:00 - 17:59:59 1046 5.3% 0.297.5 MB 10.8% 2.13
Jul 10 18:00:00 - 18:59:59 1118 5.7% 0.313.9 MB 5.6% 1.10
Jul 10 19:00:00 - 19:59:59 1199 6.1% 0.333.4 MB 4.8% 0.96
Jul 10 20:00:00 - 20:59:59 1213 6.2% 0.343.3 MB 4.8% 0.95
Jul 10 21:00:00 - 21:59:59 1275 6.5% 0.353.7 MB 5.3% 1.06
Jul 10 22:00:00 - 22:59:59 1066 5.4% 0.303.7 MB 5.4% 1.07
Jul 10 23:00:00 - 23:59:59 966 4.9% 0.273.0 MB 4.3% 0.86
Jul 11 00:00:00 - 00:00:02 12 0.1% 6.0043.7 KB 0.1% 21.85
TOTAL: 24:00:00 19680 100.0% 0.2369.6 MB 100.0% 0.82
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 7241 945 0 9 0 0 0 0 6287
2linuxd-out.news.weretis.net 6594 1727 0 0 0 0 0 0 4867
3news.fcku.it 169 2 0 0 0 0 0 0 167
TOTAL: 3 14004 2674 0 9 0 0 0 011321

Unwanted newsgroups [Top 20]:

NewsgroupCount
mailing.freebsd.pkg-fallout 594
mailing.freebsd.ports-bugs 196
fa.linux.kernel 187
lada.talk 96
it-alt.sport.calcio.juventus 89
mailing.freebsd.svn-ports-all 88
mailing.freebsd.svn-ports-head 76
mailing.freebsd.svn-src-all 57
mailing.freebsd.svn-src-head 55
ut.test 45
mailing.openbsd.ports-changes 38
mailing.openbsd.source-changes 37
lada.auto 34
alt.politics.scorched-earth 34
mailing.freebsd.bugs 34
mailing.database.pgsql-general 32
mailing.freebsd.current 31
fa.freebsd.cvs-all 30
mailing.freebsd.ports 25
fa.netbsd.bugs 25
TOTAL: 200 2674

INND Perl filter [Top 20]:

ReasonCount
EMP (phn path) 5175
EMP (md5) 4470
EMP (phl) 608
EMP (phn nph) 352
PFM bot misc 112
Too many newsgroups 61
Binary Payload 17
User-issued cancel 16
Binary: non-binary distribution 10
Binary: misplaced cmd 4
HTML post 2
EMP (phr nph) 2
U2 violation - invalid distribution 1
Binary: misplaced bin 1
TOTAL: 14 10831

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 449
newsfeed.neva.ru 32
news.fcku.it 9
TOTAL: 3 490
TOTAL: 1 490

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 24111 1161 15533 2052 0 7 4%23:59:56
2nntp.pasdenom.info 35581 678 18411 532 0 175 1%24:02:15
3weretis.net 3229 223 2681 0 0 0 6%24:06:56
4neva.ru 26998 163 17290 281 0 19 0%24:07:46
5news.albasani.net 2854 0 0 0 0 21632 0%23:58:06
6vc89.vc.panix.com 2826 0 0 0 0 22472 0%23:58:11
7eternal-september 0 0 0 0 0 16528 0%24:07:47
8news.killfile.org 2844 0 0 0 0 22490 0%23:58:11
9etla.org 0 0 0 0 0 19778 0%24:07:46
10news.netfront.net 2763 0 0 0 0 22363 0%23:58:26
11news.mixmin.net 0 0 0 0 0 0 0%00:10:00
12news.stack.nl 2853 0 0 0 0 22408 0%23:57:56
TOTAL: 12 104059 2225 53915 2865 0 147872 2%264:33:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it2.9 MB6.3 MB9.2 MB0.1 KB/s2.9 KB23:59:56
2nntp.pasdenom.info2.4 MB1.2 MB3.6 MB0.0 KB/s3.0 KB24:02:15
3neva.ru611.4 KB1.1 MB1.7 MB0.0 KB/s3.8 KB24:07:46
4weretis.net499.7 KB0.0 KB499.7 KB0.0 KB/s2.2 KB24:06:56
5news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:06
6vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:11
7eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:47
8news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:11
9etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:46
10news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:26
11news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:57:56
TOTAL: 126.3 MB8.6 MB14.9 MB0.0 KB/s3.0 KB264:33:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-04-1177941735.dsl.bell.ca 120 44444.5 KB 36 0 000:37:49
TOTAL: 1 120 44444.5 KB 36 0 003:20:30

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 120 44444.5 KB 36 0 000:37:49
2unresolved 21 00.0 KB 0 0 002:42:07
3*.188.099.pools.vodafone-ip.de 147 00.0 KB 0 0 000:00:32
TOTAL: 3 288 44444.5 KB 36 0 003:20:30

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
71.98.160.36 1.028 0.020 0.00002:36:39
mtrlpq2314w-lp140-04-1177941735.dsl.bell.ca 7.892 1.268 0.00000:37:49
199.189.26.16 0.096 0.020 0.00000:05:25
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 7.704 0.496 0.00000:00:32
188.99.106.132 0.056 0.000 0.00000:00:02
TOTAL: 5 16.776 1.804 0.00003:20:30

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 147
71.98.160.36 19
188.99.106.132 1
199.189.26.16 1
TOTAL: 4 168

NNRP client timeouts [Top 20]:

SystemConnPeer
71.98.160.36 5 0
TOTAL: 1 5 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 29 65.9%
2talk 6 13.6%
3uk 3 6.8%
4misc 3 6.8%
5rec 2 4.5%
6comp 1 2.3%
TOTAL: 6 44100.0%

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

NewsgroupCount
1alt.christnet.christianlife 7
2alt.home.repair 4
3alt.fan.rush-limbaugh 4
4talk.politics.guns 4
5misc.survivalism 3
6alt.atheism 3
7alt.bumbling.idiots.the.fbi 3
8alt.comp.freeware 3
9alt.usage.english 2
10talk.politics.misc 2
11alt.comp.os.windows-10 1
12uk.legal 1
13alt.suicide.methods 1
14rec.arts.tv 1
15uk.rec.sheds 1
16rec.arts.tv.uk.coronation-st 1
17alt.usenet.kooks 1
18uk.d-i-y 1
19comp.mobile.android 1
TOTAL: 19 44