Daily Usenet report

Sep 12 04:15:02 -- Sep 13 04:15:03

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Sep 12 07:02:37 newsfeed overchan[26316]: input line too long, skipping
Sep 12 07:02:48 newsfeed overchan[26316]: input line too long, skipping
Sep 12 16:03:41 newsfeed nnrpd[21262]: SERVER perl filter not defined
Sep 12 16:06:45 newsfeed nnrpd[21478]: SERVER perl filter not defined
Sep 12 16:27:17 newsfeed overchan[26316]: input line too long, skipping
Sep 12 16:45:42 newsfeed nnrpd[22797]: SERVER perl filter not defined
Sep 12 18:09:16 newsfeed nnrpd[25981]: SERVER perl filter not defined
Sep 12 18:12:37 newsfeed nnrpd[26067]: SERVER perl filter not defined
Sep 12 19:41:41 newsfeed overchan[1036]: input line too long, skipping
Sep 12 19:56:04 newsfeed nnrpd[5272]: SERVER perl filter not defined
Sep 12 20:12:02 newsfeed overchan[1036]: input line too long, skipping
Sep 12 20:28:38 newsfeed overchan[1036]: input line too long, skipping
Sep 12 20:37:53 newsfeed overchan[1036]: input line too long, skipping
Sep 12 23:27:07 newsfeed overchan[1036]: input line too long, skipping
Sep 12 23:27:26 newsfeed overchan[1036]: input line too long, skipping
Sep 13 00:07:49 newsfeed overchan[1036]: input line too long, skipping
Sep 13 03:22:51 newsfeed overchan[1036]: input line too long, skipping
Sep 13 03:23:03 newsfeed overchan[1036]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2705002 97.5%309.8 MB 97.2%
innfeed 45194 1.6%5.4 MB 1.7%
innd 18740 0.7%3.1 MB 1.0%
nnrpd 5208 0.2%566.0 KB 0.2%
overchan 13 0.0%2.2 KB 0.0%
controlchan 3 0.0%0.2 KB 0.0%
TOTAL: 62774160 100.0%318.8 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2660001 95.1%
Negative hits 76700 2.7%
Positive hits 59425 2.1%
Cache misses 276 0.0%
TOTAL: 4 2796402 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:02.230 0.0%79 0.000 28.228 141.000
article cleanup00:00:33.622 0.0%2689279 0.008 0.013 0.016
article logging00:01:54.543 0.1%2689706 0.029 0.043 0.067
article parse00:50:32.124 3.5%93057566 0.018 0.033 0.051
article write00:18:39.894 1.3%2683214 0.245 0.417 0.574
artlog/artcncl00:00:00.002 0.0%79 0.000 0.025 1.000
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:03:05.865 0.2%108315544 0.001 0.002 0.003
hisgrep/artcncl00:00:00.215 0.0%78 0.000 2.756 13.000
hishave/artcncl00:00:00.070 0.0%79 0.000 0.886 13.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup02:13:24.194 9.3%2823479 0.762 2.835 9.047
history sync00:00:00.020 0.0%287 0.000 0.070 0.500
history write03:15:56.773 13.7%2684863 0.710 4.379 10.971
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle11:06:15.132 46.6%63574242 0.304 0.629 1.321
nntp read01:10:53.736 5.0%90985531 0.027 0.047 0.064
overview write00:00:13.203 0.0%2683214 0.003 0.005 0.007
perl filter04:19:10.877 18.1%2684859 3.625 5.792 8.394
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:04:57.686 0.3%5492944 0.035 0.054 0.073
TOTAL: 23:50:03.42823:25:40.186 98.3%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.708 0.0%236831 0.000 0.003 0.009
article prepare00:00:00.051 0.0%243302 0.000 0.000 0.001
article read00:00:36.480 0.0%58067 0.000 0.628 119.714
backlog stats00:00:00.966 0.0%1037356 0.000 0.001 0.003
callbacks00:00:00.000 0.0%1 0.000 0.000 0.000
data read00:00:08.592 0.0%476720 0.003 0.018 0.034
data write00:01:39.012 0.1%577595 0.023 0.171 0.308
idle23:39:16.958 99.7%1037356 23.254 82.090 747.732
status file00:00:53.881 0.1%16567 0.720 3.252 28.345
TOTAL: 23:43:14.71223:42:36.648100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:37.202 72.2%3149 0.000 11.814 9322.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.300 0.6%6998 0.000 0.043 1.368
readart00:00:00.003 0.0%22 0.000 0.136 0.500
TOTAL: 00:00:51.55000:00:37.505 72.8%----

Control commands to INND:

CommandNumber
L 1
flush 27
flushlogs 2
go 2
logmode 2
mode 151
name 4
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 197

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 802 26950232690337 2554 2132 99%466:56:48
2news.nask.pl 3 62200 4662 53587 3951 7%23:36:12
3news.icm.edu.pl 47 263 86 155 22 32%17:20:55
4localhost 7 6 6 0 0 100%00:00:00
TOTAL: 4 859 27574922695091 56296 6105 97%507:53:55
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl790.6 GB75.2 MB79.2 MB790.7 GB 99%307.9 KB
2news.nask.pl90.7 MB368.5 MB378.9 MB838.1 MB 10%99.6 KB
3news.icm.edu.pl1.6 MB14.4 KB446.0 KB2.0 MB 77%19.1 KB
4localhost8.0 KB0.0 KB0.0 KB8.0 KB100%1.3 KB
TOTAL: 4790.7 GB443.7 MB458.5 MB791.5 GB 99%307.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Sep 12 04:15:02 - 04:59:59 95968 3.6% 35.5728.1 GB 3.5%10912.81
Sep 12 05:00:00 - 05:59:59 123309 4.6% 34.2536.6 GB 4.6%10673.74
Sep 12 06:00:00 - 06:59:59 116215 4.3% 32.2835.2 GB 4.4%10260.80
Sep 12 07:00:00 - 07:59:59 125675 4.7% 34.9137.0 GB 4.7%10767.07
Sep 12 08:00:00 - 08:59:59 128283 4.8% 35.6336.9 GB 4.7%10741.62
Sep 12 09:00:00 - 09:59:59 135744 5.0% 37.7139.5 GB 5.0%11511.71
Sep 12 10:00:00 - 10:59:59 134870 5.0% 37.4639.2 GB 5.0%11427.39
Sep 12 11:00:00 - 11:59:59 136492 5.1% 37.9138.2 GB 4.8%11131.98
Sep 12 12:00:00 - 12:59:59 135716 5.0% 37.7038.8 GB 4.9%11298.30
Sep 12 13:00:00 - 13:59:59 133505 4.9% 37.0838.3 GB 4.8%11159.04
Sep 12 14:00:00 - 14:59:59 105927 3.9% 29.4231.8 GB 4.0%9258.86
Sep 12 15:00:00 - 15:59:59 108739 4.0% 30.2132.1 GB 4.1%9357.91
Sep 12 16:00:00 - 16:59:59 80452 3.0% 22.3523.8 GB 3.0%6926.14
Sep 12 17:00:00 - 17:59:59 130286 4.8% 36.1936.6 GB 4.6%10650.30
Sep 12 18:00:00 - 18:59:59 128096 4.7% 35.5838.2 GB 4.8%11123.55
Sep 12 19:00:00 - 19:59:59 107538 4.0% 29.8731.7 GB 4.0%9245.75
Sep 12 20:00:00 - 20:59:59 123828 4.6% 34.4036.8 GB 4.6%10710.16
Sep 12 21:00:00 - 21:59:59 73969 2.7% 20.5524.5 GB 3.1%7126.80
Sep 12 22:00:00 - 22:59:59 111244 4.1% 30.9029.4 GB 3.7%8549.68
Sep 12 23:00:00 - 23:59:59 84002 3.1% 23.3327.6 GB 3.5%8051.40
Sep 13 00:00:00 - 00:59:59 77692 2.9% 21.5819.7 GB 2.5%5734.24
Sep 13 01:00:00 - 01:59:59 81353 3.0% 22.6021.0 GB 2.7%6122.84
Sep 13 02:00:00 - 02:59:59 109454 4.1% 30.4031.1 GB 3.9%9052.01
Sep 13 03:00:00 - 03:59:59 87788 3.3% 24.3931.6 GB 4.0%9193.88
Sep 13 04:00:00 - 04:15:03 22262 0.8% 24.658.2 GB 1.0%9575.01
TOTAL: 24:00:01 2698407 100.0% 31.23791.9 GB 100.0%9610.53
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 4021 12 0 2501 3 0 0 0 1505
2newsfeed.pionier.net.pl 2469 172 0 1904 6 0 0 0 387
3news.icm.edu.pl 23 0 0 1 0 0 0 0 22
TOTAL: 3 6513 184 0 4406 9 0 0 0 1914

Unwanted newsgroups [Top 20]:

NewsgroupCount
it.tlc.cellulari.android 61
uk.railway 38
it.comp.os.win.windows10 38
uk.rec.cycling.moderated 13
nl.comp.sys.android 10
comp.mobile.android 8
comp.sys.raspberry-pi 8
it.discussioni.energia 2
nl.comp.os.linux.techniek 2
de.rec.sf.startrek 2
de.comp.office-pakete.ms-office.word 1
it.comp.os.win.windows7 1
TOTAL: 12 184

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

GroupsCount
pl.praca.oferowana 8
bit.listserv.catholic 1
TOTAL: 2 9

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 1304
Binary in non-binary group 89
EMP (phl) 22
Scoring filter (8) 20
Scoring filter (9) 15
Scoring filter (16) 11
Scoring filter (10) 9
Scoring filter (18) 3
Too many newsgroups 1
Scoring filter (12) 1
TOTAL: 10 1475

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Article posted in the future
newsfeed.pionier.net.pl 4
TOTAL: 1 4
Including strange strings
newsfeed.pionier.net.pl 342
news.nask.pl 92
news.icm.edu.pl 1
TOTAL: 3 435
TOTAL: 2 439

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nask 153503 57104 57938 19 0 284 37%23:55:22
2panorama 125612 335 97729 82 3 140669 0%23:58:29
3poznan 6186 134 4423 16 0 1 2%23:55:22
4icm 143491 38 122090 3438 0 1 0%23:55:22
5okapi 0 0 0 0 0 1101 0%23:43:14
TOTAL: 5 428792 57611 282180 3555 3 142056 13%119:27:49
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nask28.3 GB7.0 MB28.4 GB345.3 KB/s520.5 KB23:55:22
2icm3.8 MB1.7 GB1.7 GB21.1 KB/s521.9 KB23:55:22
3panorama3.4 MB272.9 KB3.6 MB0.0 KB/s8.9 KB23:58:29
4poznan422.9 KB41.0 KB463.9 KB0.0 KB/s3.1 KB23:55:22
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:43:14
TOTAL: 528.4 GB1.7 GB30.1 GB73.4 KB/s515.8 KB119:27:49
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama1.9 MB
TOTAL: 11.9 MB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 287 2219.9 KB 9 0 000:00:27
2isvcs01.man.lodz.pl 6 00.0 KB 0 6 000:00:00
TOTAL: 2 293 2219.9 KB 9 6 000:00:50

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 784 2219.9 KB 9 6 000:00:34
2*.208-100-26.static.steadfastdns.net 2 00.0 KB 0 0 000:00:01
3*.centertel.pl 2 00.0 KB 0 0 000:00:05
4unresolved 1 00.0 KB 0 0 000:00:09
TOTAL: 4 789 2219.9 KB 9 6 000:00:50

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.316 3.956 0.00000:00:27
107.170.204.213 0.004 0.016 0.00000:00:09
mon.man.lodz.pl 1.788 4.896 0.00000:00:05
public-gprs354848.centertel.pl 0.008 0.024 0.00000:00:05
ip232.208-100-26.static.steadfastdns.net 0.012 0.028 0.00000:00:01
david.man.lodz.pl 0.400 1.292 0.00000:00:01
isvcs01.man.lodz.pl 0.008 0.116 0.00000:00:00
TOTAL: 7 3.536 10.328 0.00000:00:50

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 395
david.man.lodz.pl 96
ip232.208-100-26.static.steadfastdns.net 2
public-gprs354848.centertel.pl 2
107.170.204.213 1
TOTAL: 5 496

Newsgroup request counts (by category):

CategoryCountPct
1pl 22100.0%
TOTAL: 1 22100.0%

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

NewsgroupCount
1pl.comp.os.ms-windows.winnt 16
2pl.comp.pecet 6
TOTAL: 2 22