2016-03-04 18:17 GMT+01:00 Kane Kim <[email protected]>: > hey David, did you have a chance to look at it? Rainer can you please help > here too?
I have already assigned a release goal to the github tracker. It's probably hard to track down and I am right now busy crafting the release (which means it can't go into 8.17 anyways). I am sure it's not a new regression, because that code area hasn't been touched at least for 6 month, I guess even longer. When I begin to really work on it, I need to find a way to make it fail with an action that I can test easily, like omfwd (I don't have kafka know how and so I do not know how to craft a consistent test case for it). If that fails, I'll write a testing plugin just for that purpose (or extend omtesting, to be seen). Just FYI, Rainer > > On Wed, Mar 2, 2016 at 10:03 PM, Kane Kim <[email protected]> wrote: > >> This is with latest master >> On Mar 2, 2016 9:27 PM, "David Lang" <[email protected]> wrote: >> >>> is this with 8.16 or with the git tree? (what will be 8.17 next week) >>> >>> David Lang >>> >>> On Wed, 2 Mar 2016, Kane Kim wrote: >>> >>> Date: Wed, 2 Mar 2016 15:08:39 -0800 >>>> From: Kane Kim <[email protected]> >>>> Reply-To: rsyslog-users <[email protected]> >>>> To: rsyslog-users <[email protected]> >>>> Subject: Re: [rsyslog] retry if output module returns RS_RET_SUSPENDED >>>> >>>> Hey David, here is the full log, it starts at idle state, then I send one >>>> line and shutdown rsyslog. commitTransaction never returns at this point, >>>> queue is deleted from disk and rsyslog starts with empty queue. >>>> commitTransaction has this: >>>> >>>> dbgprintf("omsgkafka: committransaction called\n"); >>>> for(;;) { >>>> srSleep(1500, 0); >>>> } >>>> >>>> iRet = RS_RET_SUSPENDED; >>>> >>>> dbgprintf("omsgkafka: committransaction end\n"); >>>> >>>> >>>> 9774.080804647:imuxsock.c : Message from UNIX socket: #6 >>>> 9774.080828380:imuxsock.c : main Q: qqueueAdd: entry added, size now >>>> log 1, phys 1 entries >>>> 9774.080835155:imuxsock.c : main Q: EnqueueMsg advised worker start >>>> 9774.080839004:imuxsock.c : --------imuxsock calling select, active >>>> file descriptors (max 6): 6 >>>> 9774.080850186:main Q:Reg/w0 : wti 0x1333800: worker awoke from idle >>>> processing >>>> 9774.080854144:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 >>>> objects >>>> and enqueued 0 objects >>>> 9774.080857193:main Q:Reg/w0 : doDeleteBatch: delete batch from store, >>>> new >>>> sizes: log 1, phys 1 >>>> 9774.080860918:main Q:Reg/w0 : processBATCH: batch of 1 elements must be >>>> processed >>>> 9774.080863557:main Q:Reg/w0 : processBATCH: next msg 0: <190>Mar 2 >>>> 23:02:54 logger: test1 >>>> 9774.080866845:main Q:Reg/w0 : PRIFILT 'local7.*' >>>> 9774.080872187:main Q:Reg/w0 : pmask: X X X X X X X X X >>>> X X >>>> X X X X X X X X X X X X FF X X >>>> 9774.080923618:main Q:Reg/w0 : PRIFILT condition result is 1 >>>> 9774.080926256:main Q:Reg/w0 : ACTION 0 >>>> [omsgkafka:action(type="omsgkafka" ...)] >>>> 9774.080932370:main Q:Reg/w0 : executing action 0 >>>> 9774.080935598:main Q:Reg/w0 : action 'kafka_action': called, logging to >>>> omsgkafka (susp 0/0, direct q 0) >>>> 9774.080955281:main Q:Reg/w0 : strm 0x13272d0: file >>>> -1(prism_kafka_queue) >>>> flush, buflen 405 >>>> 9774.080958942:main Q:Reg/w0 : strmPhysWrite, stream 0x13272d0, len 405 >>>> 9774.081005185:main Q:Reg/w0 : file >>>> '/var/spool/rsyslog/prism_kafka_queue.00000001' opened as #7 with mode >>>> 384 >>>> 9774.081014114:main Q:Reg/w0 : strm 0x13272d0: opened file >>>> '/var/spool/rsyslog/prism_kafka_queue.00000001' for WRITE as 7 >>>> 9774.081026793:main Q:Reg/w0 : strm 0x13272d0: file 7 write wrote 405 >>>> bytes >>>> 9774.081030432:main Q:Reg/w0 : kafka_action queue: write wrote 405 >>>> octets >>>> to disk, queue disk size now 405 octets, EnqOnly:0 >>>> 9774.081033284:main Q:Reg/w0 : kafka_action queue: qqueueAdd: entry >>>> added, >>>> size now log 1, phys 1 entries >>>> 9774.081036576:main Q:Reg/w0 : kafka_action queue:Reg: high activity - >>>> starting 1 additional worker thread(s). >>>> 9774.081057367:main Q:Reg/w0 : kafka_action queue:Reg: started with >>>> state >>>> 0, num workers now 1 >>>> 9774.081061235:main Q:Reg/w0 : kafka_action queue: EnqueueMsg advised >>>> worker start >>>> 9774.081119073:main Q:Reg/w0 : action 'kafka_action': set suspended >>>> state >>>> to 0 >>>> 9774.081124496:main Q:Reg/w0 : PRIFILT 'local0.*' >>>> 9774.081130002:main Q:Reg/w0 : pmask: X X X X X X X X X >>>> X X >>>> X X X X X FF X X X X X X X X X >>>> 9774.081179409:main Q:Reg/w0 : PRIFILT condition result is 0 >>>> 9774.081183488:main Q:Reg/w0 : PRIFILT '*.emerg' >>>> 9774.081192108:main Q:Reg/w0 : pmask: 1 1 1 1 1 1 1 1 1 >>>> 1 1 >>>> 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 >>>> 9774.081236853:main Q:Reg/w0 : PRIFILT condition result is 0 >>>> 9774.081239405:main Q:Reg/w0 : END batch execution phase, entering to >>>> commit phase [processed 1 of 1 messages] >>>> 9774.081242411:main Q:Reg/w0 : processBATCH: batch of 1 elements has >>>> been >>>> processed >>>> 9774.081245359:main Q:Reg/w0 : regular consumer finished, iret=0, szlog >>>> 0 >>>> sz phys 1 >>>> 9774.081249143:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 1 >>>> objects >>>> and enqueued 0 objects >>>> 9774.081251772:main Q:Reg/w0 : doDeleteBatch: delete batch from store, >>>> new >>>> sizes: log 0, phys 0 >>>> 9774.081254445:main Q:Reg/w0 : regular consumer finished, iret=4, szlog >>>> 0 >>>> sz phys 0 >>>> 9774.081257074:main Q:Reg/w0 : main Q:Reg/w0: worker IDLE, waiting for >>>> work. >>>> 9774.082767917:7f08827fc700: thread created, tid 20310, name >>>> 'rs:kafka_action queue:R' >>>> 9774.083158670:kafka_action queue:Reg/w0: wti 0x1327ab0: worker starting >>>> 9774.083452008:kafka_action queue:Reg/w0: DeleteProcessedBatch: we >>>> deleted >>>> 0 objects and enqueued 0 objects >>>> 9774.083764135:kafka_action queue:Reg/w0: doDeleteBatch: delete batch >>>> from >>>> store, new sizes: log 1, phys 1 >>>> 9774.083928382:kafka_action queue:Reg/w0: file >>>> '/var/spool/rsyslog/prism_kafka_queue.00000001' opened as #8 with mode >>>> 384 >>>> 9774.084086546:kafka_action queue:Reg/w0: strm 0x1327570: opened file >>>> '/var/spool/rsyslog/prism_kafka_queue.00000001' for READ as 8 >>>> 9774.084098247:kafka_action queue:Reg/w0: strm 0x1327570: file 8 read 405 >>>> bytes >>>> 9774.084121083:kafka_action queue:Reg/w0: string to match for regex is: >>>> test1 >>>> 9774.084129965:kafka_action queue:Reg/w0: source file msg.c requested >>>> reference for module 'lmregexp', reference count now 2 >>>> 9774.084146981:kafka_action queue:Reg/w0: regexec return is 1 >>>> 9774.084151588:kafka_action queue:Reg/w0: regex: end search, found 0 >>>> 9774.084298127:kafka_action queue:Reg/w0: action 'kafka_action': is >>>> transactional - executing in commit phase >>>> 9774.084304450:kafka_action queue:Reg/w0: wti 0x1327ab0: we need to >>>> create >>>> a new action worker instance for action 0 >>>> 9774.084316690:kafka_action queue:Reg/w0: wti 0x1327ab0: created action >>>> worker instance 1 for action 0 >>>> 9774.084322447:kafka_action queue:Reg/w0: omsgkafka: beginTransaction >>>> called >>>> 9774.084327347:kafka_action queue:Reg/w0: Action 0 transitioned to state: >>>> itx >>>> 9774.084332329:kafka_action queue:Reg/w0: doTransaction: have >>>> commitTransaction IF, using that, pWrkrInfo 0x1327b60 >>>> 9774.084337162:kafka_action queue:Reg/w0: entering >>>> actionCallCommitTransaction(), state: itx, actionNbr 0, nMsgs 1 >>>> 9774.084341794:kafka_action queue:Reg/w0: omsgkafka: committransaction >>>> called >>>> ^C9777.756010044:main thread : DoDie called. >>>> DoDie called. >>>> 9777.757077361:main thread : exiting on signal 2 >>>> 9777.757445698:main thread : Terminating input threads... >>>> 9777.757936794:main thread : request term via SIGTTIN for input thread >>>> 'imuxsock' 0x837fe700 >>>> 9777.758371952:imuxsock.c : thrdStarter: usrThrdMain imuxsock - >>>> 0x7f08837fe700 returned with iRet 0, exiting now. >>>> 9777.758931583:imuxsock.c : destructor for debug call stack >>>> 0x7f08740008c0 called >>>> 9777.759216811:main thread : input thread term: thread imuxsock >>>> returned >>>> normally and is terminated >>>> 9777.759495668:main thread : non-cancel input thread termination >>>> succeeded for thread imuxsock 0x837fe700 >>>> 9777.759850405:main thread : imuxsock: unlinking unix socket file[0] >>>> /dev/log >>>> 9777.760098055:main thread : main Q: qqueueAdd: entry added, size now >>>> log 1, phys 1 entries >>>> 9777.760440595:main thread : main Q: EnqueueMsg advised worker start >>>> 9777.760618052:main Q:Reg/w0 : wti 0x1333800: worker awoke from idle >>>> processing >>>> 9777.760627213:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 >>>> objects >>>> and enqueued 0 objects >>>> 9777.760633556:main Q:Reg/w0 : doDeleteBatch: delete batch from store, >>>> new >>>> sizes: log 1, phys 1 >>>> 9777.760640207:main Q:Reg/w0 : processBATCH: batch of 1 elements must be >>>> processed >>>> 9777.760646078:main Q:Reg/w0 : processBATCH: next msg 0: [origin >>>> software="rsyslogd" swVersion="8.17.0.master" x-pid="20303" x-info=" >>>> http://www.rsyslog.com"] exiting on signal 2. >>>> 9777.760652377:main Q:Reg/w0 : PRIFILT 'local7.*' >>>> 9777.760663339:main Q:Reg/w0 : pmask: X X X X X X X X X >>>> X X >>>> X X X X X X X X X X X X FF X X >>>> 9777.760979712:main Q:Reg/w0 : PRIFILT condition result is 0 >>>> 9777.760985069:main Q:Reg/w0 : PRIFILT 'local0.*' >>>> 9777.760995436:main Q:Reg/w0 : pmask: X X X X X X X X X >>>> X X >>>> X X X X X FF X X X X X X X X X >>>> 9777.761073220:main Q:Reg/w0 : PRIFILT condition result is 0 >>>> 9777.761077680:main Q:Reg/w0 : PRIFILT '*.emerg' >>>> 9777.761087341:main Q:Reg/w0 : pmask: 1 1 1 1 1 1 1 1 1 >>>> 1 1 >>>> 1 1 1 1 1 1 1 1 1 1 1 1 1 1 1 >>>> 9777.761485735:main Q:Reg/w0 : PRIFILT condition result is 0 >>>> 9777.761494678:main Q:Reg/w0 : END batch execution phase, entering to >>>> commit phase [processed 1 of 1 messages] >>>> 9777.761504212:main Q:Reg/w0 : processBATCH: batch of 1 elements has >>>> been >>>> processed >>>> 9777.761513699:main Q:Reg/w0 : regular consumer finished, iret=0, szlog >>>> 0 >>>> sz phys 1 >>>> 9777.761532517:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 1 >>>> objects >>>> and enqueued 0 objects >>>> 9777.761542961:main Q:Reg/w0 : doDeleteBatch: delete batch from store, >>>> new >>>> sizes: log 0, phys 0 >>>> 9777.761552408:main Q:Reg/w0 : regular consumer finished, iret=4, szlog >>>> 0 >>>> sz phys 0 >>>> 9777.761561267:main Q:Reg/w0 : main Q:Reg/w0: worker IDLE, waiting for >>>> work. >>>> 9777.761575630:main thread : Terminating main queue... >>>> 9777.761586414:main thread : main Q: initiating worker thread shutdown >>>> sequence >>>> 9777.761595699:main thread : main Q: trying shutdown of regular >>>> workers >>>> 9777.761612563:main thread : sent SIGTTIN to worker thread 0x82ffd700 >>>> 9777.762033112:main thread : main Q:Reg: waiting 1500ms on worker >>>> thread >>>> termination, 1 still running >>>> 9777.762471004:main Q:Reg/w0 : wti 0x1333800: worker awoke from idle >>>> processing >>>> 9777.762479524:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 >>>> objects >>>> and enqueued 0 objects >>>> 9777.762485366:main Q:Reg/w0 : doDeleteBatch: delete batch from store, >>>> new >>>> sizes: log 0, phys 0 >>>> 9777.762490722:main Q:Reg/w0 : regular consumer finished, iret=4, szlog >>>> 0 >>>> sz phys 0 >>>> 9777.762495761:main Q:Reg/w0 : wti 0x1333800: terminating worker >>>> terminateRet=5, bInactivityTOOccured=0 >>>> 9777.762501491:main Q:Reg/w0 : DDDD: wti 0x1333800: worker cleanup >>>> action >>>> instances >>>> 9777.762507021:main Q:Reg/w0 : wti 0x1333800, action 0, ptr (nil) >>>> 9777.762511754:main Q:Reg/w0 : wti 0x1333800, action 1, ptr (nil) >>>> 9777.762516385:main Q:Reg/w0 : wti 0x1333800, action 2, ptr (nil) >>>> 9777.762521291:main Q:Reg/w0 : wti 0x1333800: worker exiting >>>> 9777.762526603:main Q:Reg/w0 : main Q:Reg: Worker thread 1333800, >>>> terminated, num workers now 0 >>>> 9777.762543503:main Q:Reg/w0 : destructor for debug call stack >>>> 0x7f087c0008c0 called >>>> 9777.762586045:main thread : main Q: regular queue workers shut down. >>>> 9777.762595242:main thread : main Q: checking to see if we need to >>>> cancel any worker threads of the primary queue >>>> 9777.762600740:main thread : main Q: worker threads terminated, >>>> remaining queue size log 0, phys 0. >>>> 9777.762612320:main thread : main Q: queue (type 0) will lose 0 >>>> messages, destroying... >>>> 9777.762626917:main thread : Terminating outputs... >>>> 9777.762635743:main thread : destructing ruleset 0x131b150, name >>>> 0x131b190 >>>> 9777.762642271:main thread : kafka_action queue: initiating worker >>>> thread shutdown sequence >>>> 9777.762647400:main thread : kafka_action queue: trying shutdown of >>>> regular workers >>>> 9777.762656067:main thread : sent SIGTTIN to worker thread 0x827fc700 >>>> 9777.762669071:main thread : kafka_action queue:Reg: waiting 0ms on >>>> worker thread termination, 1 still running >>>> 9777.762947979:main thread : kafka_action queue:Reg: timeout waiting >>>> on >>>> worker thread termination >>>> 9777.762972263:main thread : sent SIGTTIN to worker thread 0x827fc700 >>>> 9777.763088721:main thread : kafka_action queue: regular shutdown >>>> timed >>>> out on primary queue (this is OK) >>>> 9777.763185618:main thread : kafka_action queue: trying to shutdown >>>> workers within Action Timeoutsetting EnqOnly mode >>>> 9777.763196692:main thread : kafka_action queue: trying immediate >>>> shutdown of regular workers (if any) >>>> 9777.763214555:main thread : sent SIGTTIN to worker thread 0x827fc700 >>>> 9777.763226359:main thread : kafka_action queue:Reg: waiting 1000ms on >>>> worker thread termination, 1 still running >>>> 9778.763307260:main thread : kafka_action queue:Reg: timeout waiting >>>> on >>>> worker thread termination >>>> 9778.763833144:main thread : sent SIGTTIN to worker thread 0x827fc700 >>>> 9778.764176150:main thread : kafka_action queue: immediate shutdown >>>> timed out on primary queue (this is acceptable and triggers cancellation) >>>> 9778.764569596:main thread : kafka_action queue: checking to see if we >>>> need to cancel any worker threads of the primary queue >>>> 9778.765029132:main thread : sent SIGTTIN to worker thread 0x827fc700, >>>> giving it a chance to terminate >>>> 9778.775504248:main thread : cooperative worker termination failed, >>>> using cancellation... >>>> 9778.775932612:main thread : wti 0x1327ab0: canceling worker thread >>>> 9778.776268161:kafka_action queue:Reg/w0: kafka_action queue:Reg/w0: >>>> cancelation cleanup handler called. >>>> 9778.776647913:kafka_action queue:Reg/w0: DeleteProcessedBatch: we >>>> deleted >>>> 1 objects and enqueued 0 objects >>>> 9778.776910344:kafka_action queue:Reg/w0: doDeleteBatch: delete batch >>>> from >>>> store, new sizes: log 0, phys 0 >>>> 9778.777190970:kafka_action queue:Reg/w0: kafka_action queue:Reg/w0: done >>>> cancelation cleanup handler. >>>> 9778.777532500:kafka_action queue:Reg/w0: kafka_action queue:Reg: Worker >>>> thread 1327ab0 requested to be cancelled. >>>> 9778.777748314:kafka_action queue:Reg/w0: kafka_action queue:Reg: Worker >>>> thread 1327ab0, terminated, num workers now 0 >>>> 9778.777955651:kafka_action queue:Reg/w0: destructor for debug call stack >>>> 0x7f08780008c0 called >>>> 9778.778248027:main thread : kafka_action queue: worker threads >>>> terminated, remaining queue size log 0, phys 0. >>>> 9778.778262197:main thread : kafka_action queue: persisting queue to >>>> disk, 0 entries... >>>> 9778.778269955:main thread : strm 0x13272d0: file 7(prism_kafka_queue) >>>> closing >>>> 9778.778284655:main thread : strm 0x13272d0: file 7(prism_kafka_queue) >>>> flush, buflen 0 (no need to flush) >>>> 9778.778298933:main thread : strm 0x1327570: file 8(prism_kafka_queue) >>>> closing >>>> 9778.778435180:main thread : strm 0x1327810: file >>>> -1(prism_kafka_queue) >>>> closing >>>> 9778.778442428:main thread : strmCloseFile: deleting >>>> '/var/spool/rsyslog/prism_kafka_queue.00000001' >>>> 9778.778499467:main thread : omsgkafka: freeInstance called >>>> 9778.778513906:main thread : all primary multi-thread sources have >>>> been >>>> terminated - now doing aux cleanup... >>>> 9778.778519118:main thread : destructing current config... >>>> 9778.778524283:main thread : calling freeCnf(0x13191e0) for module >>>> 'builtin:omfile' >>>> 9778.778529272:main thread : calling freeCnf(0x13193a0) for module >>>> 'builtin:ompipe' >>>> 9778.778534707:main thread : calling freeCnf(0x131a3a0) for module >>>> 'builtin:omfwd' >>>> 9778.778540278:main thread : calling freeCnf(0x1329b00) for module >>>> 'imuxsock' >>>> 9778.778545633:main thread : calling freeCnf(0x132ac70) for module >>>> 'imklog' >>>> 9778.778729757:main thread : destructing parser 'rsyslog.rfc5424' >>>> 9778.778736340:main thread : destructing parser 'rsyslog.rfc3164' >>>> 9778.778741419:main thread : pmrfc3164: free parser instance 0x131a8b0 >>>> 9778.778810084:main thread : file rsyslogd.c released module 'lmnet', >>>> reference count now 2 >>>> 9778.778970064:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 2 >>>> 9778.778976042:main thread : Unloading module builtin:omfile >>>> 9778.778981773:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 2 >>>> 9778.778986410:main thread : Unloading module builtin:ompipe >>>> 9778.778991284:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 2 >>>> 9778.778996016:main thread : Unloading module builtin-shell >>>> 9778.779000666:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 2 >>>> 9778.779005245:main thread : Unloading module builtin:omdiscard >>>> 9778.779010169:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 2 >>>> 9778.779014765:main thread : Unloading module builtin:omfwd >>>> 9778.779020701:main thread : file omfwd.c released module 'lmnet', >>>> reference count now 1 >>>> 9778.779025970:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779030535:main thread : Unloading module builtin:omusrmsg >>>> 9778.779035196:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779039750:main thread : Unloading module builtin:pmrfc5424 >>>> 9778.779044623:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779049166:main thread : Unloading module builtin:pmrfc3164 >>>> 9778.779053994:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779058499:main thread : Unloading module builtin:smfile >>>> 9778.779063115:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779067650:main thread : Unloading module builtin:smtradfile >>>> 9778.779072340:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779080829:main thread : Unloading module builtin:smfwd >>>> 9778.779085950:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779090579:main thread : Unloading module builtin:smtradfwd >>>> 9778.779095331:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779099910:main thread : Unloading module imuxsock >>>> 9778.779127532:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779134318:main thread : Unloading module imklog >>>> 9778.779149232:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.779452603:main thread : module lmregexp NOT unloaded because it >>>> still has a refcount of 2 >>>> 9778.779458039:main thread : Unloading module omsgkafka >>>> 9778.779853849:main thread : module lmnet NOT unloaded because it >>>> still >>>> has a refcount of 1 >>>> 9778.780141515:main thread : module lmregexp NOT unloaded because it >>>> still has a refcount of 2 >>>> 9778.780157201:main thread : file conf.c released module 'lmnet', >>>> reference count now 0 >>>> 9778.780297378:main thread : module 'lmnet' has zero reference count, >>>> unloading... >>>> 9778.780438764:main thread : Unloading module lmnet >>>> 9778.780664790:main thread : rsyslog runtime de-initialized, current >>>> users 0 >>>> 9778.780921611:main thread : Clean shutdown completed, bye >>>> >>>> >>>> >>>> On Thu, Feb 25, 2016 at 4:58 PM, David Lang <[email protected]> wrote: >>>> >>>> On Thu, 25 Feb 2016, Kane Kim wrote: >>>>> >>>>> Btw, I've tried it with latest master - I have following: >>>>> >>>>>> >>>>>> BEGINcommitTransaction >>>>>> CODESTARTcommitTransaction >>>>>> dbgprintf("omsgkafka: committransaction called\n"); >>>>>> srSleep(1500, 0); >>>>>> >>>>>> ..... >>>>>> >>>>>> Then I send 3 log lines and restart rsyslog 3 times in a row (while >>>>>> commitTransaction is still sleeping). After 3rd restart queue file >>>>>> disappears from disk. >>>>>> >>>>>> >>>>>> prior to this, what does the debug log say is happening to the log >>>>> message? it should show the message being read from the queue and then >>>>> ... >>>>> >>>>> >>>>> David Lang >>>>> _______________________________________________ >>>>> rsyslog mailing list >>>>> http://lists.adiscon.net/mailman/listinfo/rsyslog >>>>> http://www.rsyslog.com/professional-services/ >>>>> What's up with rsyslog? Follow https://twitter.com/rgerhards >>>>> NOTE WELL: This is a PUBLIC mailing list, posts are ARCHIVED by a myriad >>>>> of sites beyond our control. PLEASE UNSUBSCRIBE and DO NOT POST if you >>>>> DON'T LIKE THAT. >>>>> >>>>> _______________________________________________ >>>> rsyslog mailing list >>>> http://lists.adiscon.net/mailman/listinfo/rsyslog >>>> http://www.rsyslog.com/professional-services/ >>>> What's up with rsyslog? Follow https://twitter.com/rgerhards >>>> NOTE WELL: This is a PUBLIC mailing list, posts are ARCHIVED by a myriad >>>> of sites beyond our control. PLEASE UNSUBSCRIBE and DO NOT POST if you >>>> DON'T LIKE THAT. >>>> >>>> _______________________________________________ >>> rsyslog mailing list >>> http://lists.adiscon.net/mailman/listinfo/rsyslog >>> http://www.rsyslog.com/professional-services/ >>> What's up with rsyslog? Follow https://twitter.com/rgerhards >>> NOTE WELL: This is a PUBLIC mailing list, posts are ARCHIVED by a myriad >>> of sites beyond our control. PLEASE UNSUBSCRIBE and DO NOT POST if you >>> DON'T LIKE THAT. >>> >> > _______________________________________________ > rsyslog mailing list > http://lists.adiscon.net/mailman/listinfo/rsyslog > http://www.rsyslog.com/professional-services/ > What's up with rsyslog? Follow https://twitter.com/rgerhards > NOTE WELL: This is a PUBLIC mailing list, posts are ARCHIVED by a myriad of > sites beyond our control. PLEASE UNSUBSCRIBE and DO NOT POST if you DON'T > LIKE THAT. _______________________________________________ rsyslog mailing list http://lists.adiscon.net/mailman/listinfo/rsyslog http://www.rsyslog.com/professional-services/ What's up with rsyslog? Follow https://twitter.com/rgerhards NOTE WELL: This is a PUBLIC mailing list, posts are ARCHIVED by a myriad of sites beyond our control. PLEASE UNSUBSCRIBE and DO NOT POST if you DON'T LIKE THAT.

