Daily Usenet report

May 20 04:15:02 -- May 21 04:15:03

Unknown entries from news log file:

First 29 / 29 lines (100.0%)

May 20 06:52:32 newsfeed overchan[27527]: input line too long, skipping
May 20 07:49:36 newsfeed overchan[27527]: input line too long, skipping
May 20 07:50:51 newsfeed overchan[27527]: input line too long, skipping
May 20 07:51:32 newsfeed overchan[27527]: input line too long, skipping
May 20 07:51:49 newsfeed overchan[27527]: input line too long, skipping
May 20 07:52:27 newsfeed overchan[27527]: input line too long, skipping
May 20 07:52:45 newsfeed overchan[27527]: input line too long, skipping
May 20 08:37:10 newsfeed nnrpd[5180]: SERVER perl filter not defined
May 20 08:37:25 newsfeed nnrpd[5186]: SERVER perl filter not defined
May 20 11:01:47 newsfeed nnrpd[10573]: SERVER perl filter not defined
May 20 12:11:50 newsfeed nnrpd[13136]: SERVER perl filter not defined
May 20 12:12:18 newsfeed nnrpd[13148]: SERVER perl filter not defined
May 20 12:15:37 newsfeed nnrpd[13241]: SERVER perl filter not defined
May 20 12:22:29 newsfeed innfeed[1074]: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
May 20 12:22:29 newsfeed innfeed[1074]: SM: storage method 'cnfs' failed initialization
May 20 12:22:29 newsfeed innfeed[1074]: SM: one or more storage methods failed initialization
May 20 12:22:29 newsfeed innfeed[1074]: Storage manager initialization failed -- it is OK after a change in storage methods
May 20 12:22:29 newsfeed innd: CNFS: Mismatch 3: read BUF04 for cycbuff BUF03
May 20 12:22:29 newsfeed innd: SM: storage method 'cnfs' failed initialization
May 20 12:22:29 newsfeed innd: SM: one or more storage methods failed initialization
May 20 13:02:49 newsfeed innd: CNFS: ERROR opening '/var/spool/news/cycbuffs/big8' O_RDONLY: Permission denied
May 20 13:02:49 newsfeed innd: SM: storage method 'cnfs' failed initialization
May 20 13:02:49 newsfeed innd: SM: one or more storage methods failed initialization
May 20 13:25:29 newsfeed overchan[1065]: input line too long, skipping
May 20 14:02:25 newsfeed nnrpd[4761]: message repeated 2 times: [ pc-tom.man.lodz.pl group alt.binaries.pictures.erotica.amateur 1]
May 20 17:47:57 newsfeed nnrpd[13340]: SERVER perl filter not defined
May 20 18:14:20 newsfeed overchan[1065]: input line too long, skipping
May 20 18:44:18 newsfeed nnrpd[15539]: SERVER perl filter not defined
May 20 19:33:01 newsfeed overchan[1065]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn2465425 97.3%284.8 MB 97.1%
innfeed 41564 1.6%4.9 MB 1.7%
innd 20886 0.8%3.3 MB 1.1%
nnrpd 4717 0.2%498.0 KB 0.2%
overchan 13 0.0%2.1 KB 0.0%
controlchan 5 0.0%0.3 KB 0.0%
TOTAL: 62532610 100.0%293.5 MB100.0%

History cache:

ReasonCount%Count
Do not exist 2429569 96.8%
Negative hits 70285 2.8%
Positive hits 10149 0.4%
Cache misses 92 0.0%
TOTAL: 4 2510095 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:01.436 0.0%172 0.000 8.349 47.556
article cleanup00:00:29.033 0.0%2438595 0.000 0.012 0.017
article logging00:01:28.669 0.1%2439374 0.000 0.036 0.172
article parse00:54:32.284 4.0%168643896 0.000 0.019 0.027
article write00:26:55.967 2.0%2430697 0.363 0.665 3.919
artlog/artcncl00:00:00.004 0.0%167 0.000 0.024 0.200
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:03:26.617 0.2%183922662 0.001 0.001 0.022
hisgrep/artcncl00:00:00.535 0.0%161 0.000 3.323 21.333
hishave/artcncl00:00:00.107 0.0%172 0.000 0.622 2.944
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:56:18.794 4.1%2516195 0.000 1.343 7.422
history sync00:00:00.012 0.0%278 0.000 0.043 1.500
history write02:28:15.743 10.7%2434854 0.273 3.654 19.808
hiswrite/artcncl00:00:00.085 0.0%11 0.000 7.727 9.444
idle12:11:03.312 53.0%129081694 0.236 0.340 9076.864
nntp read01:03:25.489 4.6%166685675 0.000 0.023 0.030
overview write00:00:12.397 0.0%2430697 0.000 0.005 0.007
perl filter04:23:34.063 19.1%2434854 5.347 6.495 10.000
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:03:53.557 0.3%4923695 0.000 0.047 0.067
TOTAL: 23:00:05.17922:33:38.104 98.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.316 0.0%132448 0.000 0.002 0.016
article prepare00:00:00.043 0.0%189384 0.000 0.000 0.021
article read00:01:09.159 0.1%47171 0.176 1.466 1543.000
backlog stats00:00:00.287 0.0%367688 0.000 0.001 0.006
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:02.927 0.0%185831 0.000 0.016 0.034
data write00:00:31.140 0.0%184751 0.016 0.169 0.424
idle23:01:57.081 99.8%367688 13.641 225.509 1414.845
status file00:00:11.785 0.0%15412 0.547 0.765 1.242
TOTAL: 23:04:09.43623:03:52.738100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:50:48.194 99.5%8459 0.000 360.349180559.400
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.399 0.0%19494 0.000 0.020 0.900
readart00:00:00.232 0.0%34 0.000 6.824 57.500
TOTAL: 00:51:02.01400:50:48.825 99.6%----

Control commands to INND:

CommandNumber
L 1
flush 26
flushlogs 2
go 2
logmode 2
mode 149
name 4
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 194

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 965 24533252446922 2887 3516 99%419:21:11
2news.nask.pl 3 14527 6889 3271 4367 47%22:43:45
3news.icm.edu.pl 44 345 23 249 73 6%16:30:53
4localhost 9 8 8 0 0 100%00:00:00
TOTAL: 4 1021 24682052453842 6407 7956 99%458:35:49
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl754.5 GB17.7 MB18.2 MB754.5 GB 99%322.9 KB
2news.nask.pl27.9 MB971.8 KB27.6 MB56.5 MB 49%5.1 KB
3news.icm.edu.pl481.6 KB47.8 KB1.3 MB1.8 MB 25%19.3 KB
4localhost11.3 KB0.0 KB0.0 KB11.3 KB100%1.4 KB
TOTAL: 4754.5 GB18.7 MB47.0 MB754.6 GB 99%321.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 20 04:15:02 - 04:59:59 22297 0.9% 8.267.7 GB 1.0%2981.62
May 20 05:00:00 - 05:59:59 44759 1.8% 12.4316.7 GB 2.2%4864.54
May 20 06:00:00 - 06:59:59 77932 3.2% 21.6526.3 GB 3.5%7647.62
May 20 07:00:00 - 07:59:59 62410 2.5% 17.3422.3 GB 3.0%6491.71
May 20 08:00:00 - 08:59:59 76440 3.1% 21.2326.4 GB 3.5%7675.57
May 20 09:00:00 - 09:59:59 88089 3.6% 24.4728.9 GB 3.8%8419.77
May 20 10:00:00 - 10:59:59 89874 3.7% 24.9628.8 GB 3.8%8377.44
May 20 11:00:00 - 11:59:59 105356 4.3% 29.2731.6 GB 4.2%9199.63
May 20 12:00:00 - 12:59:59 42179 1.7% 11.7212.3 GB 1.6%3581.50
May 20 13:00:00 - 13:59:59 66562 2.7% 18.4919.1 GB 2.5%5559.33
May 20 14:00:00 - 14:59:59 113774 4.6% 31.6032.8 GB 4.3%9551.38
May 20 15:00:00 - 15:59:59 115163 4.7% 31.9932.7 GB 4.3%9510.59
May 20 16:00:00 - 16:59:59 117757 4.8% 32.7133.9 GB 4.5%9879.44
May 20 17:00:00 - 17:59:59 120418 4.9% 33.4536.1 GB 4.8%10519.73
May 20 18:00:00 - 18:59:59 130904 5.3% 36.3640.7 GB 5.4%11847.80
May 20 19:00:00 - 19:59:59 140399 5.7% 39.0042.9 GB 5.7%12506.34
May 20 20:00:00 - 20:59:59 133784 5.4% 37.1640.4 GB 5.4%11776.77
May 20 21:00:00 - 21:59:59 133123 5.4% 36.9840.3 GB 5.3%11731.90
May 20 22:00:00 - 22:59:59 131870 5.4% 36.6340.3 GB 5.3%11745.53
May 20 23:00:00 - 23:59:59 127206 5.2% 35.3439.1 GB 5.2%11385.12
May 21 00:00:00 - 00:59:59 108464 4.4% 30.1333.9 GB 4.5%9864.09
May 21 01:00:00 - 01:59:59 139157 5.7% 38.6538.5 GB 5.1%11213.82
May 21 02:00:00 - 02:59:59 131269 5.3% 36.4639.0 GB 5.2%11353.11
May 21 03:00:00 - 03:59:59 109057 4.4% 30.2934.6 GB 4.6%10090.78
May 21 04:00:00 - 04:15:03 28275 1.2% 31.319.1 GB 1.2%10521.67
TOTAL: 24:00:01 2456518 100.0% 28.43754.2 GB 100.0%9152.70
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news.nask.pl 4474 34 0 386 3 0 0 0 4051
2newsfeed.pionier.net.pl 4191 87 0 3387 4 0 0 0 713
3news.icm.edu.pl 75 0 0 3 0 0 0 0 72
TOTAL: 3 8740 121 0 3776 7 0 0 0 4836

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 41
comp.mobile.android 33
it.comp.os.win.windows10 14
comp.sys.raspberry-pi 8
de.rec.sf.startrek 7
it.tlc.cellulari.android 5
it.comp.os.win.windows7 4
it.discussioni.energia 3
comp.mobile.ipad 2
uk.rec.cycling.moderated 2
de.comp.lang.vbclassic 1
alt.sex.telephones 1
TOTAL: 12 121

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

GroupsCount
pl.praca.oferowana 6
bit.listserv.catholic 1
TOTAL: 2 7

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 3731
EMP (phl) 119
Binary in non-binary group 75
Scoring filter (8) 73
Scoring filter (9) 21
Scoring filter (10) 10
Scoring filter (11) 8
HTML post 2
Scoring filter (17) 1
Scoring filter (12) 1
TOTAL: 10 4041

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 673
news.nask.pl 120
news.icm.edu.pl 2
TOTAL: 3 795
TOTAL: 1 795

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 66954 44605 15895 1269 0 531 66%23:06:02
2panorama 81933 473 55184 370 8 76336 0%23:17:14
3poznan 10228 33 6837 43 0 0 0%23:06:02
4nask 6341 6 4500 0 0 0 0%23:06:02
5okapi 0 0 0 0 0 1128 0%23:03:56
TOTAL: 5 165456 45117 82416 1682 8 77995 27%115:39:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm8.0 GB204.4 MB8.2 GB103.3 KB/s187.2 KB23:06:02
2panorama1.1 MB31.1 MB32.3 MB0.4 KB/s39.2 KB23:17:14
3poznan109.3 KB122.5 KB231.8 KB0.0 KB/s3.1 KB23:06:02
4nask11.2 KB0.0 KB11.2 KB0.0 KB/s1.9 KB23:06:02
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:03:56
TOTAL: 58.0 GB235.6 MB8.2 GB20.7 KB/s184.2 KB115:39:16
Outgoing feeds (innfeed) by Volume

Backlog files shrunk by innfeed:

ServerSize
panorama976.7 KB
TOTAL: 1976.7 KB

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 280 3024.8 KB 14 0 000:00:23
2pc-tom.man.lodz.pl 5 41023.5 KB 2 0 000:39:21
3isvcs01.man.lodz.pl 8 00.0 KB 0 8 000:00:01
TOTAL: 3 293 341.0 MB 16 8 000:51:01

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 667 341.0 MB 16 8 000:39:51
2*.astral.lodz.pl 2 00.0 KB 0 0 000:00:00
3unresolved 2 00.0 KB 0 0 000:11:09
TOTAL: 3 671 341.0 MB 16 8 000:51:01

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
pc-tom.man.lodz.pl 0.040 0.040 0.00000:39:21
185.48.194.49 0.356 0.548 0.00000:11:09
web.man.lodz.pl 1.136 2.928 0.00000:00:23
mon.man.lodz.pl 1.024 2.972 0.00000:00:03
david.man.lodz.pl 0.340 0.996 0.00000:00:01
isvcs01.man.lodz.pl 0.052 0.120 0.00000:00:01
cmpc67-011.astral.lodz.pl 0.004 0.004 0.00000:00:00
TOTAL: 7 2.952 7.608 0.00000:51:01

Curious NNRP server explorers [Top 20]:

SystemConn
mon.man.lodz.pl 281
david.man.lodz.pl 93
cmpc67-011.astral.lodz.pl 2
185.48.194.49 2
TOTAL: 4 378

NNRP client timeouts [Top 20]:

SystemConnPeer
pc-tom.man.lodz.pl 1 1
TOTAL: 1 1 1

Newsgroup request counts (by category):

CategoryCountPct
1pl 30 93.8%
2alt 2 6.2%
TOTAL: 2 32100.0%

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

NewsgroupCount
1pl.comp.pecet 24
2pl.comp.os.ms-windows.winnt 6
3alt.binaries.pictures.erotica.amateur 2
TOTAL: 3 32