Daily Usenet report for paganini.linuxd.org

Oct 9 00:00:02 -- Oct 10 00:00:03

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Oct 9 02:12:55 paganini overchan[20357]: input line too long, skipping
Oct 9 20:16:49 paganini pgpverify[18462]: pgpverify: verification failed
Oct 9 21:38:31 paganini pgpverify[26421]: pgpverify: verification failed
Oct 9 23:09:08 paganini overchan[20357]: input line too long, skipping
Oct 9 23:09:58 paganini overchan[20357]: input line too long, skipping
Oct 9 23:12:43 paganini overchan[20357]: input line too long, skipping
Oct 10 00:00:03 paganini overchan[6333]: 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 115136 69.5%14.3 MB 58.8%
inn 35100 21.2%7.8 MB 32.0%
innd 15056 9.1%2.2 MB 8.9%
nnrpd 302 0.2%43.0 KB 0.2%
nocem 47 0.0%8.6 KB 0.0%
overchan 6 0.0%0.8 KB 0.0%
controlchan 6 0.0%0.9 KB 0.0%
pgpverify 2 0.0%0.3 KB 0.0%
TOTAL: 8 165655 100.0%24.2 MB100.0%

History cache:

ReasonCount%Count
Positive hits 127194 54.8%
Negative hits 70289 30.3%
Do not exist 34705 14.9%
Cache misses 53 0.0%
TOTAL: 4 232241 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.059 0.0%125 0.000 0.472 2.133
article cleanup00:00:00.220 0.0%34719 0.000 0.006 0.022
article logging00:00:00.305 0.0%34982 0.000 0.009 0.029
article parse00:00:00.872 0.0%79617 0.000 0.011 0.038
article write00:00:42.097 0.0%20641 0.092 2.039 19.693
artlog/artcncl00:00:00.001 0.0%124 0.000 0.008 0.056
data move00:00:00.148 0.0%258464 0.000 0.001 0.003
hisgrep/artcncl00:00:00.001 0.0%123 0.000 0.008 0.071
hishave/artcncl00:00:00.000 0.0%125 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:10.313 0.0%232566 0.013 0.044 0.340
history sync00:00:00.063 0.0%400 0.000 0.158 15.333
history write00:00:29.974 0.0%34717 0.033 0.863 8.734
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:57:27.441 99.7%254397 176.479 339.027 1609.008
nntp read00:00:01.924 0.0%262885 0.003 0.007 0.016
overview write00:00:19.965 0.0%20641 0.020 0.967 4.869
perl filter00:01:16.656 0.1%34717 1.071 2.208 5.040
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.200 0.0%62029 0.000 0.019 0.057
TOTAL: 24:01:08.73924:00:31.239100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.085 0.0%36673 0.000 0.002 0.017
article prepare00:00:00.003 0.0%16200 0.000 0.000 0.014
article read00:00:00.548 0.0%3483 0.000 0.157 13.714
backlog stats00:00:00.181 0.0%303159 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.293 0.0%160543 0.002 0.008 0.015
data write00:00:02.833 0.0%140029 0.011 0.020 0.038
idle23:42:07.684 99.2%303159 202.968 281.462 495.193
status file00:10:49.784 0.8%48231 8.239 13.472 24.932
TOTAL: 23:53:18.97923:53:02.411100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle01:13:34.855 99.7%308 28.333 14333.945259165.571
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.219 0.1%808 0.000 2.746 63.229
readart00:00:00.062 0.0%509 0.056 0.122 8.250
TOTAL: 01:13:47.66401:13:37.136 99.8%----

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
grisbi-control@grisbi.org000200
TOTAL000200

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 5 45338 16497 26697 2144 36%48:09:12
2newsfeed.neva.ru 143 74343 2333 60741 11269 3%72:23:17
3news.fcku.it 1 7661 1624 5794 243 21%23:00:56
4nntp.pasdenom.info 4 13254 4 12931 319 0%11:54:54
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 178 140596 20458 106163 13975 14%155:28:19
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net52.7 MB64.4 KB14.8 MB67.5 MB 77%3.7 KB
2newsfeed.neva.ru6.6 MB28.5 KB86.2 MB92.8 MB 7%7.0 KB
3news.fcku.it5.1 MB0.0 KB6.3 MB11.4 MB 44%6.2 KB
4nntp.pasdenom.info9.5 KB0.0 KB1.3 MB1.3 MB 0%4.2 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 564.4 MB92.9 KB108.6 MB173.1 MB 37%5.1 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Oct 9 00:00:02 - 00:59:59 804 3.9% 0.222.3 MB 3.5% 0.65
Oct 9 01:00:00 - 01:59:59 703 3.4% 0.201.8 MB 2.8% 0.52
Oct 9 02:00:00 - 02:59:59 634 3.1% 0.181.9 MB 2.9% 0.53
Oct 9 03:00:00 - 03:59:59 601 2.9% 0.172.8 MB 4.4% 0.81
Oct 9 04:00:00 - 04:59:59 435 2.1% 0.121.7 MB 2.7% 0.49
Oct 9 05:00:00 - 05:59:59 418 2.0% 0.121.2 MB 1.8% 0.33
Oct 9 06:00:00 - 06:59:59 419 2.0% 0.121.6 MB 2.5% 0.47
Oct 9 07:00:00 - 07:59:59 385 1.9% 0.111.3 MB 2.0% 0.37
Oct 9 08:00:00 - 08:59:59 561 2.7% 0.161.9 MB 2.9% 0.53
Oct 9 09:00:00 - 09:59:59 692 3.4% 0.192.0 MB 3.0% 0.56
Oct 9 10:00:00 - 10:59:59 778 3.8% 0.222.0 MB 3.0% 0.56
Oct 9 11:00:00 - 11:59:59 880 4.3% 0.242.4 MB 3.6% 0.67
Oct 9 12:00:00 - 12:59:59 916 4.4% 0.253.3 MB 5.1% 0.93
Oct 9 13:00:00 - 13:59:59 910 4.4% 0.252.2 MB 3.4% 0.62
Oct 9 14:00:00 - 14:59:59 1051 5.1% 0.293.1 MB 4.8% 0.88
Oct 9 15:00:00 - 15:59:59 1020 4.9% 0.282.9 MB 4.4% 0.82
Oct 9 16:00:00 - 16:59:59 1056 5.1% 0.292.9 MB 4.4% 0.81
Oct 9 17:00:00 - 17:59:59 1250 6.1% 0.353.7 MB 5.7% 1.05
Oct 9 18:00:00 - 18:59:59 1270 6.2% 0.354.1 MB 6.4% 1.18
Oct 9 19:00:00 - 19:59:59 1233 6.0% 0.345.2 MB 8.1% 1.48
Oct 9 20:00:00 - 20:59:59 1273 6.2% 0.355.6 MB 8.7% 1.60
Oct 9 21:00:00 - 21:59:59 1231 6.0% 0.343.4 MB 5.3% 0.97
Oct 9 22:00:00 - 22:59:59 1110 5.4% 0.312.9 MB 4.5% 0.84
Oct 9 23:00:00 - 23:59:59 992 4.8% 0.282.7 MB 4.1% 0.76
Oct 10 00:00:00 - 00:00:03 4 0.0% 1.3318.9 KB 0.0% 6.32
TOTAL: 24:00:01 20626 100.0% 0.2464.8 MB 100.0% 0.77
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 11294 599 0 1 0 0 0 010694
2linuxd-out.news.weretis.net 2383 430 0 1 0 0 0 0 1952
3nntp.pasdenom.info 424 0 0 0 0 0 0 0 424
4news.fcku.it 249 2 0 0 0 0 0 0 247
TOTAL: 4 14350 1031 0 2 0 0 0 013317

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 195
ut.test 115
lada.talk 106
alt.survival 46
it-alt.sport.calcio.juventus 36
alt.windows7.general 35
mozilla.support.seamonkey 27
fa.netbsd.bugs 26
fa.openbsd.source-changes 24
spline.eisfair 21
fa.freebsd.cvs-all 19
it.comp.os.win.windows10 18
mpc.lists.nanog 18
cakewalk.audio 18
lada.auto 17
fa.openbsd.tech 15
alt.philosophy 13
uk.radio.amateur.moderated 12
mpc.lists.freebsd.gecko 11
mozilla.governance 10
TOTAL: 116 1031

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 9859
EMP (phn path) 2084
EMP (phl) 789
EMP (phn nph) 209
Too many newsgroups 66
Binary Payload 16
User-issued cancel 13
Binary Image: misplaced jpg 5
Binary: non-binary distribution 5
HTML post 4
Too many newsgroups (low_xpost) 2
Binary: misplaced par2 1
User-issued spam cancel 1
TOTAL: 13 13054

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 237
newsfeed.neva.ru 19
news.fcku.it 7
TOTAL: 3 263
TOTAL: 1 263

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 24978 915 16047 1931 0 2 3%23:59:54
2weretis.net 4413 439 3646 0 0 5 9%23:57:58
3nntp.pasdenom.info 9227 204 3743 26 0 18654 2%23:57:47
4neva.ru 28589 180 17750 344 0 39 0%23:59:54
5eternal-september 23568 26 17285 1 0 12 0%23:59:38
6vc89.vc.panix.com 2839 0 0 0 0 23464 0%23:59:54
7news.mixmin.net 0 0 0 0 0 0 0%00:10:00
8news.netfront.net 2751 0 0 0 0 23321 0%23:59:53
9news.stack.nl 2837 0 0 0 0 23345 0%23:59:54
10news.killfile.org 2849 0 0 0 0 23474 0%23:59:54
11etla.org 0 0 0 0 0 20626 0%23:59:54
12news.albasani.net 2858 0 0 0 0 22637 0%23:59:54
TOTAL: 12 104909 1764 58471 2302 0 155579 1%264:04:34
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it3.7 MB6.9 MB10.6 MB0.1 KB/s3.8 KB23:59:54
2weretis.net1.5 MB0.0 KB1.5 MB0.0 KB/s3.5 KB23:57:58
3neva.ru1.2 MB1.5 MB2.7 MB0.0 KB/s5.4 KB23:59:54
4nntp.pasdenom.info866.6 KB42.0 KB908.7 KB0.0 KB/s4.0 KB23:57:47
5eternal-september89.2 KB1.5 KB90.7 KB0.0 KB/s3.4 KB23:59:38
6vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
7news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
8news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
9news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
10news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
11etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
12news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:54
TOTAL: 127.3 MB8.5 MB15.8 MB0.0 KB/s4.0 KB264:04:34
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 3 59.6 KB 1 0 000:30:43
2c-73-195-247-24.hsd1.nj.comcast.net 2 47.8 KB 1 0 000:42:34
TOTAL: 2 5 917.3 KB 2 0 001:13:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 3 59.6 KB 1 0 000:30:43
2*.hsd1.nj.comcast.net 2 47.8 KB 1 0 000:42:34
3*.188.110.pools.vodafone-ip.de 88 00.0 KB 0 0 000:00:19
4unresolved 1 00.0 KB 0 0 000:00:09
TOTAL: 4 94 917.3 KB 2 0 001:13:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
c-73-195-247-24.hsd1.nj.comcast.net 0.172 0.012 0.00000:42:34
challenger.nielsenmedia.com 0.196 0.008 0.00000:30:43
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 4.696 0.328 0.00000:00:19
196.52.16.23 0.080 0.008 0.00000:00:09
TOTAL: 4 5.144 0.356 0.00001:13:47

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 88
196.52.16.23 1
TOTAL: 2 89

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 1 0
c-73-195-247-24.hsd1.nj.comcast.net 1 0
TOTAL: 2 2 0

Newsgroup request counts (by category):

CategoryCountPct
1comp 9100.0%
TOTAL: 1 9100.0%

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

NewsgroupCount
1comp.lang.awk 9
TOTAL: 1 9