Daily Usenet report for csiph.com

Aug 3 04:15:00 -- Aug 4 04:15:00

Unknown entries from news log file:

First 3 / 3 lines (100.0%)

Aug 3 13:15:02 csiph pgpverify[32508]: pgpverify: verification failed
Aug 4 04:05:07 csiph nnrpd[74403]: unable to get certificate from '/usr/local/news/etc/cert.pem'
Aug 4 04:05:07 csiph nnrpd[74403]: error initializing TLS: [CA_file: ] [CA_path: /usr/local/news/etc] [cert_file: /usr/local/news/etc/cert.pem] [key_file: /usr/local/news/etc/key.pem]

Log entries by program:

Program nameLines%LinesSize%Size
inn 19125 46.9%4.8 MB 62.9%
innd 18020 44.2%2.2 MB 29.3%
innfeed 3407 8.4%590.5 KB 7.6%
nnrpd 185 0.5%18.4 KB 0.2%
nocem 27 0.1%2.5 KB 0.0%
controlchan 12 0.0%2.4 KB 0.0%
pgpverify 1 0.0%0.1 KB 0.0%
TOTAL: 7 40777 100.0%7.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 269161 57.9%
Negative hits 176635 38.0%
Do not exist 18596 4.0%
Cache misses 256 0.1%
TOTAL: 4 464648 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.215 0.0%57 0.000 3.772 23.000
article cleanup00:00:00.105 0.0%17540 0.000 0.006 0.061
article logging00:00:00.264 0.0%17920 0.000 0.015 0.046
article parse00:00:01.513 0.0%89374 0.000 0.017 0.303
article write00:00:04.843 0.0%14601 0.149 0.332 6.185
artlog/artcncl00:00:00.000 0.0%57 0.000 0.000 0.000
artlog/artparse00:00:00.014 0.0%1148 0.000 0.012 0.333
data move00:00:00.130 0.0%474500 0.000 0.000 0.001
hisgrep/artcncl00:00:00.023 0.0%57 0.000 0.404 4.200
hishave/artcncl00:00:00.039 0.0%57 0.000 0.684 7.000
hishave/artparse00:00:00.002 0.0%1147 0.000 0.002 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:04.027 0.0%465589 0.001 0.009 0.563
history sync00:00:00.756 0.0%380 0.000 1.989 15.000
history write00:00:08.255 0.0%17541 0.073 0.471 7.723
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
hiswrite/artparse00:00:00.583 0.0%1147 0.000 0.508 17.667
idle23:59:23.655 99.9%478617 72.524 180.444 601.790
nntp read00:00:01.950 0.0%477805 0.002 0.004 0.015
overview write00:00:15.169 0.0%14601 0.085 1.039 21.407
perl filter00:00:28.961 0.0%17539 1.083 1.651 2.453
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.204 0.0%29226 0.000 0.007 0.025
TOTAL: 24:01:00.87524:00:30.708100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.035 0.0%17831 0.000 0.002 0.024
article prepare00:00:00.000 0.0%17088 0.000 0.000 0.000
article read00:00:00.233 0.0%1166 0.000 0.200 15.333
backlog stats00:00:00.182 0.0%307333 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.143 0.0%235702 0.002 0.005 0.010
data write00:00:01.470 0.0%223364 0.001 0.007 0.013
idle23:54:11.799100.0%307333 153.027 279.995 839.442
status file00:00:00.214 0.0%303 0.000 0.706 2.000
TOTAL: 23:54:21.51223:54:15.076100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:27:21.875 99.7%187 0.000105036.765450224.500
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:32.497 0.2%114828 0.000 0.283 0.284
readart00:00:00.519 0.0%42 0.000 12.357 63.000
TOTAL: 05:28:15.73005:27:54.891 99.9%----

Control commands to INND:

CommandNumber
L 1
flush 5
flushlogs 2
go 2
logmode 2
mode 138
pause 2
paused 2
reload 2
reserve 2
TOTAL: 10 158

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.news.weretis.net 3 28797 3041 25618 138 10%47:40:35
2feeder.eternal-september.org 4 16023 1873 14049 101 11%23:31:39
3newsfeeds.xmission.com 2 12865 1668 11196 1 12%22:19:07
4news-out.aioe.org 2 13877 1396 12467 14 10%23:59:46
5csiph-out.feeder.erje.net 1439 71919 1358 70289 272 1%118:47:27
6news.freedyn.net 3 17490 1306 14736 1448 7%24:09:02
7news-out.mixmin.net 2 15473 1065 14143 265 6%23:59:51
8csiph-out.news.xs4all.nl 861 16246 794 14796 656 4%71:26:08
9newsfeed.fu-berlin.de 7 11660 735 10722 203 6%19:28:24
10newsfeed.neva.ru 146 17320 655 16165 500 3%71:52:32
11news.fcku.it 7 1390 194 1194 2 13%21:41:46
12csiph-out.feed.uzoreto.com 864 22604 189 22097 318 0%71:12:01
13nntp.pasdenom.info 4 9382 44 9332 6 0%24:01:09
14news.swapon.de 10 1113 30 1083 0 2%24:00:52
15news.samoylyk.net 2 14167 17 14149 1 0%23:59:10
16news.bbs.nz 1 14879 1 14878 0 0%23:59:52
17xanadu-bbs.net 19 717 1 685 31 0%20:28:30
18localhost 31 0 0 0 0 0%00:00:03
TOTAL: 18 3407 285922 14367 267599 3956 5%656:37:54
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net10.9 MB0.0 KB7.7 MB18.6 MB 58%11.7 KB
2csiph-out.news.weretis.net9.3 MB0.0 KB4.2 MB13.5 MB 69%4.4 KB
3feeder.eternal-september.org5.2 MB0.0 KB1.2 MB6.4 MB 81%3.3 KB
4newsfeeds.xmission.com4.8 MB0.0 KB1.7 KB4.8 MB 99%3.0 KB
5news.freedyn.net3.6 MB0.0 KB87.8 MB91.4 MB 3%34.0 KB
6news-out.aioe.org3.6 MB0.0 KB35.3 KB3.6 MB 99%2.6 KB
7news-out.mixmin.net2.8 MB0.0 KB697.7 KB3.5 MB 80%2.7 KB
8csiph-out.news.xs4all.nl1.9 MB0.0 KB5.9 MB7.8 MB 24%5.5 KB
9newsfeed.neva.ru1.8 MB0.0 KB2.6 MB4.4 MB 41%3.9 KB
10newsfeed.fu-berlin.de1.7 MB0.0 KB558.2 KB2.2 MB 75%2.4 KB
11csiph-out.feed.uzoreto.com581.5 KB0.0 KB10.2 MB10.7 MB 5%21.7 KB
12news.fcku.it328.8 KB0.0 KB6.0 KB334.8 KB 98%1.7 KB
13nntp.pasdenom.info108.1 KB0.0 KB12.1 KB120.2 KB 89%2.4 KB
14news.swapon.de68.6 KB0.0 KB0.0 KB68.6 KB100%2.3 KB
15news.samoylyk.net45.1 KB0.0 KB32.9 KB78.0 KB 57%4.3 KB
16news.bbs.nz1.9 KB0.0 KB0.0 KB1.9 KB100%1.9 KB
17xanadu-bbs.net1.3 KB0.0 KB2.2 MB2.2 MB 0%69.8 KB
18localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 1846.9 MB0.0 KB122.9 MB169.8 MB 27%9.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Aug 3 04:15:00 - 04:59:59 248 1.7% 0.09752.9 KB 1.6% 0.28
Aug 3 05:00:00 - 05:59:59 332 2.3% 0.09972.1 KB 2.0% 0.27
Aug 3 06:00:00 - 06:59:59 398 2.7% 0.111.2 MB 2.6% 0.35
Aug 3 07:00:00 - 07:59:59 465 3.2% 0.131.4 MB 2.9% 0.39
Aug 3 08:00:00 - 08:59:59 708 4.8% 0.203.3 MB 6.9% 0.94
Aug 3 09:00:00 - 09:59:59 669 4.6% 0.191.9 MB 4.0% 0.54
Aug 3 10:00:00 - 10:59:59 602 4.1% 0.171.5 MB 3.2% 0.43
Aug 3 11:00:00 - 11:59:59 706 4.8% 0.203.1 MB 6.5% 0.87
Aug 3 12:00:00 - 12:59:59 713 4.9% 0.202.1 MB 4.4% 0.59
Aug 3 13:00:00 - 13:59:59 782 5.4% 0.222.3 MB 4.8% 0.64
Aug 3 14:00:00 - 14:59:59 849 5.8% 0.243.3 MB 7.0% 0.94
Aug 3 15:00:00 - 15:59:59 883 6.0% 0.253.9 MB 8.3% 1.12
Aug 3 16:00:00 - 16:59:59 744 5.1% 0.212.6 MB 5.4% 0.73
Aug 3 17:00:00 - 17:59:59 772 5.3% 0.212.1 MB 4.4% 0.60
Aug 3 18:00:00 - 18:59:59 729 5.0% 0.202.1 MB 4.3% 0.59
Aug 3 19:00:00 - 19:59:59 746 5.1% 0.212.3 MB 4.9% 0.66
Aug 3 20:00:00 - 20:59:59 795 5.4% 0.222.4 MB 5.1% 0.68
Aug 3 21:00:00 - 21:59:59 722 4.9% 0.202.1 MB 4.4% 0.60
Aug 3 22:00:00 - 22:59:59 580 4.0% 0.161.6 MB 3.3% 0.44
Aug 3 23:00:00 - 23:59:59 465 3.2% 0.131.7 MB 3.6% 0.48
Aug 4 00:00:00 - 00:59:59 374 2.6% 0.101.1 MB 2.3% 0.31
Aug 4 01:00:00 - 01:59:59 572 3.9% 0.161.7 MB 3.5% 0.47
Aug 4 02:00:00 - 02:59:59 402 2.8% 0.111.2 MB 2.6% 0.35
Aug 4 03:00:00 - 03:59:59 257 1.8% 0.07770.8 KB 1.6% 0.21
Aug 4 04:00:00 - 04:15:00 89 0.6% 0.10262.8 KB 0.5% 0.29
TOTAL: 24:00:00 14602 100.0% 0.1747.4 MB 100.0% 0.56
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.freedyn.net 1534 27 0 0 0 0 0 0 1507
2csiph-out.news.xs4all.nl 664 23 0 0 0 0 0 0 641
3newsfeed.fu-berlin.de 585 27 0 0 0 0 0 0 558
4newsfeed.neva.ru 498 45 0 0 0 0 0 0 453
5csiph-out.feed.uzoreto.com 317 20 0 0 0 0 0 0 297
6csiph-out.feeder.erje.net 274 39 0 0 0 0 0 0 235
7news-out.mixmin.net 273 9 0 0 0 0 0 0 264
8csiph-out.news.weretis.net 147 55 0 0 0 0 0 0 92
9feeder.eternal-september.org 107 23 0 0 0 0 0 0 84
10xanadu-bbs.net 31 0 0 0 0 0 0 0 31
11news-out.aioe.org 18 13 0 0 0 0 0 0 5
12news.swapon.de 6 0 0 0 0 0 0 0 6
13nntp.pasdenom.info 6 0 0 0 0 0 0 0 6
14news.fcku.it 4 0 0 0 0 0 0 0 4
15newsfeeds.xmission.com 2 1 0 0 0 0 0 0 1
TOTAL: 15 4466 282 0 0 0 0 0 0 4184

Unwanted newsgroups [Top 20]:

NewsgroupCount
alt.computer.workshop 73
alt.bbs.synchronet 42
lada.talk 34
alt.windows7.general 15
alt.binaries.bungalow 13
oesterreich.tratsch 9
alt.binaries.pictures.purebeauty 6
alt.music.pink-floyd.publius 6
alt.job 6
alt.eunuchs 5
mozilla.general 4
microsoft.public.test1 4
alt.politics 3
alt.religion 3
spline.fli4l.dev 3
alt.paranormal.spells 3
spline.eisfair.dev 2
spline.eisfair 2
rec.travel.resorts.all 2
it-alt.sport.calcio.juventus 2
TOTAL: 59 282

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 2025
EMP (phn path) 295
EMP (phn nph) 106
Too many newsgroups 72
Binary: misplaced rar 64
EMP (phl) 35
User-issued cancel 16
HTML Multipart 12
Binary Payload 9
Binary: misplaced par2 5
Scoring filter (10) 5
User-issued spam cancel 4
Scoring filter (11) 2
Scoring filter (8) 2
Scoring filter (9) 1
TOTAL: 15 2653

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com1701818
TOTAL: 11761818

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Bad Message-ID's offered
csiph-out.feeder.erje.net 45
TOTAL: 1 45
Huge articles
news.freedyn.net 731
csiph-out.feeder.erje.net 143
csiph-out.feed.uzoreto.com 124
csiph-out.news.xs4all.nl 60
csiph-out.news.weretis.net 47
xanadu-bbs.net 31
feeder.eternal-september.org 10
news-out.mixmin.net 3
newsfeeds.xmission.com 1
TOTAL: 9 1150
Including strange strings
newsfeed.fu-berlin.de 338
csiph-out.news.xs4all.nl 9
csiph-out.news.weretis.net 9
news.swapon.de 6
news-out.mixmin.net 5
news-out.aioe.org 5
feeder.eternal-september.org 2
csiph-out.feeder.erje.net 2
news.fcku.it 2
news.freedyn.net 1
TOTAL: 10 379
No colon-space in header
csiph-out.feeder.erje.net 1
TOTAL: 1 1
TOTAL: 4 1575

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1xanadu-bbs 16491 762 13839 0 0 3188 4%23:59:55
2news.samoylyk.net 15063 729 13854 2 0 0 4%23:59:54
3news.freedyn.net 13506 676 12501 73 0 0 5%23:59:34
4fu-berlin.de 13682 664 13018 0 0 0 4%23:59:29
5news.bbs.nz 27103 246 13616 5 0 1 0%23:59:49
6usenet.pasdenom.info 15012 61 13754 743 0 26 0%23:59:55
7newsfeed.xs4all.nl 14211 30 13362 5 0 51 0%23:59:54
8xmission 16395 25 12081 1 0 0 0%23:59:55
9erje 13839 12 13121 12 0 0 0%23:59:55
10news.uzoreto.com 14097 9 13915 88 0 0 0%23:59:46
11weretis.net 10734 6 10622 1 0 0 0%23:59:01
12aioe 12213 5 11846 1 0 0 0%23:59:54
13neva.ru 13202 4 12955 106 0 1 0%23:59:39
14eternal-september 12390 2 11898 0 0 0 0%23:59:55
15news.swapon.de 1351 2 1331 0 0 0 0%23:23:42
16news.mixmin.net 12948 1 12617 0 0 0 0%23:59:37
17news.fcku.it 1136 1 1131 0 0 0 0%21:10:36
18news.redatomik.org 0 0 0 0 0 13870 0%23:59:55
TOTAL: 18 223373 3235 195461 1037 0 17137 1%428:30:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1xanadu-bbs9.0 MB0.0 KB9.0 MB0.1 KB/s12.1 KB23:59:55
2news.samoylyk.net8.9 MB2.1 KB8.9 MB0.1 KB/s12.5 KB23:59:54
3news.freedyn.net8.8 MB161.1 KB9.0 MB0.1 KB/s12.3 KB23:59:34
4fu-berlin.de8.8 MB0.0 KB8.8 MB0.1 KB/s13.6 KB23:59:29
5news.bbs.nz620.3 KB16.7 KB637.0 KB0.0 KB/s2.5 KB23:59:49
6newsfeed.xs4all.nl203.1 KB39.9 KB243.0 KB0.0 KB/s6.9 KB23:59:54
7usenet.pasdenom.info190.9 KB8.9 MB9.1 MB0.1 KB/s11.6 KB23:59:55
8xmission67.9 KB2.5 KB70.5 KB0.0 KB/s2.7 KB23:59:55
9erje23.1 KB66.4 KB89.4 KB0.0 KB/s3.7 KB23:59:55
10news.uzoreto.com17.3 KB488.0 KB505.3 KB0.0 KB/s5.2 KB23:59:46
11weretis.net15.5 KB1.3 KB16.8 KB0.0 KB/s2.4 KB23:59:01
12aioe12.0 KB1.8 KB13.8 KB0.0 KB/s2.3 KB23:59:54
13neva.ru7.5 KB228.3 KB235.8 KB0.0 KB/s2.1 KB23:59:39
14eternal-september6.8 KB0.0 KB6.8 KB0.0 KB/s3.4 KB23:59:55
15news.swapon.de3.2 KB0.0 KB3.2 KB0.0 KB/s1.6 KB23:23:42
16news.mixmin.net2.7 KB0.0 KB2.7 KB0.0 KB/s2.7 KB23:59:37
17news.fcku.it1.5 KB0.0 KB1.5 KB0.0 KB/s1.5 KB21:10:36
18news.redatomik.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:55
TOTAL: 1836.7 MB9.9 MB46.6 MB0.0 KB/s11.2 KB428:30:25
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mail.nappey.org 4 39107.5 KB 5 0 002:37:51
2a131033.upc-a.chello.nl 4 34.0 KB 2 0 000:49:55
TOTAL: 2 8 42111.5 KB 7 0 005:28:15

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nappey.org 4 39107.5 KB 5 0 002:37:51
2*.upc-a.chello.nl 4 34.0 KB 2 0 000:49:55
3unresolved 3 00.0 KB 0 0 000:00:12
4*.ph.ph.cox.net 4 00.0 KB 0 0 002:00:16
TOTAL: 4 15 42111.5 KB 7 0 005:28:15

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
mail.nappey.org 0.959 2.040 0.00002:37:51
ip72-222-200-117.ph.ph.cox.net 0.435 0.093 0.00002:00:16
a131033.upc-a.chello.nl 0.342 0.063 0.00000:49:55
164.52.24.176 0.200 0.054 0.00000:00:12
TOTAL: 4 1.936 2.250 0.00005:28:15

Curious NNRP server explorers [Top 20]:

SystemConn
ip72-222-200-117.ph.ph.cox.net 4
164.52.24.176 3
TOTAL: 2 7

NNRP unrecognized commands (by host):

SystemConn
164.52.24.176 2
TOTAL: 1 2

NNRP unrecognized commands (by command):

CommandCount
À/À+À'À#À^SÀ\x09 1
^V^C^A^A"^A 1
TOTAL: 2 2

NNRP client timeouts [Top 20]:

SystemConnPeer
mail.nappey.org 4 0
ip72-222-200-117.ph.ph.cox.net 4 0
164.52.24.176 1 1
TOTAL: 3 9 1

Newsgroup request counts (by category):

CategoryCountPct
1fr 35 83.3%
2comp 7 16.7%
TOTAL: 2 42100.0%

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

NewsgroupCount
1fr.comp.text.tex 35
2comp.text.tex 4
3comp.sys.ibm.ps2.hardware 3
TOTAL: 3 42