Daily Usenet report for csiph.com

Feb 15 04:15:00 -- Feb 16 04:15:00

Unknown entries from news log file:

First 1 / 1 lines (100.0%)

Feb 15 09:50:03 csiph controlchan[16381]: Unknown "tags" control by robomod@news.nic.it

Log entries by program:

Program nameLines%LinesSize%Size
inn 43674 60.3%8.3 MB 71.8%
innd 23309 32.2%2.5 MB 21.5%
innfeed 4004 5.5%642.0 KB 5.4%
nnrpd 1308 1.8%147.6 KB 1.3%
nocem 92 0.1%10.3 KB 0.1%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 6 72389 100.0%11.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 441254 56.9%
Negative hits 291010 37.5%
Do not exist 42321 5.5%
Cache misses 633 0.1%
TOTAL: 4 775218 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.830 0.0%447 0.000 6.331 32.000
article cleanup00:00:00.273 0.0%42656 0.000 0.006 0.050
article logging00:00:04.719 0.0%43184 0.000 0.109 1.830
article parse00:00:02.351 0.0%139674 0.005 0.017 0.297
article write00:03:23.102 0.2%24386 0.212 8.329 42.374
artlog/artcncl00:00:00.037 0.0%417 0.000 0.089 1.000
artlog/artparse00:00:00.003 0.0%94 0.000 0.032 1.000
data move00:00:00.271 0.0%701415 0.000 0.000 0.003
hisgrep/artcncl00:00:00.236 0.0%303 0.000 0.779 11.000
hishave/artcncl00:00:00.600 0.0%447 0.000 1.342 9.000
hishave/artparse00:00:00.001 0.0%90 0.000 0.011 0.333
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:02:38.201 0.2%779193 0.002 0.203 1.826
history sync00:00:03.043 0.0%367 0.000 8.292 41.000
history write00:03:45.700 0.3%42396 0.075 5.324 45.010
hiswrite/artcncl00:00:01.151 0.0%144 0.000 7.993 23.000
hiswrite/artparse00:00:00.705 0.0%90 0.000 7.833 73.000
idle23:42:58.288 98.8%679142 70.606 125.715 387.696
nntp read00:00:03.278 0.0%715072 0.002 0.005 0.015
overview write00:04:39.791 0.3%24386 1.307 11.473 101.476
perl filter00:01:50.405 0.1%42325 1.538 2.609 11.949
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:00.462 0.0%48860 0.000 0.009 0.026
TOTAL: 24:00:57.70223:59:35.447 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.076 0.0%25554 0.000 0.003 0.024
article prepare00:00:00.003 0.0%39054 0.000 0.000 0.003
article read00:00:01.494 0.0%7577 0.000 0.197 9.500
backlog stats00:00:00.248 0.0%441960 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:02.411 0.0%346318 0.003 0.007 0.011
data write00:00:02.030 0.0%330443 0.001 0.006 0.014
idle23:53:59.697100.0%441960 113.724 194.678 550.868
status file00:00:00.464 0.0%626 0.000 0.741 20.000
TOTAL: 23:54:16.10323:54:06.423100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.390 0.0%95 0.000 4.105 59.000
idle76:19:45.182 99.8%740 540.000371331.327900201.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.007 0.0%1632 0.000 0.004 0.250
readart00:00:00.706 0.0%52 0.000 13.577 64.000
TOTAL: 76:28:43.78676:19:46.285 99.8%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1csiph-out.feeder.erje.net 1152 136763 9135 117543 10085 6%94:57:47
2csiph-out.news.weretis.net 4 57516 4119 52643 754 7%53:25:19
3feeder.eternal-september.org 1 38535 3438 30076 5021 8%23:58:54
4news-out.aioe.org 3 23626 1839 21753 34 7%24:09:56
5newsfeed.neva.ru 145 56539 1411 52408 2720 2%71:44:51
6newsfeed.fu-berlin.de 54 22612 1119 21102 391 4%29:18:21
7news-out.mixmin.net 3 26702 1061 25395 246 3%24:08:39
8news.redatomik.org 1 20390 1015 19370 5 4%21:35:37
9newsfeeds.xmission.com 3 23439 588 22844 7 2%24:34:58
10news.freedyn.net 3 27649 230 27243 176 0%24:09:49
11news.fcku.it 1 10512 181 10270 61 1%23:59:57
12nntp.pasdenom.info 6 23659 114 23535 10 0%24:01:32
13news.swapon.de 2 2375 86 2275 14 3%23:49:44
14xanadu-bbs.net 9 173 21 152 0 12%06:45:18
15news.bbs.geek.nz 1 16647 11 16636 0 0%23:59:50
16localhost 41 9 9 0 0 100%00:00:13
TOTAL: 16 1429 487146 24377 443245 19524 5%494:40:45
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1csiph-out.feeder.erje.net26.7 MB1.9 MB56.6 MB85.2 MB 31%4.5 KB
2csiph-out.news.weretis.net12.9 MB19.3 KB10.5 MB23.5 MB 54%4.9 KB
3feeder.eternal-september.org9.2 MB913.4 KB64.8 MB75.0 MB 12%9.1 KB
4news-out.mixmin.net5.0 MB19.5 KB977.2 KB5.9 MB 83%4.6 KB
5news-out.aioe.org4.6 MB1.6 KB52.0 KB4.7 MB 98%2.6 KB
6newsfeed.neva.ru3.6 MB417.5 KB24.6 MB28.6 MB 12%7.1 KB
7news.redatomik.org2.7 MB0.0 KB249.8 KB3.0 MB 91%3.0 KB
8newsfeed.fu-berlin.de2.5 MB0.0 KB1.1 MB3.5 MB 69%2.4 KB
9newsfeeds.xmission.com1.7 MB18.0 KB126.2 KB1.9 MB 92%3.2 KB
10news.freedyn.net866.7 KB0.0 KB1.5 MB2.3 MB 36%5.8 KB
11news.fcku.it356.7 KB0.0 KB283.0 KB639.7 KB 55%2.6 KB
12nntp.pasdenom.info286.3 KB0.0 KB19.2 KB305.5 KB 93%2.5 KB
13news.swapon.de233.0 KB0.0 KB44.3 KB277.3 KB 84%2.8 KB
14xanadu-bbs.net40.6 KB0.0 KB0.0 KB40.6 KB100%1.9 KB
15news.bbs.geek.nz22.3 KB0.0 KB0.0 KB22.3 KB100%2.0 KB
16localhost13.7 KB0.0 KB0.0 KB13.7 KB100%1.5 KB
TOTAL: 1670.7 MB3.3 MB160.7 MB234.7 MB 30%5.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 15 04:15:00 - 04:59:59 352 1.4% 0.131.2 MB 1.7% 0.46
Feb 15 05:00:00 - 05:59:59 574 2.4% 0.162.1 MB 2.9% 0.59
Feb 15 06:00:00 - 06:59:59 519 2.1% 0.141.8 MB 2.5% 0.50
Feb 15 07:00:00 - 07:59:59 653 2.7% 0.181.9 MB 2.6% 0.53
Feb 15 08:00:00 - 08:59:59 900 3.7% 0.252.5 MB 3.5% 0.71
Feb 15 09:00:00 - 09:59:59 1084 4.4% 0.303.0 MB 4.3% 0.87
Feb 15 10:00:00 - 10:59:59 1223 5.0% 0.343.1 MB 4.3% 0.87
Feb 15 11:00:00 - 11:59:59 1048 4.3% 0.292.9 MB 4.0% 0.81
Feb 15 12:00:00 - 12:59:59 1178 4.8% 0.332.8 MB 4.0% 0.80
Feb 15 13:00:00 - 13:59:59 1215 5.0% 0.343.1 MB 4.4% 0.88
Feb 15 14:00:00 - 14:59:59 1215 5.0% 0.343.1 MB 4.4% 0.88
Feb 15 15:00:00 - 15:59:59 1247 5.1% 0.353.3 MB 4.7% 0.94
Feb 15 16:00:00 - 16:59:59 1490 6.1% 0.414.6 MB 6.6% 1.32
Feb 15 17:00:00 - 17:59:59 1395 5.7% 0.394.0 MB 5.7% 1.14
Feb 15 18:00:00 - 18:59:59 1536 6.3% 0.434.3 MB 6.1% 1.24
Feb 15 19:00:00 - 19:59:59 1289 5.3% 0.363.9 MB 5.5% 1.11
Feb 15 20:00:00 - 20:59:59 1300 5.3% 0.363.9 MB 5.5% 1.11
Feb 15 21:00:00 - 21:59:59 1165 4.8% 0.323.2 MB 4.6% 0.92
Feb 15 22:00:00 - 22:59:59 1238 5.1% 0.343.3 MB 4.7% 0.95
Feb 15 23:00:00 - 23:59:59 1154 4.7% 0.323.8 MB 5.3% 1.07
Feb 16 00:00:00 - 00:59:59 826 3.4% 0.233.1 MB 4.4% 0.89
Feb 16 01:00:00 - 01:59:59 602 2.5% 0.172.1 MB 2.9% 0.59
Feb 16 02:00:00 - 02:59:59 589 2.4% 0.161.9 MB 2.7% 0.54
Feb 16 03:00:00 - 03:59:59 473 1.9% 0.131.5 MB 2.1% 0.42
Feb 16 04:00:00 - 04:15:00 124 0.5% 0.14349.9 KB 0.5% 0.39
TOTAL: 24:00:00 24389 100.0% 0.2870.7 MB 100.0% 0.84
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1csiph-out.feeder.erje.net 8904 397 0 154 1 0 0 0 8352
2feeder.eternal-september.org 5125 63 0 69 0 0 0 0 4993
3newsfeed.neva.ru 2696 308 0 35 0 0 0 0 2353
4newsfeed.fu-berlin.de 864 28 0 0 0 0 0 0 836
5csiph-out.news.weretis.net 690 145 0 2 0 0 0 0 543
6news-out.mixmin.net 260 0 0 2 0 0 0 0 258
7news.freedyn.net 176 8 0 0 0 0 0 0 168
8news.fcku.it 63 0 0 0 0 0 0 0 63
9news-out.aioe.org 41 33 0 1 0 0 0 0 7
10news.swapon.de 17 0 0 0 0 0 0 0 17
11newsfeeds.xmission.com 10 4 0 1 0 0 0 0 5
12news.redatomik.org 9 0 0 0 0 0 0 0 9
13nntp.pasdenom.info 8 3 0 0 0 0 0 0 5
14xanadu-bbs.net 3 0 0 0 0 0 0 0 3
15news.bbs.geek.nz 2 0 0 0 0 0 0 0 2
TOTAL: 15 18868 989 0 264 1 0 0 017614

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 147
ut.test 122
lada.auto 90
it-alt.sport.calcio.juventus 73
it.annunci.immobiliari 39
alt.windows7.general 37
it.annunci.commerciali 36
it.annunci.varie 33
gmane.linux.alsa.devel 31
alt.politics.scorched-earth 23
oesterreich.tratsch 19
lada.mobile 18
mpc.lists.freebsd.x11 17
spline.eisfair 15
mozilla.support.seamonkey 14
cakewalk.audio 11
mozilla.dev.tech.crypto 10
mozilla.dev.security.policy 10
gmane.comp.gis.openstreetmap.tagging 9
gmane.comp.gis.openstreetmap 8
TOTAL: 131 989

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
nz.politics.announce 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 14533
EMP (phn path) 1651
EMP (phl) 502
Too many newsgroups 83
EMP (phn nph) 75
Binary Payload 16
User-issued cancel 13
HTML Multipart 10
Binary Image: misplaced jpg 8
Scoring filter (8) 5
Scoring filter (16) 5
HTML post 5
Scoring filter (15) 5
Scoring filter (9) 5
Binary: misplaced PAR2 4
Scoring filter (13) 2
Binary: misplaced JPG 1
Scoring filter (10) 1
Too many newsgroups (low_xpost) 1
Binary: misplaced par2 1
TOTAL: 20 16926

NoCeM on Spool:

IdGoodBadUniqueTotal
bleachbot@httrack.com870105105
TOTAL: 1870105105

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
csiph-out.feeder.erje.net 66
TOTAL: 1 66
Huge articles
csiph-out.news.weretis.net 45
feeder.eternal-september.org 23
news-out.mixmin.net 9
news.fcku.it 3
news.redatomik.org 3
newsfeeds.xmission.com 3
newsfeed.fu-berlin.de 2
news.freedyn.net 1
news.swapon.de 1
TOTAL: 9 90
Including strange strings
newsfeed.fu-berlin.de 388
csiph-out.feeder.erje.net 62
feeder.eternal-september.org 31
news.swapon.de 9
news-out.mixmin.net 8
news-out.aioe.org 7
newsfeed.neva.ru 6
news.redatomik.org 5
csiph-out.news.weretis.net 4
newsfeeds.xmission.com 2
TOTAL: 13 527
No colon-space in header
csiph-out.feeder.erje.net 4
TOTAL: 1 4
TOTAL: 4 687

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.bbs.geek.nz 40484 5849 17672 85 0 0 14%23:50:11
2news.freedyn.net 24821 787 23296 1 1 0 3%23:50:11
3xanadu-bbs 27379 733 23585 7 0 0 2%23:59:56
4fu-berlin.de 22915 516 22399 0 0 0 2%23:59:55
5usenet.pasdenom.info 27437 215 22849 1208 0 1198 0%23:59:55
6xmission 27544 100 22941 4 0 1 0%23:59:56
7aioe 21608 93 20147 4 0 0 0%23:50:11
8news.redatomik.org 23565 36 22545 5 0 0 0%23:50:06
9eternal-september 21983 29 19846 0 0 1 0%23:59:55
10weretis.net 19626 26 18644 1 0 1 0%23:59:26
11news.mixmin.net 23019 13 21763 0 0 1 0%23:59:55
12erje 15108 11 15034 4 0 0 0%23:50:06
13news.swapon.de 2289 9 2177 2 0 0 0%23:53:12
14neva.ru 23453 7 21981 8 0 1 0%23:59:55
15news.fcku.it 3124 2 2969 0 0 0 0%22:55:08
16au2pb 0 0 0 0 0 23694 0%23:58:42
17bwh 0 0 0 0 0 23619 0%23:50:09
TOTAL: 17 324355 8426 277848 1329 1 48516 2%405:46:49
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.bbs.geek.nz14.8 MB414.2 KB15.2 MB0.2 KB/s2.6 KB23:50:11
2news.freedyn.net4.9 MB2.3 KB4.9 MB0.1 KB/s6.3 KB23:50:11
3xanadu-bbs4.7 MB15.9 KB4.7 MB0.1 KB/s6.5 KB23:59:56
4fu-berlin.de4.3 MB0.0 KB4.3 MB0.1 KB/s8.6 KB23:59:55
5usenet.pasdenom.info561.1 KB5.6 MB6.2 MB0.1 KB/s4.4 KB23:59:55
6aioe329.8 KB11.8 KB341.6 KB0.0 KB/s3.5 KB23:50:11
7xmission240.0 KB6.7 KB246.7 KB0.0 KB/s2.4 KB23:59:56
8eternal-september83.7 KB0.0 KB83.7 KB0.0 KB/s2.9 KB23:59:55
9news.redatomik.org73.0 KB57.7 KB130.7 KB0.0 KB/s3.2 KB23:50:06
10weretis.net72.7 KB5.0 KB77.7 KB0.0 KB/s2.9 KB23:59:26
11news.mixmin.net24.0 KB0.0 KB24.0 KB0.0 KB/s1.8 KB23:59:55
12erje16.7 KB17.0 KB33.7 KB0.0 KB/s2.2 KB23:50:06
13news.swapon.de13.7 KB3.7 KB17.4 KB0.0 KB/s1.6 KB23:53:12
14neva.ru11.9 KB17.8 KB29.6 KB0.0 KB/s2.0 KB23:59:55
15news.fcku.it3.8 KB0.0 KB3.8 KB0.0 KB/s1.9 KB22:55:08
16au2pb0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:42
17bwh0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:09
TOTAL: 1730.1 MB6.2 MB36.2 MB0.0 KB/s3.8 KB405:46:49
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1tempe0.bbox.io 17 2054.2 KB 6 0 004:47:09
2dhcp-089-099-203-254.chello.nl 51 1422.9 KB 8 0 012:03:58
368-185-181-64.dhcp.mdsn.wi.charter.com 28 1219.9 KB 9 9 708:36:00
4194-73-239-109.cust.centrio.cz 11 616.5 KB 4 0 004:32:12
TOTAL: 4 107 52113.5 KB 27 9 776:28:41

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.bbox.io 17 2054.2 KB 6 0 004:47:09
2*.chello.nl 51 1422.9 KB 8 0 012:03:58
3*.dhcp.mdsn.wi.charter.com 28 1219.9 KB 9 9 708:36:00
4*.cust.centrio.cz 11 616.5 KB 4 0 004:32:12
5unresolved 95 00.0 KB 0 0 045:59:16
6*.19-1.cable.virginm.net 1 00.0 KB 0 0 000:30:02
7*.shodan.io 1 00.0 KB 0 0 000:00:00
TOTAL: 7 204 52113.5 KB 27 9 776:28:41

NNRP auth users [Top 20]:

UserConn
ctomczyk 10
ohlandl 7
TOTAL: 2 17

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
216.239.174.218 7.218 1.454 0.00045:59:16
dhcp-089-099-203-254.chello.nl 4.386 0.825 0.00012:03:58
68-185-181-64.dhcp.mdsn.wi.charter.com 2.504 0.497 0.02708:36:00
tempe0.bbox.io 1.390 0.290 0.00004:47:09
194-73-239-109.cust.centrio.cz 0.988 0.240 0.04804:32:12
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net 0.093 0.008 0.00000:30:02
census8.shodan.io 0.074 0.025 0.00000:00:00
TOTAL: 7 16.653 3.339 0.07576:28:41

Curious NNRP server explorers [Top 20]:

SystemConn
216.239.174.218 95
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net 1
census8.shodan.io 1
TOTAL: 3 97

NNRP client timeouts [Top 20]:

SystemConnPeer
216.239.174.218 89 0
194-73-239-109.cust.centrio.cz 9 0
68-185-181-64.dhcp.mdsn.wi.charter.com 9 0
tempe0.bbox.io 8 0
cpc8-smal14-2-0-cust979.19-1.cable.virginm.net 1 0
TOTAL: 5 116 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 45 86.5%
2muc 7 13.5%
TOTAL: 2 52100.0%

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

NewsgroupCount
1comp.sys.ibm.ps2.hardware 39
2comp.lang.javascript 6
3muc.lists.freebsd.stable 6
4muc.lists.freebsd.emulation 1
TOTAL: 4 52