Hi
Fotr the second night, one of my servers, a 5.6b2 on 2K Server, crashed,
just after expiring.
The last lines og dnews.log :
19 02:00:00 :info: suck: Scheduling EXPIRE hour=2 min=0
19 02:00:00 :info: group: Group Write d:\dnews/active.bak
19 02:00:00 :info: group: Group write done
19 02:00:00 :info: stats: Con 14, Unique 6, Feeds_in 2, Feeds_out 6,
Total_in 0k/s, Total_out 0k/s
19 02:00:00 :info: spawning external expire process
19 02:00:00 :info: CreateProcess(C:\WINNT\System32\dnews -expire)
19 02:00:01 :info: CreateProcess Finished
19 02:00:01 :info: external expire process successfully spawned (pid=2324)
19 02:00:03 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:00:06 :info: master: initiating expire command function (index_pause)
19 02:00:06 :info: expire: Told us to stop writing to the dbi.idx file
19 02:00:06 :info: db_clear_cache(index_pause)
19 02:00:06 :info: Group clear cache
19 02:00:06 :info: g_free (0) age=516 index_pause g_clear fr.usenet.divers
19 02:00:06 :info: g_free (1) age=469 index_pause g_clear
fr.usenet.forums.evolution
19 02:00:06 :info: g_free (2) age=4803 index_pause g_clear control.cancel
19 02:00:06 :info: g_free (3) age=464 index_pause g_clear
fr.reseaux.telecoms.adsl
19 02:00:06 :info: g_free (4) age=2321 index_pause g_clear
fr.reseaux.internet.hebergement
19 02:00:06 :info: g_free (5) age=465 index_pause g_clear
fr.lettres.langue.francaise
19 02:00:06 :info: g_free (6) age=2945 index_pause g_clear
fr.comp.os.ms-windows.winnt
19 02:00:06 :info: g_free (7) age=513 index_pause g_clear
fr.usenet.logiciels
19 02:00:06 :info: g_free (8) age=2065 index_pause g_clear
fr.education.divers
19 02:00:06 :info: g_free (9) age=1699 index_pause g_clear
fr.comp.reseaux.ip
19 02:00:06 :info: g_free (10) age=514 index_pause g_clear fr.comp.mail
19 02:00:06 :info: g_free (11) age=512 index_pause g_clear
fr.misc.engueulades
19 02:00:06 :info: g_free (12) age=506 index_pause g_clear hamster.test
19 02:00:06 :info: g_free (13) age=462 index_pause g_clear
fr.comp.graphisme.multimedia
19 02:00:06 :info: Group clear cache - done
19 02:00:08 :info: master: initiating expire command function
(reuse_buckets)
19 02:00:09 :info: master: initiating expire command function
(index_unpause)
19 02:00:09 :info: expire: Told us to continue writing to the dbi.idx file
again
19 02:00:09 :info: db_clear_cache(index_unpause)
19 02:00:09 :info: Group clear cache
19 02:00:09 :info: Group clear cache - done
19 02:00:09 :info: Sending buffered data to index, 0 records
19 02:00:13 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:00:14 :info: pool: No feed file {d:\dnews\work/ns2_freenix_org.feed}
, nothing to send, try again 180 seconds
19 02:00:14 :info: pool: No feed file
{d:\dnews\work/mendel_ac-versailles_fr.feed} , nothing to send, try again
180 seconds
19 02:00:14 :info: chan: chan_open {ns2.freenix.org}
19 02:00:14 :info: max_mb = 0, max_con=0, max_users=0
19 02:00:14 :info: chan: Currently 15 users from a_* ns2.freenix.org
19 02:00:14 :info: nntp: Setting TCP_NODELAY for socket [6]
19 02:00:14 :info: chan: chan_open {mendel.ac-versailles.fr}
19 02:00:14 :info: max_mb = 0, max_con=0, max_users=0
19 02:00:14 :info: chan: Currently 16 users from a_*
mendel.ac-versailles.fr
19 02:00:14 :info: nntp: Setting TCP_NODELAY for socket [8]
19 02:00:14 :warn: c=12 mendel.ac-versailles.fr Live no response for 505
seconds, closing 12
19 02:00:14 :info: Close later called and now closing chan [12]
19 02:00:14 :info: chan: Currently 15 users from a_*
19 02:00:15 6:info: live: (c=6 ns2.freenix.org) streaming 203 StreamOK.
19 02:00:15 8:info: live: (c=8 mendel.ac-versailles.fr) streaming 203
StreamOK
19 02:00:23 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:00:24 :info: reopen append (1) d:\dnews\spool/db_128_1.itm
19 02:00:27 :info: Rename d:\dnews\work/news_ac-versailles_fr.feed
d:\dnews\work/news_ac-versailles_fr.send
19 02:00:27 :info: Rename d:\dnews\work/ns0_xavhome_fr_eu_org.feed
d:\dnews\work/ns0_xavhome_fr_eu_org.send
19 02:00:33 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:00:43 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:00:45 :info: db_pile_status
19 02:00:45 :info: db_pile_status_done
19 02:00:45 :info: dnews_status 1
19 02:00:45 :info: dnews_status 2
19 02:00:45 :info: dnews_status 3
19 02:00:46 :info: Feed out summary gw.glou.org Posts=4842 Rejects=41
Dup=8663 Queued=351 Requed=5420 File=0M Posts=0.07/s Total=0.29/s
Total=8667k Type=LiveStrm
19 02:00:46 :info: Feed out summary mendel.ac-versailles.fr Posts=38
Rejects=1 Dup=9991 Queued=5 Requed=2 File=0M Posts=0.00/s Total=0.15/s
Total=64k Type=LiveStrm
19 02:00:49 :info: pool: No feed file
{d:\dnews\work/mazarin_ac-versailles_fr.feed} , nothing to send, try again
180 seconds
19 02:00:52 3:info: nntp: Socket closed by remote end [3] 400
richelieu.ac-versailles.fr
19 02:00:52 3:info: chan: Currently 14 users from a_*
19 02:00:53 1:info: tcp receive buffer was 8192
19 02:00:53 1:info: receive buffer is now 65535
19 02:00:53 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:00:53 1:info: reopen append (1) d:\dnews\spool/db_128_1.itm
19 02:00:53 1:info: thin: spawning threaded input
19 02:00:53 1:info: thin_start 641 0 chan 1
19 02:00:53 1:info: Current threads 3, used 56 total
19 02:00:53 :info: th=148 Input thread running 1 chan=1
19 02:00:55 :info: pool: No feed file {d:\dnews\work/gw_glou_org.feed} ,
nothing to send, try again 180 seconds
19 02:01:00 :info: Rename d:\dnews\work/news_ac-versailles_fr.feed
d:\dnews\work/news_ac-versailles_fr.send
19 02:01:00 :info: Rename d:\dnews\work/ns0_xavhome_fr_eu_org.feed
d:\dnews\work/ns0_xavhome_fr_eu_org.send
19 02:01:02 3:info: nntp: Setting TCP_NODELAY for socket [3]
19 02:01:02 3:info: max_mb = 0, max_con=0, max_users=0
19 02:01:02 3:info: chan: Currently 15 users from a_*
richelieu.ac-versailles.fr
19 02:01:02 3:warn: nntp: connection [3:476] From richelieu.ac-versailles.fr
195.221.97.9
19 02:01:02 3:info: nntp: Setting TCP_NODELAY for socket [3]
19 02:01:02 3:info: cmd: Do cmdx[3:richelieu.ac-versailles.fr] {MODE STREAM}
{mode}
19 02:01:03 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:01:11 :info: Rename d:\dnews\work/news_ac-versailles_fr.feed
d:\dnews\work/news_ac-versailles_fr.send
19 02:01:11 :info: Rename d:\dnews\work/ns0_xavhome_fr_eu_org.feed
d:\dnews\work/ns0_xavhome_fr_eu_org.send
19 02:01:13 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:01:24 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:01:33 :info: Rename d:\dnews\work/news_ac-versailles_fr.feed
d:\dnews\work/news_ac-versailles_fr.send
19 02:01:33 :info: Rename d:\dnews\work/ns0_xavhome_fr_eu_org.feed
d:\dnews\work/ns0_xavhome_fr_eu_org.send
19 02:01:34 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:01:44 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:01:46 :info: db_pile_status
19 02:01:46 :info: db_pile_status_done
19 02:01:46 :info: dnews_status 1
19 02:01:46 :info: dnews_status 2
19 02:01:46 :info: dnews_status 3
19 02:01:47 :info: Feed in summary ns2.freenix.org Posts=9839 Rejects=88
Dup=3196 0.15/s 0.29k/s 18640k
19 02:01:47 :info: Feed out summary ns2.freenix.org Posts=44 Rejects=0
Dup=3459 Queued=131 Requed=332 File=0M Posts=0.00/s Total=0.06/s Total=72k
Type=LiveStrm
19 02:01:47 :info: Feed out summary gw.glou.org Posts=4845 Rejects=41
Dup=8664 Queued=351 Requed=5421 File=0M Posts=0.07/s Total=0.29/s
Total=8677k Type=LiveStrm
19 02:01:47 :info: Feed out summary mendel.ac-versailles.fr Posts=38
Rejects=1 Dup=9997 Queued=5 Requed=2 File=0M Posts=0.00/s Total=0.15/s
Total=64k Type=LiveStrm
19 02:01:54 :info: pool: No feed file {d:\dnews\work/ns2_freenix_org.feed}
, nothing to send, try again 180 seconds
19 02:01:54 :info: pool: No feed file
{d:\dnews\work/mendel_ac-versailles_fr.feed} , nothing to send, try again
180 seconds
19 02:01:54 :info: tomail: rename failed d:\dnews\work/tomail.send No such
file or directory
19 02:01:55 :info: chan: chan_open {mendel.ac-versailles.fr}
19 02:01:55 :info: max_mb = 0, max_con=0, max_users=0
19 02:01:55 :info: chan: Currently 16 users from a_*
mendel.ac-versailles.fr
19 02:01:55 :info: nntp: Setting TCP_NODELAY for socket [12]
19 02:01:58 :info: reopen append (1) d:\dnews\spool/db_128_1.itm
19 02:02:03 :info: master: initiating expire command function
(history_flush_pause)
19 02:02:03 :info: expire: external expire asked master to close history
file
19 02:02:05 :info: master: continuing after history_flush_unpause command -
rereading history
19 02:02:05 :info: hist: Freeing old history indexes
19 02:02:09 :info: hist: Freeing old history indexes, completed
19 02:02:09 :info: hist: Block usedmatch size 10000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 20000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 40000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 80000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 160000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 300000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 300000, used 0==0
19 02:02:09 :info: hist: Block usedmatch size 300000, used 0==0
19 03:42:21 :error: Cannot get mutex, fatal problem, dieing MAIN
19 03:42:22 :error: Cannot get mutex, fatal problem, dieing MAIN
19 03:42:36 :error: Cannot get mutex, fatal problem, dieing MAIN
My expire.conf file :
remember 3
pile 1 groups * use 85%
pile 2 groups *binaries*,*pictures*,*warez* use 1%
pile 3 groups ac-versailles.* use 10%
pile 4 groups control* use 2% days 1
And some lines of a status :
Spawned Expire started at Thu Sep 19 02:00:05 2002 Working, start 79% end
79%
Processed 1252180 removed 26336 deleting 293/0/284 speed 10516.6 pass 0
Pile hist_mins 1, xover_mins 0, resort_mins 0, nold 293, fillin 0, copy 0
Nothing is being sucked at present.
DB used 2326.52MB, Slack 128.39MB 5.52%, DBI Misses 0/0, blocks 0=0k h=99
History: disk 110396k memory 19625k/24160k, Str 277k/754k/1100k Malloc
38638k/38746k/10340k
Used 2632MB=79%, Free work 408Mb, hist 408Mb, spool 408Mb, log 408Mb
Groups Xover 215 306MB, Cached 126 of 443, GID 3066, Uptime 0 day(s) 0
hour(s), XOver Cache 18.18%
Imposible to restart the server or the Dnews service. I oughted to restart
the machine :(
What happened? Where did I make a fault in my expire rules ?
Thanks and regards.
--
Michel Guillou
