Daily Usenet report for paganini.linuxd.org

Aug 1 00:00:02 -- Aug 2 00:00:06

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Aug 1 00:00:14 paganini nnrpd[17050]: 2001:ba8:1f1:f1ef::2 noperm ihave without permission
Aug 1 00:00:44 paganini nnrpd[17103]: ceres.etla.org noperm ihave without permission
Aug 1 07:04:03 paganini pgpverify[30198]: pgpverify: verification failed
Aug 1 18:31:19 paganini innd: logging hist stats to /var/spool/news/www/inn.status
Aug 1 18:31:31 paganini overchan[2977]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Aug 1 21:31:27 paganini overchan[23788]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Aug 2 00:00:06 paganini overchan[3438]: 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
inn 41287 50.1%9.0 MB 66.7%
nnrpd 20089 24.4%2.0 MB 14.7%
innd 18469 22.4%2.1 MB 15.4%
innfeed 2453 3.0%426.0 KB 3.1%
nocem 132 0.2%15.0 KB 0.1%
controlchan 9 0.0%1.2 KB 0.0%
overchan 5 0.0%0.5 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 8 82445 100.0%13.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 275065 54.9%
Negative hits 164466 32.8%
Do not exist 40245 8.0%
Cache misses 21401 4.3%
TOTAL: 4 501177 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.967 0.0%274 0.000 7.179 50.056
article cleanup00:00:00.257 0.0%40527 0.000 0.006 0.021
article logging00:00:00.378 0.0%40755 0.000 0.009 0.096
article parse00:00:01.034 0.0%117010 0.000 0.009 0.029
article write00:03:04.993 0.2%22674 0.148 8.159 49.799
artlog/artcncl00:00:00.003 0.0%250 0.000 0.012 0.333
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.302 0.0%528681 0.000 0.001 0.002
hisgrep/artcncl00:00:00.202 0.0%269 0.000 0.751 5.778
hishave/artcncl00:00:00.176 0.0%274 0.000 0.642 9.444
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:52.564 0.2%506255 0.018 0.341 1.940
history sync00:00:00.015 0.0%317 0.000 0.047 0.500
history write00:02:54.101 0.2%40508 0.051 4.298 26.203
hiswrite/artcncl00:00:00.024 0.0%5 0.000 4.800 24.000
idle23:38:03.883 99.1%515769 40.828 164.965 400.953
nntp read00:00:03.298 0.0%540209 0.003 0.006 0.012
overview write00:00:53.503 0.1%22674 0.007 2.360 23.392
perl filter00:01:31.459 0.1%40508 1.224 2.258 7.772
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.985 0.0%68449 0.000 0.014 0.065
TOTAL: 23:50:26.87723:49:29.144 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.070 0.0%23364 0.000 0.003 0.023
article prepare00:00:00.018 0.0%116146 0.000 0.000 0.002
article read00:00:10.440 0.0%18179 0.000 0.574 6.196
backlog stats00:00:00.275 0.0%527819 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.092 0.0%342314 0.003 0.006 0.013
data write00:00:03.521 0.0%322216 0.005 0.011 0.018
idle23:32:24.753 99.9%527819 95.024 160.556 371.330
status file00:00:06.944 0.0%211 0.000 32.910 445.000
TOTAL: 23:33:12.07623:32:48.113100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.000 0.0%1 0.000 0.000 0.000
idle02:08:12.134 98.4%26682 0.000 288.289 12128.843
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:30.114 0.4%295921 0.000 0.102 7.163
readart00:00:38.597 0.5%28907 0.000 1.335 34.500
TOTAL: 02:10:17.74102:09:20.845 99.3%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 148
name 2
newgroup 1
pause 2
paused 2
reload 2
reserve 2
TOTAL: 12 172

Newsgroups created:

GroupMode
free.fr.test.Ïτφ-8y
TOTAL1

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
bofh-control@killfile.org001001
stephane@2a01_e35_2e9c_92b0_222_fbff_febd_df14100011
us-control@lists.killfile.org000100
TOTAL101112

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 53077 6463 44029 2585 12%48:48:51
2feeder.eternal-september.org 17 36042 4657 27963 3422 12%25:43:01
3vc89.vc.panix.com 2 6047 3264 2150 633 53%22:45:11
4newsfeed.neva.ru 139 80249 2597 68820 8832 3%70:58:16
5news.albasani.net 3 26766 2176 23512 1078 8%22:49:27
6news-out.mixmin.net 6 25771 1246 23634 891 4%22:34:15
7news.fcku.it 2 4099 1056 3017 26 25%20:10:24
8etla.org 5 41245 336 40908 1 0%20:53:36
9news.netfront.net 3 46274 109 46146 19 0%25:51:38
10nntp.pasdenom.info 4 31112 88 31013 11 0%27:02:29
11localhost 75 0 0 0 0 0%00:05:07
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 261 350682 21992 311192 17498 6%307:52:15
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net22.9 MB0.0 KB12.5 MB35.4 MB 64%4.0 KB
2vc89.vc.panix.com16.8 MB86.1 KB5.0 MB21.9 MB 76%5.7 KB
3feeder.eternal-september.org13.8 MB0.0 KB28.2 MB42.0 MB 32%5.3 KB
4newsfeed.neva.ru9.0 MB94.5 KB65.8 MB74.9 MB 12%6.7 KB
5news.albasani.net8.2 MB0.0 KB2.2 MB10.4 MB 78%3.3 KB
6news.fcku.it4.3 MB0.0 KB48.9 KB4.3 MB 98%4.1 KB
7news-out.mixmin.net3.6 MB1.4 KB1.7 MB5.2 MB 67%2.5 KB
8etla.org1.2 MB0.0 KB1.2 KB1.2 MB 99%3.6 KB
9news.netfront.net586.5 KB27.6 KB3.9 MB4.5 MB 12%35.6 KB
10nntp.pasdenom.info215.4 KB16.8 KB25.6 KB257.8 KB 83%2.6 KB
11localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1280.5 MB226.4 KB119.4 MB200.1 MB 40%5.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 1 00:00:02 - 00:59:59 960 4.2% 0.273.0 MB 3.6% 0.86
Aug 1 01:00:00 - 01:59:59 767 3.4% 0.212.8 MB 3.4% 0.80
Aug 1 02:00:00 - 02:59:59 665 2.9% 0.181.9 MB 2.3% 0.53
Aug 1 03:00:00 - 03:59:59 522 2.3% 0.141.7 MB 2.0% 0.48
Aug 1 04:00:00 - 04:59:59 493 2.2% 0.141.7 MB 2.0% 0.48
Aug 1 05:00:00 - 05:59:59 521 2.3% 0.141.6 MB 2.0% 0.47
Aug 1 06:00:00 - 06:59:59 523 2.3% 0.152.3 MB 2.8% 0.67
Aug 1 07:00:00 - 07:59:59 605 2.6% 0.172.6 MB 3.1% 0.74
Aug 1 08:00:00 - 08:59:59 657 2.9% 0.184.2 MB 5.1% 1.20
Aug 1 09:00:00 - 09:59:59 931 4.1% 0.262.9 MB 3.5% 0.81
Aug 1 10:00:00 - 10:59:59 983 4.3% 0.275.1 MB 6.2% 1.46
Aug 1 11:00:00 - 11:59:59 1103 4.8% 0.312.9 MB 3.5% 0.83
Aug 1 12:00:00 - 12:59:59 1084 4.7% 0.303.0 MB 3.6% 0.85
Aug 1 13:00:00 - 13:59:59 1022 4.5% 0.282.9 MB 3.5% 0.82
Aug 1 14:00:00 - 14:59:59 1069 4.7% 0.303.5 MB 4.2% 0.99
Aug 1 15:00:00 - 15:59:59 1191 5.2% 0.334.2 MB 5.1% 1.20
Aug 1 16:00:00 - 16:59:59 1066 4.7% 0.304.7 MB 5.6% 1.33
Aug 1 17:00:00 - 17:59:59 1113 4.9% 0.313.5 MB 4.2% 0.99
Aug 1 18:00:00 - 18:59:59 1381 6.0% 0.385.3 MB 6.5% 1.52
Aug 1 19:00:00 - 19:59:59 1455 6.4% 0.405.2 MB 6.3% 1.49
Aug 1 20:00:00 - 20:59:59 1176 5.1% 0.334.4 MB 5.3% 1.25
Aug 1 21:00:00 - 21:59:59 1301 5.7% 0.363.5 MB 4.2% 0.99
Aug 1 22:00:00 - 22:59:59 1143 5.0% 0.326.3 MB 7.6% 1.79
Aug 1 23:00:00 - 23:59:59 1139 5.0% 0.323.5 MB 4.2% 1.00
Aug 2 00:00:00 - 00:00:06 13 0.1% 2.1746.1 KB 0.1% 7.68
TOTAL: 24:00:04 22883 100.0% 0.2682.8 MB 100.0% 0.98
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 9054 1143 0 5 0 0 0 0 7906
2feeder.eternal-september.org 3592 485 0 0 0 0 0 0 3107
3linuxd-out.news.weretis.net 2691 936 0 0 0 0 0 0 1755
4news.albasani.net 1127 85 0 0 0 0 0 0 1042
5news-out.mixmin.net 945 12 0 1 0 0 0 0 932
6vc89.vc.panix.com 659 3 0 2 0 0 0 0 654
7news.fcku.it 50 28 0 0 0 0 0 0 22
8news.netfront.net 23 0 0 2 0 0 0 0 21
9nntp.pasdenom.info 11 1 0 9 0 0 0 0 1
10etla.org 1 1 0 0 0 0 0 0 0
TOTAL: 10 18153 2694 0 19 0 0 0 015440

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 273
lada.talk 249
alt.politics 117
alt.politics.scorched-earth 112
mailing.freebsd.pkg-fallout 106
it-alt.sport.calcio.juventus 98
alt.windows7.general 85
ut.test 76
pld.cvs.commit 72
alt.job 70
alt.survival 60
lada.auto 50
free.it.calcio.juventus 44
mozilla.community.brasil 37
it.comp.os.win.windows10 37
mailing.freebsd.svn-src-all 35
fa.freebsd.cvs-all 34
mpc.lists.nanog 34
mailing.freebsd.svn-src-head 34
mailing.freebsd.ports-bugs 30
TOTAL: 348 2694

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11045
EMP (phn path) 3030
EMP (phl) 704
PFM bot misc 216
EMP (phn nph) 62
User-issued cancel 36
Binary Image: misplaced jpg 33
Binary: non-binary distribution 25
Too many newsgroups 23
Binary Payload 18
EMP (fsl) 6
HTML post 6
Too many newsgroups (meow) 4
Binary: misplaced 076 1
Binary: misplaced 046 1
Binary: misplaced PAR2 1
Binary: misplaced BRK 1
Binary: misplaced par2 1
Binary: misplaced 011 1
TOTAL: 19 15214

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad command received
localhost 30
TOTAL: 1 30
Including strange strings
linuxd-out.news.weretis.net 141
feeder.eternal-september.org 29
news.albasani.net 28
news.fcku.it 21
vc89.vc.panix.com 4
news-out.mixmin.net 2
TOTAL: 6 225
TOTAL: 2 255

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 25068 2096 8296 12294 1 93 8%23:48:19
2news.fcku.it 22638 1381 13982 6083 0 40 6%23:48:20
3news.netfront.net 40848 1300 21263 34 0 132 3%23:48:19
4weretis.net 16884 423 15577 0 0 26 2%23:48:20
5etla.org 27443 346 22313 24 0 136 1%23:48:22
6news.albasani.net 24447 172 18642 1717 0 68 0%23:53:54
7neva.ru 22712 169 19143 551 0 71 0%23:48:57
8news.killfile.org 37704 135 21049 1495 0 95 0%23:48:19
9nntp.pasdenom.info 29168 107 21620 906 0 68 0%23:54:00
10news.stack.nl 34677 52 22525 60 0 211 0%23:51:40
11eternal-september 18685 1 17751 2 0 17 0%23:48:20
12news.mixmin.net 0 0 0 0 0 0 0%00:30:00
TOTAL: 12 300274 6182 202161 23166 1 957 2%262:36:50
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1etla.org16.6 MB673.6 KB17.2 MB0.2 KB/s47.6 KB23:48:22
2news.netfront.net15.4 MB825.5 KB16.2 MB0.2 KB/s12.4 KB23:48:19
3vc89.vc.panix.com7.5 MB39.0 MB46.4 MB0.6 KB/s3.3 KB23:48:19
4news.fcku.it6.6 MB32.6 MB39.2 MB0.5 KB/s5.4 KB23:48:20
5neva.ru817.8 KB1.1 MB1.9 MB0.0 KB/s2.7 KB23:48:57
6weretis.net786.5 KB0.0 KB786.5 KB0.0 KB/s1.9 KB23:48:20
7news.killfile.org459.4 KB6.5 MB7.0 MB0.1 KB/s4.4 KB23:48:19
8news.albasani.net441.4 KB10.4 MB10.8 MB0.1 KB/s5.9 KB23:53:54
9nntp.pasdenom.info422.4 KB1.6 MB2.0 MB0.0 KB/s2.0 KB23:54:00
10news.stack.nl243.0 KB114.3 KB357.4 KB0.0 KB/s3.2 KB23:51:40
11eternal-september5.0 KB2.9 KB7.9 KB0.0 KB/s2.6 KB23:48:20
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:30:00
TOTAL: 1249.2 MB92.7 MB141.8 MB0.2 KB/s4.9 KB262:36:50
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp130-02-1176034505.dsl.bell.ca 380 148459.7 KB 94 0 000:50:06
2183.103.176.107 10 73343.9 KB 30 0 101:14:10
3x55b41fcb.dyn.telefonica.de 41 35146.0 KB 20 0 000:00:56
4f051217085.adsl.alicedsl.de 7 1043.3 KB 7 0 000:00:10
5115.22.210.80 1 23.1 KB 2 0 000:04:08
671.94.21.184 1 11.0 KB 1 0 000:00:26
TOTAL: 6 440 269997.0 KB 154 0 102:10:16

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 380 148459.7 KB 94 0 000:50:06
2unresolved 12 76348.0 KB 33 0 101:18:45
3*.dyn.telefonica.de 41 35146.0 KB 20 0 000:00:56
4*.adsl.alicedsl.de 7 1043.3 KB 7 0 000:00:10
5*.etla.org 1 00.0 KB 0 0 000:00:00
6*.snarked.org 2 00.0 KB 0 0 000:00:16
7*.ip-94-23-43.eu 1 00.0 KB 0 0 000:00:00
8? 1 00.0 KB 0 0 000:00:00
TOTAL: 8 445 269997.0 KB 154 0 102:10:16

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
183.103.176.107 1.064 0.216 0.00001:14:10
mtrlpq2314w-lp130-02-1176034505.dsl.bell.ca 32.412 2.600 0.00000:50:06
115.22.210.80 0.096 0.004 0.00000:04:08
x55b41fcb.dyn.telefonica.de 2.708 0.252 0.00000:00:56
71.94.21.184 0.472 0.688 0.00000:00:26
news.snarked.org 0.200 0.048 0.00000:00:16
f051217085.adsl.alicedsl.de 0.532 0.024 0.00000:00:10
2001:ba8:1f1:f1ef::2 0.080 0.000 0.00000:00:00
ceres.etla.org 0.056 0.000 0.00000:00:00
ns369314.ip-94-23-43.eu 0.048 0.000 0.00000:00:00
TOTAL: 10 37.668 3.832 0.00002:10:16

Curious NNRP server explorers [Top 20]:

SystemConn
news.snarked.org 2
ns369314.ip-94-23-43.eu 1
ceres.etla.org 1
2001:ba8:1f1:f1ef::2 1
TOTAL: 4 5

NNRP client timeouts [Top 20]:

SystemConnPeer
71.94.21.184 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1de 45 16.7%
2rec 44 16.4%
3sci 42 15.6%
4comp 35 13.0%
5uk 26 9.7%
6alt 25 9.3%
7han 19 7.1%
8misc 9 3.3%
9linux 8 3.0%
10news 6 2.2%
11talk 6 2.2%
12perl 1 0.4%
13be 1 0.4%
14fr 1 0.4%
15aus 1 0.4%
TOTAL: 15 269100.0%

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

NewsgroupCount
1sci.electronics.design 29
2uk.d-i-y 25
3de.talk.jokes 19
4rec.arts.sf.written 16
5comp.unix.shell 15
6sci.physics 12
7han.test 11
8rec.sport.pro-wrestling 9
9misc.test 9
10linux.kernel 8
11de.comp.security.misc 7
12de.alt.rec.digitalfotografie 7
13de.etc.finanz.boerse 7
14news.answers 6
15alt.comp.hardware.pc-homebuilt 6
16alt.home.repair 5
17rec.woodworking 5
18talk.origins 5
19rec.crafts.metalworking 5
20comp.os.linux.misc 4
21comp.infosystems.www.servers.unix 3
22alt.happy.valley 3
23alt.usenet.kooks 3
24alt.christnet.christianlife 3
25han.sci.math 2
26rec.games.pinball 2
27comp.multimedia 2
28de.org.ccc 2
29comp.lang.pascal.borland 2
30rec.sport.football.college 2
31rec.gambling.poker 2
32comp.mail.misc 2
33han.news.admin 2
34han.sci.chem 2
35alt.christnet.prayer 1
36rec.food.cooking 1
37comp.compilers 1
38comp.mail.eudora.ms-windows 1
39sci.physics.relativity 1
40comp.os.cpm 1
41alt.comp.hardware.homebuilt 1
42de.admin.lists 1
43comp.admin.policy 1
44perl.perl6.compiler 1
45han.news.users 1
46han.school.middle 1
47comp.os.ms-windows.programmer.win32 1
48de.admin.news.announce 1
49fr.soc.politique 1
50de.comm.provider.usenet 1
51uk.rec.motorcycles 1
52alt.test 1
53rec.arts.comics.dc.universe 1
54talk.politics.misc 1
55comp.arch 1
56be.comp.internet 1
57comp.mail.sendmail 1
58rec.sport.tennis 1
59alt.smokers.pipes 1
60aus.cars 1
61alt.buddha.short.fat.guy 1
TOTAL: 61 269