Hi,
as requested, new debug log with "RSYSLOG_DebugFormat" template and the
output.
-Thomas
4421.182766009:main thread : rsyslogd 8.8.0 startup, module path
'../runtime/.libs:../.libs',
cwd:/var/tmp/portage/app-admin/rsyslog-8.8.0/work/rsyslog-8.8.0/tests
4421.182829095:main thread : setting default module load directory
'/var/tmp/portage/app-admin/rsyslog-8.8.0/work/rsyslog-8.8.0/runtime/.libs/'
4421.182836963:main thread : setting default module load directory
'../runtime/.libs:../.libs'
4421.182873037:main thread : caller requested object 'net', not found (iRet
-3003)
4421.182882016:main thread : Requested to load module 'lmnet'
4421.182888507:main thread : loading module '../runtime/.libs/lmnet.so'
4421.182995184:main thread : module lmnet of type 2 being loaded
(keepType=0).
4421.183002820:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.183005992:main thread : entry point 'setModCnf' not present in module
4421.183008968:main thread : entry point 'getModCnfName' not present in
module
4421.183011767:main thread : entry point 'beginCnfLoad' not present in module
4421.183015794:main thread : source file conf.c requested reference for
module 'lmnet', reference count now 1
4421.183026688:main thread : rsyslog runtime initialized, version 8.8.0,
current users 1
4421.183051950:main thread : source file rsyslogd.c requested reference for
module 'lmnet', reference count now 2
4421.189186082:main thread : GenerateLocalHostName uses 'vm-gentoo-x64'
4421.189200261:main thread : deque option C, optarg ''
4421.189204840:main thread : deque option n, optarg ''
4421.189208222:main thread : deque option i, optarg 'rsyslog.pid'
4421.189211097:main thread : deque option f, optarg
'./testsuites/imuxsock_logger.conf'
4421.189226739:main thread : omfile: using transactional output interface.
4421.189248036:main thread : module builtin:omfile of type 1 being loaded
(keepType=0).
4421.189253075:main thread : module config name is 'omfile'
4421.189256343:main thread : module builtin:omfile supports rsyslog v6
config interface
4421.189261103:main thread : entry point 'activateCnfPrePrivDrop' not
present in module
4421.189266007:main thread : entry point 'SetShutdownImmdtPtr' not present
in module
4421.189269089:main thread : entry point 'doAction' not present in module
4421.189272180:main thread : entry point 'endTransaction' not present in
module
4421.189277396:main thread : module builtin:ompipe of type 1 being loaded
(keepType=0).
4421.189281766:main thread : module config name is 'ompipe'
4421.189284898:main thread : module builtin:ompipe supports rsyslog v6
config interface
4421.189289337:main thread : entry point 'activateCnfPrePrivDrop' not
present in module
4421.189293832:main thread : entry point 'SetShutdownImmdtPtr' not present
in module
4421.189296816:main thread : entry point 'beginTransaction' not present in
module
4421.189300041:main thread : entry point 'commitTransaction' not present in
module
4421.189302921:main thread : entry point 'endTransaction' not present in
module
4421.189307816:main thread : module builtin-shell of type 1 being loaded
(keepType=0).
4421.189311435:main thread : entry point 'setModCnf' not present in module
4421.189314350:main thread : entry point 'getModCnfName' not present in
module
4421.189317044:main thread : entry point 'beginCnfLoad' not present in module
4421.189320920:main thread : entry point 'doHUP' not present in module
4421.189323592:main thread : entry point 'SetShutdownImmdtPtr' not present
in module
4421.189326237:main thread : entry point 'beginTransaction' not present in
module
4421.189329019:main thread : entry point 'commitTransaction' not present in
module
4421.189331641:main thread : entry point 'endTransaction' not present in
module
4421.189334337:main thread : entry point 'newActInst' not present in module
4421.189338294:main thread : module builtin:omdiscard of type 1 being loaded
(keepType=0).
4421.189341898:main thread : entry point 'setModCnf' not present in module
4421.189344594:main thread : entry point 'getModCnfName' not present in
module
4421.189353100:main thread : entry point 'beginCnfLoad' not present in module
4421.189357747:main thread : entry point 'doHUP' not present in module
4421.189360598:main thread : entry point 'SetShutdownImmdtPtr' not present
in module
4421.189363427:main thread : entry point 'beginTransaction' not present in
module
4421.189366303:main thread : entry point 'commitTransaction' not present in
module
4421.189369185:main thread : entry point 'endTransaction' not present in
module
4421.189372051:main thread : entry point 'newActInst' not present in module
4421.189377225:main thread : source file omfwd.c requested reference for
module 'lmnet', reference count now 3
4421.189389371:main thread : module builtin:omfwd of type 1 being loaded
(keepType=0).
4421.189394140:main thread : module config name is 'omfwd'
4421.189397320:main thread : module builtin:omfwd supports rsyslog v6 config
interface
4421.189401919:main thread : entry point 'activateCnfPrePrivDrop' not
present in module
4421.189406281:main thread : entry point 'doHUP' not present in module
4421.189409396:main thread : entry point 'SetShutdownImmdtPtr' not present
in module
4421.189412493:main thread : entry point 'doAction' not present in module
4421.189415587:main thread : entry point 'endTransaction' not present in
module
4421.189420476:main thread : module builtin:omusrmsg of type 1 being loaded
(keepType=0).
4421.189424211:main thread : entry point 'setModCnf' not present in module
4421.189427199:main thread : module config name is 'omusrmsg'
4421.189430232:main thread : entry point 'beginCnfLoad' not present in module
4421.189434207:main thread : entry point 'doHUP' not present in module
4421.189437002:main thread : entry point 'SetShutdownImmdtPtr' not present
in module
4421.189439740:main thread : entry point 'beginTransaction' not present in
module
4421.189442744:main thread : entry point 'commitTransaction' not present in
module
4421.189445559:main thread : entry point 'endTransaction' not present in
module
4421.189450564:main thread : rfc5424 parser init called
4421.189453910:main thread : GetParserName addr 0x44eec0
4421.189456950:main thread : module builtin:pmrfc5424 of type 3 being loaded
(keepType=0).
4421.189460031:main thread : entry point 'setModCnf' not present in module
4421.189462837:main thread : entry point 'getModCnfName' not present in
module
4421.189465698:main thread : entry point 'beginCnfLoad' not present in module
4421.189468501:main thread : entry point 'parse2' not present in module
4421.189472321:main thread : DDDDD: added parser 'rsyslog.rfc5424' to list
0x6904b0
4421.189475167:main thread : Parser 'rsyslog.rfc5424' added to list of
available parsers.
4421.189479392:main thread : rfc3164 parser init called
4421.189483024:main thread : module builtin:pmrfc3164 of type 3 being loaded
(keepType=0).
4421.189486082:main thread : entry point 'setModCnf' not present in module
4421.189488901:main thread : entry point 'getModCnfName' not present in
module
4421.189491673:main thread : entry point 'beginCnfLoad' not present in module
4421.189494409:main thread : entry point 'parse2' not present in module
4421.189497543:main thread : DDDDD: added parser 'rsyslog.rfc3164' to list
0x6904b0
4421.189500226:main thread : Parser 'rsyslog.rfc3164' added to list of
available parsers.
4421.189503205:main thread : DDDDD: added parser 'rsyslog.rfc5424' to list
0x6904a8
4421.189505945:main thread : Parser 'rsyslog.rfc5424' added to default
parser set.
4421.189508789:main thread : DDDDD: added parser 'rsyslog.rfc3164' to list
0x6904a8
4421.189511354:main thread : Parser 'rsyslog.rfc3164' added to default
parser set.
4421.189514488:main thread : rsyslog standard file format strgen init
called, compiled with version 8.8.0
4421.189517779:main thread : module builtin:smfile of type 4 being loaded
(keepType=0).
4421.189520620:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.189527629:main thread : entry point 'setModCnf' not present in module
4421.189530574:main thread : entry point 'getModCnfName' not present in
module
4421.189533375:main thread : entry point 'beginCnfLoad' not present in module
4421.189537721:main thread : Strgen 'RSYSLOG_FileFormat' added to list of
available strgens.
4421.189541521:main thread : traditional file format strgen init called,
compiled with version 8.8.0
4421.189544907:main thread : module builtin:smtradfile of type 4 being
loaded (keepType=0).
4421.189547658:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.189550240:main thread : entry point 'setModCnf' not present in module
4421.189552828:main thread : entry point 'getModCnfName' not present in
module
4421.189555421:main thread : entry point 'beginCnfLoad' not present in module
4421.189558720:main thread : Strgen 'RSYSLOG_TraditionalFileFormat' added to
list of available strgens.
4421.189562165:main thread : rsyslog standard (network) forward format
strgen init called, compiled with version 8.8.0
4421.189565526:main thread : module builtin:smfwd of type 4 being loaded
(keepType=0).
4421.189568280:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.189570877:main thread : entry point 'setModCnf' not present in module
4421.189573490:main thread : entry point 'getModCnfName' not present in
module
4421.189576054:main thread : entry point 'beginCnfLoad' not present in module
4421.189579192:main thread : Strgen 'RSYSLOG_ForwardFormat' added to list of
available strgens.
4421.189582997:main thread : rsyslog traditional (network) forward format
strgen init called, compiled with version 8.8.0
4421.189586266:main thread : module builtin:smtradfwd of type 4 being loaded
(keepType=0).
4421.189589115:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.189591782:main thread : entry point 'setModCnf' not present in module
4421.189594395:main thread : entry point 'getModCnfName' not present in
module
4421.189597120:main thread : entry point 'beginCnfLoad' not present in module
4421.189600449:main thread : Strgen 'RSYSLOG_TraditionalForwardFormat' added
to list of available strgens.
4421.189603593:main thread : doing legacy config system init
4421.189654970:main thread : tplAddLine processing template
'RSYSLOG_DebugFormat'
4421.189678876:main thread : tplAddLine processing template
'RSYSLOG_SyslogProtocol23Format'
4421.189687138:main thread : tplAddLine processing template
'RSYSLOG_FileFormat'
4421.189690940:main thread : template bound to strgen 'RSYSLOG_FileFormat'
4421.189694274:main thread : tplAddLine processing template
'RSYSLOG_TraditionalFileFormat'
4421.189697372:main thread : template bound to strgen
'RSYSLOG_TraditionalFileFormat'
4421.189700345:main thread : tplAddLine processing template ' WallFmt'
4421.189705427:main thread : tplAddLine processing template
'RSYSLOG_ForwardFormat'
4421.189708568:main thread : template bound to strgen 'RSYSLOG_ForwardFormat'
4421.189711595:main thread : tplAddLine processing template
'RSYSLOG_TraditionalForwardFormat'
4421.189714643:main thread : template bound to strgen
'RSYSLOG_TraditionalForwardFormat'
4421.189717559:main thread : tplAddLine processing template ' StdUsrMsgFmt'
4421.189722461:main thread : tplAddLine processing template ' StdDBFmt'
4421.189732722:main thread : tplAddLine processing template
'RSYSLOG_SysklogdFileFormat'
4421.189738360:main thread : tplAddLine processing template ' StdPgSQLFmt'
4421.189746263:main thread : tplAddLine processing template ' StdJSONFmt'
4421.189754382:main thread : tplAddLine processing template
'RSYSLOG_omudpspoofDfltSourceTpl'
4421.189794382:main thread : config parser: pushed file
./testsuites/imuxsock_logger.conf on top of stack
4421.189814349:main thread : requested to include config file
'diag-common.conf'
4421.189837331:main thread : config parser: pushed file diag-common.conf on
top of stack
4421.189854571:main thread : cnf:global:cfsysline: $ModLoad
../plugins/imdiag/.libs/imdiag
4421.189864763:main thread : Requested to load module
'../plugins/imdiag/.libs/imdiag'
4421.189869482:main thread : loading module
'../plugins/imdiag/.libs/imdiag.so'
4421.189962160:main thread : source file imdiag.c requested reference for
module 'lmnet', reference count now 4
4421.189971021:main thread : caller requested object 'netstrm', not found
(iRet -3003)
4421.189977967:main thread : Requested to load module 'lmnetstrms'
4421.189982146:main thread : loading module '../runtime/.libs/lmnetstrms.so'
4421.190038733:main thread : doing nsselClassInit
4421.190046531:main thread : doing nspollClassInit
4421.190051956:main thread : module lmnetstrms of type 2 being loaded
(keepType=0).
4421.190055272:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.190058110:main thread : entry point 'setModCnf' not present in module
4421.190060795:main thread : entry point 'getModCnfName' not present in
module
4421.190063525:main thread : entry point 'beginCnfLoad' not present in module
4421.190067127:main thread : source file imdiag.c requested reference for
module 'lmnetstrms', reference count now 1
4421.190071046:main thread : caller requested object 'tcps_sess', not found
(iRet -3003)
4421.190074038:main thread : Requested to load module 'lmtcpsrv'
4421.190077441:main thread : loading module '../runtime/.libs/lmtcpsrv.so'
4421.190086904:main thread : loading module '../.libs/lmtcpsrv.so'
4421.190148080:main thread : source file tcps_sess.c requested reference for
module 'lmnetstrms', reference count now 2
4421.190160452:main thread : source file tcpsrv.c requested reference for
module 'lmnet', reference count now 5
4421.190165027:main thread : source file tcpsrv.c requested reference for
module 'lmnetstrms', reference count now 3
4421.190172456:main thread : module lmtcpsrv of type 2 being loaded
(keepType=0).
4421.190176119:main thread : entry point 'isCompatibleWithFeature' not
present in module
4421.190178896:main thread : entry point 'setModCnf' not present in module
4421.190181719:main thread : entry point 'getModCnfName' not present in
module
4421.190184559:main thread : entry point 'beginCnfLoad' not present in module
4421.190187829:main thread : source file imdiag.c requested reference for
module 'lmtcpsrv', reference count now 1
4421.190191731:main thread : source file imdiag.c requested reference for
module 'lmtcpsrv', reference count now 2
4421.190204250:main thread : module ../plugins/imdiag/.libs/imdiag of type 0
being loaded (keepType=0).
4421.190208073:main thread : entry point 'setModCnf' not present in module
4421.190210958:main thread : entry point 'getModCnfName' not present in
module
4421.190213702:main thread : entry point 'beginCnfLoad' not present in module
4421.190216893:main thread : entry point 'newInpInst' not present in module
4421.190221820:main thread : cnf:global:cfsysline: $IMDiagServerRun 13500
4421.190227505:main thread : doGetWord: get newval '13500' (len 5), hdlr
0x7ff03c3f7210
4421.190234252:main thread : ratelimit:tcperver:new
ratelimiter:bReduceRepeatMsgs 0
4421.190239495:main thread : cnf:global:cfsysline: $template
startupfile,"rsyslogd.started" # trick to use relative path names!
4421.190243905:main thread : tplAddLine processing template 'startupfile'
4421.190249912:main thread : lexer: propfilt is ':syslogtag, contains,
"rsyslogd"'
4421.190257576:main thread : tried selector action for builtin:omfile: 0
4421.190261018:main thread : Module builtin:omfile processes this action.
4421.190268655:main thread : template: 'RSYSLOG_FileFormat' assigned
4421.190272307:main thread : template: 'startupfile' assigned
4421.190279356:main thread : action 1 queue: parameter dump:
4421.190282806:main thread : action 1 queue: queue.filename '[NONE]'
4421.190285464:main thread : action 1 queue: queue.size: 1000
4421.190287980:main thread : action 1 queue: queue.dequeuebatchsize: 16
4421.190297164:main thread : action 1 queue: queue.maxdiskspace: 0
4421.190300050:main thread : action 1 queue: queue.highwatermark: -1
4421.190302765:main thread : action 1 queue: queue.lowwatermark: -1
4421.190305303:main thread : action 1 queue: queue.fulldelaymark: -1
4421.190307883:main thread : action 1 queue: queue.lightdelaymark: -1
4421.190310533:main thread : action 1 queue: queue.discardmark: 980
4421.190313020:main thread : action 1 queue: queue.discardseverity: 8
4421.190315560:main thread : action 1 queue: queue.checkpointinterval: 0
4421.190318030:main thread : action 1 queue: queue.syncqueuefiles: 0
4421.190320619:main thread : action 1 queue: queue.type: 3 [Direct]
4421.190323112:main thread : action 1 queue: queue.workerthreads: 1
4421.190325597:main thread : action 1 queue: queue.timeoutshutdown: 0
4421.190328233:main thread : action 1 queue: queue.timeoutactioncompletion:
1000
4421.190330803:main thread : action 1 queue: queue.timeoutenqueue: 50
4421.190333400:main thread : action 1 queue:
queue.timeoutworkerthreadshutdown: 60000
4421.190336074:main thread : action 1 queue:
queue.workerthreadminimummessages: -1
4421.190338671:main thread : action 1 queue: queue.maxfilesize: 1048576
4421.190341184:main thread : action 1 queue: queue.saveonshutdown: 1
4421.190343730:main thread : action 1 queue: queue.dequeueslowdown: 0
4421.190346274:main thread : action 1 queue: queue.dequeuetimebegin: 0
4421.190348841:main thread : action 1 queue: queue.dequeuetimeend: 25
4421.190351559:main thread : Action 0x7ff03c876100: queue 0x7ff03c87a280
created
4421.190358117:main thread : config parser: reached end of file
diag-common.conf
4421.190361546:main thread : config parser: resume parsing of file
./testsuites/imuxsock_logger.conf at line 2
4421.190365622:main thread : Decoding property-based filter ':syslogtag,
contains, "rsyslogd"'
4421.190372672:main thread : cnf:global:script
4421.190381262:main thread : cnf:global:obj: obj: 'module'
4421.190386644:main thread : nvlst 0x7ff03c81b7f0:
4421.190389839:main thread : name: 'sysSock.use', value 'off'
4421.190392897:main thread : name: 'load', value
'../plugins/imuxsock/.libs/imuxsock'
4421.190397835:main thread : nvlstGetParam: name 'load', type 13,
valnode->bUsed 0
4421.190402404:main thread : modulesProcessCnf params:
4421.190405445:main thread : load: '../plugins/imuxsock/.libs/imuxsock'
4421.190413155:main thread : Requested to load module
'../plugins/imuxsock/.libs/imuxsock'
4421.190416669:main thread : loading module
'../plugins/imuxsock/.libs/imuxsock.so'
4421.190481960:main thread : imuxsock version 8.8.0 initializing
4421.190489015:main thread : rsyslog/glbl: using '127.0.0.1' as localhost IP
4421.190521033:main thread : module ../plugins/imuxsock/.libs/imuxsock of
type 0 being loaded (keepType=0).
4421.190525916:main thread : module config name is 'imuxsock'
4421.190528994:main thread : module ../plugins/imuxsock/.libs/imuxsock
supports rsyslog v6 config interface
4421.190535290:main thread : nvlstGetParam: name 'syssock.use', type 4,
valnode->bUsed 0
4421.190539318:main thread : module (global) param blk for imuxsock:
4421.190542352:main thread : syssock.use: 0
4421.190548788:main thread : syssock.name: (unset)
4421.190554952:main thread : syssock.unlink: (unset)
4421.190560886:main thread : syssock.ignoretimestamp: (unset)
4421.190567066:main thread : syssock.ignoreownmessages: (unset)
4421.190573241:main thread : syssock.flowcontrol: (unset)
4421.190579132:main thread : syssock.usesystimestamp: (unset)
4421.190585121:main thread : syssock.annotate: (unset)
4421.190591015:main thread : syssock.parsetrusted: (unset)
4421.190596878:main thread : syssock.usepidfromsystem: (unset)
4421.190602897:main thread : syssock.ratelimit.interval: (unset)
4421.190608877:main thread : syssock.ratelimit.burst: (unset)
4421.190614758:main thread : syssock.ratelimit.severity: (unset)
4421.190630166:main thread : cnf:global:obj: obj: 'input'
4421.190635667:main thread : nvlst 0x7ff03c81b460:
4421.190638326:main thread : name: 'Socket', value 'testbench_socket'
4421.190641122:main thread : name: 'type', value 'imuxsock'
4421.190644657:main thread : nvlstGetParam: name 'type', type 14,
valnode->bUsed 0
4421.190648283:main thread : input param blk after inputProcessCnf:
4421.190651022:main thread : type: 'imuxsock'
4421.190658283:main thread : newInpInst (imuxsock)
4421.190661570:main thread : nvlstGetParam: name 'socket', type 14,
valnode->bUsed 0
4421.190664910:main thread : input param blk in imuxsock:
4421.190667529:main thread : socket: 'testbench_socket'
4421.190673982:main thread : unlink: (unset)
4421.190680157:main thread : createpath: (unset)
4421.190686211:main thread : parsetrusted: (unset)
4421.190692211:main thread : ignoreownmessages: (unset)
4421.190698195:main thread : hostname: (unset)
4421.190704088:main thread : ignoretimestamp: (unset)
4421.190710148:main thread : flowcontrol: (unset)
4421.190716074:main thread : usesystimestamp: (unset)
4421.190722142:main thread : annotate: (unset)
4421.190728051:main thread : usepidfromsystem: (unset)
4421.190733989:main thread : ratelimit.interval: (unset)
4421.190739835:main thread : ratelimit.burst: (unset)
4421.190745738:main thread : ratelimit.severity: (unset)
4421.190756543:main thread : cnf:global:obj: obj: 'template'
4421.190761788:main thread : nvlst 0x7ff03c81b7f0:
4421.190764446:main thread : name: 'string', value '%msg:%
'
4421.190767189:main thread : name: 'type', value 'string'
4421.190769717:main thread : name: 'name', value 'outfmt'
4421.190772920:main thread : nvlstGetParam: name 'name', type 14,
valnode->bUsed 0
4421.190776117:main thread : nvlstGetParam: name 'type', type 14,
valnode->bUsed 0
4421.190779051:main thread : nvlstGetParam: name 'string', type 14,
valnode->bUsed 0
4421.190781962:main thread : name: 'outfmt'
4421.190788404:main thread : type: 'string'
4421.190794682:main thread : string: '%msg:%
'
4421.190800982:main thread : plugin: (unset)
4421.190806943:main thread : subtree: (unset)
4421.190812915:main thread : option.stdsql: (unset)
4421.190818955:main thread : option.sql: (unset)
4421.190825050:main thread : option.json: (unset)
4421.190835877:main thread : tried selector action for builtin:omfile: 0
4421.190839397:main thread : Module builtin:omfile processes this action.
4421.190842861:main thread : template: 'RSYSLOG_DebugFormat' assigned
4421.190847853:main thread : action 2 queue: parameter dump:
4421.190850902:main thread : action 2 queue: queue.filename '[NONE]'
4421.190853472:main thread : action 2 queue: queue.size: 1000
4421.190855987:main thread : action 2 queue: queue.dequeuebatchsize: 16
4421.190858558:main thread : action 2 queue: queue.maxdiskspace: 0
4421.190861139:main thread : action 2 queue: queue.highwatermark: -1
4421.190863686:main thread : action 2 queue: queue.lowwatermark: -1
4421.190866245:main thread : action 2 queue: queue.fulldelaymark: -1
4421.190868828:main thread : action 2 queue: queue.lightdelaymark: -1
4421.190871366:main thread : action 2 queue: queue.discardmark: 980
4421.190873912:main thread : action 2 queue: queue.discardseverity: 8
4421.190876409:main thread : action 2 queue: queue.checkpointinterval: 0
4421.190878948:main thread : action 2 queue: queue.syncqueuefiles: 0
4421.190881612:main thread : action 2 queue: queue.type: 3 [Direct]
4421.190884135:main thread : action 2 queue: queue.workerthreads: 1
4421.190886651:main thread : action 2 queue: queue.timeoutshutdown: 0
4421.190889256:main thread : action 2 queue: queue.timeoutactioncompletion:
1000
4421.190891879:main thread : action 2 queue: queue.timeoutenqueue: 50
4421.190894449:main thread : action 2 queue:
queue.timeoutworkerthreadshutdown: 60000
4421.190949603:main thread : action 2 queue:
queue.workerthreadminimummessages: -1
4421.190956904:main thread : action 2 queue: queue.maxfilesize: 1048576
4421.190959714:main thread : action 2 queue: queue.saveonshutdown: 1
4421.190962446:main thread : action 2 queue: queue.dequeueslowdown: 0
4421.190965113:main thread : action 2 queue: queue.dequeuetimebegin: 0
4421.190967886:main thread : action 2 queue: queue.dequeuetimeend: 25
4421.190970823:main thread : Action 0x7ff03c876200: queue 0x7ff03c87a600
created
4421.190975251:main thread : config parser: reached end of file
./testsuites/imuxsock_logger.conf
4421.190978313:main thread : config parser: parsing completed
4421.190981555:main thread : Decoding traditional PRI filter '*.notice'
4421.190984736:main thread : symbolic name: notice ==> 5
4421.190993546:main thread : cnf:global:script
4421.190997025:main thread : Number of actions in this configuration: 2
4421.190999991:main thread : begin ruleset optimization phase
4421.191002985:main thread : ruleset 'RSYSLOG_DefaultRuleset' before
optimization:
4421.191006488:main thread : ruleset 0x7ff03c864500: rsyslog ruleset
RSYSLOG_DefaultRuleset:
4421.191010003:main thread : PROPFILT
4421.191012574:main thread : Property.: 'syslogtag'
4421.191015168:main thread : Operation: 'contains'
4421.191020016:main thread : Value....: 'rsyslogd'
4421.191022589:main thread : THEN
4421.191025088:main thread : ACTION 0 [builtin:omfile:?startupfile]
4421.191029754:main thread : END PROPFILT
4421.191032184:main thread : PRIFILT '*.notice'
4421.191034486:main thread : pmask: 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
4421.191087102:main thread : ACTION 1
[builtin:omfile:./rsyslog.out.log;RSYSLOG_DebugFormat]
4421.191091865:main thread : END PRIFILT
4421.191094442:main thread : ruleset 0x7ff03c864500: ruleset
RSYSLOG_DefaultRuleset assigned parser list:
4421.191097820:main thread : ruleset 'RSYSLOG_DefaultRuleset' after
optimization:
4421.191100507:main thread : ruleset 0x7ff03c864500: rsyslog ruleset
RSYSLOG_DefaultRuleset:
4421.191103071:main thread : PROPFILT
4421.191105495:main thread : Property.: 'syslogtag'
4421.191107839:main thread : Operation: 'contains'
4421.191112122:main thread : Value....: 'rsyslogd'
4421.191114399:main thread : THEN
4421.191116673:main thread : ACTION 0 [builtin:omfile:?startupfile]
4421.191121044:main thread : END PROPFILT
4421.191123378:main thread : PRIFILT '*.notice'
4421.191125648:main thread : pmask: 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
4421.191179425:main thread : ACTION 1
[builtin:omfile:./rsyslog.out.log;RSYSLOG_DebugFormat]
4421.191184110:main thread : END PRIFILT
4421.191186613:main thread : ruleset 0x7ff03c864500: ruleset
RSYSLOG_DefaultRuleset assigned parser list:
4421.191189276:main thread : ruleset optimization phase finished.
4421.191191888:main thread : telling rsyslog core that config load for
0x7ff03c85e180 is done
4421.191196033:main thread : Timezone information table (0 entries):
4421.191199112:main thread : telling modules that config load for
0x7ff03c85e180 is done
4421.191201823:main thread : beginCnfLoad(0x44b4c0) for module
'builtin:omfile'
4421.191204301:main thread : calling endCnfLoad() for module 'builtin:omfile'
4421.191206979:main thread : beginCnfLoad(0x44e060) for module
'builtin:ompipe'
4421.191209317:main thread : calling endCnfLoad() for module 'builtin:ompipe'
4421.191212134:main thread : beginCnfLoad((nil)) for module 'builtin-shell'
4421.191214861:main thread : beginCnfLoad((nil)) for module
'builtin:omdiscard'
4421.191217394:main thread : beginCnfLoad(0x448780) for module
'builtin:omfwd'
4421.191219789:main thread : calling endCnfLoad() for module 'builtin:omfwd'
4421.191222464:main thread : beginCnfLoad((nil)) for module
'builtin:omusrmsg'
4421.191225150:main thread : beginCnfLoad((nil)) for module
'builtin:pmrfc5424'
4421.191227648:main thread : beginCnfLoad((nil)) for module
'builtin:pmrfc3164'
4421.191233438:main thread : beginCnfLoad((nil)) for module 'builtin:smfile'
4421.191236249:main thread : beginCnfLoad((nil)) for module
'builtin:smtradfile'
4421.191238777:main thread : beginCnfLoad((nil)) for module 'builtin:smfwd'
4421.191241278:main thread : beginCnfLoad((nil)) for module
'builtin:smtradfwd'
4421.191243840:main thread : beginCnfLoad((nil)) for module
'../plugins/imdiag/.libs/imdiag'
4421.191246586:main thread : beginCnfLoad(0x7ff03bde2d10) for module
'../plugins/imuxsock/.libs/imuxsock'
4421.191249079:main thread : calling endCnfLoad() for module
'../plugins/imuxsock/.libs/imuxsock'
4421.191251890:main thread : telling modules to check config 0x7ff03c85e180
4421.191254627:main thread : module builtin:omfile tells us config can be
activated
4421.191257209:main thread : module builtin:ompipe tells us config can be
activated
4421.191259835:main thread : module builtin:omfwd tells us config can be
activated
4421.191262504:main thread : module ../plugins/imuxsock/.libs/imuxsock tells
us config can be activated
4421.191265119:main thread : rsyslog finished loading master config
0x7ff03c85e180
4421.191267787:main thread : configuration object 0x7ff03c85e180
4421.191270181:main thread : Global Settings:
4421.191272745:main thread : bDebugPrintTemplateList.............: 1
4421.191275167:main thread : bDebugPrintModuleList : 0
4421.191277575:main thread : bDebugPrintCfSysLineHandlerList.....: 0
4421.191279964:main thread : bLogStatusMsgs : 1
4421.191282356:main thread : bErrMsgToStderr.....................: 1
4421.191284865:main thread : drop Msgs with malicious PTR Record : 0
4421.191287258:main thread : All Rulesets:
4421.191289779:main thread : ruleset 0x7ff03c864500: rsyslog ruleset
RSYSLOG_DefaultRuleset:
4421.191292290:main thread : PROPFILT
4421.191294620:main thread : Property.: 'syslogtag'
4421.191296846:main thread : Operation: 'contains'
4421.191300977:main thread : Value....: 'rsyslogd'
4421.191303178:main thread : THEN
4421.191305403:main thread : ACTION 0 [builtin:omfile:?startupfile]
4421.191309659:main thread : END PROPFILT
4421.191311960:main thread : PRIFILT '*.notice'
4421.191314151:main thread : pmask: 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
4421.191365514:main thread : ACTION 1
[builtin:omfile:./rsyslog.out.log;RSYSLOG_DebugFormat]
4421.191370106:main thread : END PRIFILT
4421.191372548:main thread : ruleset 0x7ff03c864500: ruleset
RSYSLOG_DefaultRuleset assigned parser list:
4421.191375137:main thread : End of Rulesets.
4421.191377476:main thread :
4421.191379943:main thread : Template: Name='RSYSLOG_DebugFormat'
4421.191384676:main thread : Entry(7ff03c86c0c0): type 1,
(CONSTANT), value: 'Debug line with all properties:
FROMHOST: ''
4421.191391464:main thread : Entry(7ff03c86c180): type 2, (FIELD),
value: '7'
4421.191397938:main thread : Entry(7ff03c86c240): type 1,
(CONSTANT), value: '', fromhost-ip: ''
4421.191403986:main thread : Entry(7ff03c86c300): type 2, (FIELD),
value: '8'
4421.191410225:main thread : Entry(7ff03c86c3c0): type 1,
(CONSTANT), value: '', HOSTNAME: ''
4421.191416276:main thread : Entry(7ff03c86c480): type 2, (FIELD),
value: '3'
4421.191422335:main thread : Entry(7ff03c86c540): type 1,
(CONSTANT), value: '', PRI: '
4421.191428449:main thread : Entry(7ff03c86c600): type 2, (FIELD),
value: '9'
4421.191434578:main thread : Entry(7ff03c86c6c0): type 1,
(CONSTANT), value: ',
syslogtag ''
4421.191440686:main thread : Entry(7ff03c86c780): type 2, (FIELD),
value: '4'
4421.191449284:main thread : Entry(7ff03c86c840): type 1,
(CONSTANT), value: '', programname: ''
4421.191461237:main thread : Entry(7ff03c86c900): type 2, (FIELD),
value: '17'
4421.191474195:main thread : Entry(7ff03c86c9c0): type 1,
(CONSTANT), value: '', APP-NAME: ''
4421.191480431:main thread : Entry(7ff03c86ca80): type 2, (FIELD),
value: '20'
4421.191490429:main thread : Entry(7ff03c86cb40): type 1,
(CONSTANT), value: '', PROCID: ''
4421.191496721:main thread : Entry(7ff03c86cc00): type 2, (FIELD),
value: '21'
4421.191502914:main thread : Entry(7ff03c86ccc0): type 1,
(CONSTANT), value: '', MSGID: ''
4421.191509073:main thread : Entry(7ff03c86cd80): type 2, (FIELD),
value: '22'
4421.191515336:main thread : Entry(7ff03c86ce40): type 1,
(CONSTANT), value: '',
TIMESTAMP: ''
4421.191521620:main thread : Entry(7ff03c86cf00): type 2, (FIELD),
value: '2'
4421.191527884:main thread : Entry(7ff03c86cfc0): type 1,
(CONSTANT), value: '', STRUCTURED-DATA: ''
4421.191534122:main thread : Entry(7ff03c86d080): type 2, (FIELD),
value: '19'
4421.191540143:main thread : Entry(7ff03c86d140): type 1,
(CONSTANT), value: '',
msg: ''
4421.191546204:main thread : Entry(7ff03c86d200): type 2, (FIELD),
value: '1'
4421.191552304:main thread : Entry(7ff03c86d2c0): type 1,
(CONSTANT), value: ''
escaped msg: ''
4421.191558357:main thread : Entry(7ff03c86d380): type 2, (FIELD),
value: '1' [drop control-characters] [COMPLEX]
4421.191568244:main thread : Entry(7ff03c86d440): type 1,
(CONSTANT), value: ''
inputname: '
4421.191574296:main thread : Entry(7ff03c86d500): type 2, (FIELD),
value: '6'
4421.191580391:main thread : Entry(7ff03c86d5c0): type 1,
(CONSTANT), value: ' rawmsg: ''
4421.191586493:main thread : Entry(7ff03c86d680): type 2, (FIELD),
value: '5'
4421.191592556:main thread : Entry(7ff03c86d740): type 1,
(CONSTANT), value: ''
$!:'
4421.191598581:main thread : Entry(7ff03c86d800): type 2, (FIELD),
value: '200' [EE-Property: '!']
4421.191606889:main thread : Entry(7ff03c86d8c0): type 1,
(CONSTANT), value: '
$.:'
4421.191612956:main thread : Entry(7ff03c86d980): type 2, (FIELD),
value: '202' [Local Var: '!']
4421.191620978:main thread : Entry(7ff03c86da40): type 1,
(CONSTANT), value: '
$/:'
4421.191626956:main thread : Entry(7ff03c86db00): type 2, (FIELD),
value: '203'
4421.191633081:main thread : Entry(7ff03c86dbc0): type 1,
(CONSTANT), value: '
'
4421.191639183:main thread : Template: Name='RSYSLOG_SyslogProtocol23Format'
4421.191643559:main thread : Entry(7ff03c86dc80): type 1,
(CONSTANT), value: '<'
4421.191649762:main thread : Entry(7ff03c86dd40): type 2, (FIELD),
value: '9'
4421.191655936:main thread : Entry(7ff03c86de00): type 1,
(CONSTANT), value: '>1 '
4421.191661915:main thread : Entry(7ff03c86dec0): type 2, (FIELD),
value: '2' [Format as RFC3339-Date] [COMPLEX]
4421.191671697:main thread : Entry(7ff03c86df80): type 1,
(CONSTANT), value: ' '
4421.191677689:main thread : Entry(7ff03c86e040): type 2, (FIELD),
value: '3'
4421.191683614:main thread : Entry(7ff03c86e100): type 1,
(CONSTANT), value: ' '
4421.191689536:main thread : Entry(7ff03c86e1c0): type 2, (FIELD),
value: '20'
4421.191695601:main thread : Entry(7ff03c86e280): type 1,
(CONSTANT), value: ' '
4421.191701574:main thread : Entry(7ff03c86e340): type 2, (FIELD),
value: '21'
4421.191707528:main thread : Entry(7ff03c86e400): type 1,
(CONSTANT), value: ' '
4421.191713598:main thread : Entry(7ff03c86e4c0): type 2, (FIELD),
value: '22'
4421.191719555:main thread : Entry(7ff03c86e580): type 1,
(CONSTANT), value: ' '
4421.191725569:main thread : Entry(7ff03c86e640): type 2, (FIELD),
value: '19'
4421.191731647:main thread : Entry(7ff03c86e700): type 1,
(CONSTANT), value: ' '
4421.191737774:main thread : Entry(7ff03c86e7c0): type 2, (FIELD),
value: '1'
4421.191743796:main thread : Entry(7ff03c86e880): type 1,
(CONSTANT), value: '
'
4421.191749805:main thread : Template: Name='RSYSLOG_FileFormat'
4421.191754202:main thread : Template: Name='RSYSLOG_TraditionalFileFormat'
4421.191758551:main thread : Template: Name=' WallFmt'
4421.191762949:main thread : Entry(7ff03c86e940): type 1,
(CONSTANT), value: '
Message from syslogd@'
4421.191769215:main thread : Entry(7ff03c86ea00): type 2, (FIELD),
value: '3'
4421.191777771:main thread : Entry(7ff03c86eac0): type 1,
(CONSTANT), value: ' at '
4421.191784044:main thread : Entry(7ff03c86eb80): type 2, (FIELD),
value: '16'
4421.191790243:main thread : Entry(7ff03c86ec40): type 1,
(CONSTANT), value: ' ...
'
4421.191796359:main thread : Entry(7ff03c86ed00): type 2, (FIELD),
value: '4'
4421.191802468:main thread : Entry(7ff03c86edc0): type 2, (FIELD),
value: '1'
4421.191808636:main thread : Entry(7ff03c86ee80): type 1,
(CONSTANT), value: '
'
4421.191814720:main thread : Template: Name='RSYSLOG_ForwardFormat'
4421.191819224:main thread : Template:
Name='RSYSLOG_TraditionalForwardFormat'
4421.191823647:main thread : Template: Name=' StdUsrMsgFmt'
4421.191827940:main thread : Entry(7ff03c86ef40): type 1,
(CONSTANT), value: ' '
4421.191834071:main thread : Entry(7ff03c86f0c0): type 2, (FIELD),
value: '4'
4421.191840222:main thread : Entry(7ff03c86f180): type 2, (FIELD),
value: '1'
4421.191846138:main thread : Entry(7ff03c86f240): type 1,
(CONSTANT), value: '
'
4421.191852053:main thread : Template: Name=' StdDBFmt' [SQL-Format (MySQL)]
4421.191858404:main thread : Entry(7ff03c86f300): type 1,
(CONSTANT), value: 'insert into SystemEvents (Message, Facility, FromHost,
Priority, DeviceReportedTime, ReceivedAt, InfoUnitID, SysLogTag) values (''
4421.191864760:main thread : Entry(7ff03c86f3c0): type 2, (FIELD),
value: '1'
4421.191870644:main thread : Entry(7ff03c86f480): type 1,
(CONSTANT), value: '', '
4421.191876589:main thread : Entry(7ff03c86f540): type 2, (FIELD),
value: '12'
4421.191882517:main thread : Entry(7ff03c86f600): type 1,
(CONSTANT), value: ', ''
4421.191888545:main thread : Entry(7ff03c86f6c0): type 2, (FIELD),
value: '3'
4421.191969838:main thread : Entry(7ff03c86f780): type 1,
(CONSTANT), value: '', '
4421.191977960:main thread : Entry(7ff03c86f840): type 2, (FIELD),
value: '14'
4421.191984717:main thread : Entry(7ff03c86f900): type 1,
(CONSTANT), value: ', ''
4421.191990899:main thread : Entry(7ff03c86f9c0): type 2, (FIELD),
value: '2' [Format as MySQL-Date] [COMPLEX]
4421.192001220:main thread : Entry(7ff03c86fa80): type 1,
(CONSTANT), value: '', ''
4421.192007327:main thread : Entry(7ff03c86fb40): type 2, (FIELD),
value: '16' [Format as MySQL-Date] [COMPLEX]
4421.192017043:main thread : Entry(7ff03c86fc00): type 1,
(CONSTANT), value: '', '
4421.192023029:main thread : Entry(7ff03c86fcc0): type 2, (FIELD),
value: '11'
4421.192029056:main thread : Entry(7ff03c86fd80): type 1,
(CONSTANT), value: ', ''
4421.192035156:main thread : Entry(7ff03c86fe40): type 2, (FIELD),
value: '4'
4421.192041156:main thread : Entry(7ff03c86ff00): type 1,
(CONSTANT), value: '')'
4421.192047269:main thread : Template: Name='RSYSLOG_SysklogdFileFormat'
4421.192051823:main thread : Entry(7ff03c86ffc0): type 2, (FIELD),
value: '2'
4421.192057994:main thread : Entry(7ff03c870080): type 1,
(CONSTANT), value: ' '
4421.192064011:main thread : Entry(7ff03c870140): type 2, (FIELD),
value: '3'
4421.192069882:main thread : Entry(7ff03c870200): type 1,
(CONSTANT), value: ' '
4421.192075798:main thread : Entry(7ff03c8702c0): type 2, (FIELD),
value: '4'
4421.192081669:main thread : Entry(7ff03c870380): type 2, (FIELD),
value: '1' [SP iff no first SP] [COMPLEX]
4421.192091431:main thread : Entry(7ff03c870440): type 2, (FIELD),
value: '1'
4421.192097300:main thread : Entry(7ff03c870500): type 1,
(CONSTANT), value: '
'
4421.192103187:main thread : Template: Name=' StdPgSQLFmt' [SQL-Format
(standard SQL)]
4421.192109546:main thread : Entry(7ff03c8705c0): type 1,
(CONSTANT), value: 'insert into SystemEvents (Message, Facility, FromHost,
Priority, DeviceReportedTime, ReceivedAt, InfoUnitID, SysLogTag) values (''
4421.192116184:main thread : Entry(7ff03c870680): type 2, (FIELD),
value: '1'
4421.192122147:main thread : Entry(7ff03c870740): type 1,
(CONSTANT), value: '', '
4421.192128179:main thread : Entry(7ff03c870800): type 2, (FIELD),
value: '12'
4421.192140787:main thread : Entry(7ff03c8708c0): type 1,
(CONSTANT), value: ', ''
4421.192147037:main thread : Entry(7ff03c870980): type 2, (FIELD),
value: '3'
4421.192153125:main thread : Entry(7ff03c870a40): type 1,
(CONSTANT), value: '', '
4421.192159125:main thread : Entry(7ff03c870b00): type 2, (FIELD),
value: '14'
4421.192165103:main thread : Entry(7ff03c870bc0): type 1,
(CONSTANT), value: ', ''
4421.192171081:main thread : Entry(7ff03c870c80): type 2, (FIELD),
value: '2' [Format as PgSQL-Date] [COMPLEX]
4421.192181207:main thread : Entry(7ff03c870d40): type 1,
(CONSTANT), value: '', ''
4421.192187202:main thread : Entry(7ff03c870e00): type 2, (FIELD),
value: '16' [Format as PgSQL-Date] [COMPLEX]
4421.192196932:main thread : Entry(7ff03c870ec0): type 1,
(CONSTANT), value: '', '
4421.192203024:main thread : Entry(7ff03c870f80): type 2, (FIELD),
value: '11'
4421.192209084:main thread : Entry(7ff03c871040): type 1,
(CONSTANT), value: ', ''
4421.192215267:main thread : Entry(7ff03c871100): type 2, (FIELD),
value: '4'
4421.192221401:main thread : Entry(7ff03c8711c0): type 1,
(CONSTANT), value: '')'
4421.192227472:main thread : Template: Name=' StdJSONFmt'
4421.192231704:main thread : Entry(7ff03c871280): type 1,
(CONSTANT), value: '{"message":"'
4421.192237905:main thread : Entry(7ff03c871340): type 2, (FIELD),
value: '1' [format as JSON] [COMPLEX]
4421.192247716:main thread : Entry(7ff03c871400): type 1,
(CONSTANT), value: '","fromhost":"'
4421.192253815:main thread : Entry(7ff03c8714c0): type 2, (FIELD),
value: '3' [format as JSON] [COMPLEX]
4421.192263609:main thread : Entry(7ff03c871580): type 1,
(CONSTANT), value: '","facility":"'
4421.192269722:main thread : Entry(7ff03c871640): type 2, (FIELD),
value: '13'
4421.192275732:main thread : Entry(7ff03c871700): type 1,
(CONSTANT), value: '","priority":"'
4421.192281829:main thread : Entry(7ff03c8717c0): type 2, (FIELD),
value: '15'
4421.192287830:main thread : Entry(7ff03c871880): type 1,
(CONSTANT), value: '","timereported":"'
4421.192293901:main thread : Entry(7ff03c871940): type 2, (FIELD),
value: '2' [Format as RFC3339-Date] [COMPLEX]
4421.192303634:main thread : Entry(7ff03c871a00): type 1,
(CONSTANT), value: '","timegenerated":"'
4421.192309772:main thread : Entry(7ff03c871ac0): type 2, (FIELD),
value: '16' [Format as RFC3339-Date] [COMPLEX]
4421.192319572:main thread : Entry(7ff03c871b80): type 1,
(CONSTANT), value: '"}'
4421.192325733:main thread : Template:
Name='RSYSLOG_omudpspoofDfltSourceTpl'
4421.192330262:main thread : Entry(7ff03c871c40): type 2, (FIELD),
value: '8'
4421.192336387:main thread : Template: Name='startupfile'
4421.192340709:main thread : Entry(7ff03c871f40): type 1,
(CONSTANT), value: 'rsyslogd.started'
4421.192346915:main thread : Template: Name='outfmt'
4421.192351331:main thread : Entry(7ff03c886300): type 2, (FIELD),
value: '1' [COMPLEX]
4421.192359287:main thread : Entry(7ff03c8863c0): type 1,
(CONSTANT), value: '
'
4421.192365516:main thread : Main queue size 100000 messages.
4421.192368291:main thread : Main queue worker threads: 2, wThread shutdown:
60000, Perists every 0 updates.
4421.192371082:main thread : Main queue timeouts: shutdown: 1500, action
completion shutdown: 1000, enq: 2000
4421.192373789:main thread : Main queue watermarks: high: 80000, low: 20000,
discard: 98000, discard-severity: 8
4421.192376453:main thread : Main queue save on shutdown 1, max disk space
allowed 0
4421.192378977:main thread : Work Directory: ''.
4421.192381448:main thread : Modules used in this configuration:
4421.192383969:main thread : builtin:omfile
4421.192386286:main thread : builtin:ompipe
4421.192388559:main thread : builtin-shell
4421.192390936:main thread : builtin:omdiscard
4421.192393267:main thread : builtin:omfwd
4421.192395512:main thread : builtin:omusrmsg
4421.192397728:main thread : builtin:pmrfc5424
4421.192403200:main thread : builtin:pmrfc3164
4421.192405833:main thread : builtin:smfile
4421.192408231:main thread : builtin:smtradfile
4421.192410558:main thread : builtin:smfwd
4421.192412868:main thread : builtin:smtradfwd
4421.192415338:main thread : ../plugins/imdiag/.libs/imdiag
4421.192417916:main thread : ../plugins/imuxsock/.libs/imuxsock
4421.192420851:main thread : GenerateLocalHostName uses 'vm-gentoo-x64'
4421.192425710:main thread : ratelimit:rsyslogd[dflt]:new
ratelimiter:bReduceRepeatMsgs 0
4421.192429428:main thread : ratelimit:rsyslogd[internal_messages]:new
ratelimiter:bReduceRepeatMsgs 0
4421.192432507:main thread : rsyslogd: checking if startup is ok, pidfile
'rsyslog.pid'.
4421.192444077:main thread : telling modules to activate config (before
dropping privs) 0x7ff03c85e180
4421.192448362:main thread : pre priv drop activating config 0x7ff03c85e180
for module ../plugins/imuxsock/.libs/imuxsock
4421.192451486:main thread : imuxsock: allocating memory for 1 listeners
4421.192455240:main thread : ratelimit:imuxsock:new
ratelimiter:bReduceRepeatMsgs 0
4421.192487223:main thread : imuxsock: Opened UNIX socket 'testbench_socket'
(fd 4).
4421.192491292:main thread : telling modules to activate config
0x7ff03c85e180
4421.192494222:main thread : activating config 0x7ff03c85e180 for module
builtin:omfile
4421.192496959:main thread : activating config 0x7ff03c85e180 for module
builtin:ompipe
4421.192499712:main thread : activating config 0x7ff03c85e180 for module
builtin:omfwd
4421.192502492:main thread : activating config 0x7ff03c85e180 for module
../plugins/imuxsock/.libs/imuxsock
4421.192506779:main thread : iterateAllActions calling into action
0x7ff03c876100
4421.192509970:main thread : action 1 queue: starting queue
4421.192516486:main thread : action 1 queue: params: type 3, enq-only 0,
disk assisted 0, spoolDir '', maxFileSz 1048576, maxQSize 1000, lqsize 0,
pqsize 0, child 0, full delay 970, light delay 700, deq batch size 16, high
wtrmrk 900, low wtrmrk 700, discardmrk 980, max wrkr 1, min msgs f. wrkr 1000
4421.192520079:main thread : Action builtin:omfile[0x7ff03c876100]: queue
0x7ff03c87a280 started
4421.192522741:main thread : iterateAllActions calling into action
0x7ff03c876200
4421.192525259:main thread : action 2 queue: starting queue
4421.192529210:main thread : action 2 queue: params: type 3, enq-only 0,
disk assisted 0, spoolDir '', maxFileSz 1048576, maxQSize 1000, lqsize 0,
pqsize 0, child 0, full delay 970, light delay 700, deq batch size 16, high
wtrmrk 900, low wtrmrk 700, discardmrk 980, max wrkr 1, min msgs f. wrkr 1000
4421.192532156:main thread : Action builtin:omfile[0x7ff03c876200]: queue
0x7ff03c87a600 started
4421.192535255:main thread : Activating Ruleset Queue[(nil)] for Ruleset
RSYSLOG_DefaultRuleset
4421.192538197:main thread : activateMainQueue: mainq cnf obj ptr is (nil)
4421.192541886:main thread : main Q: starting queue
4421.192546490:main thread : main Q: is NOT disk-assisted
4421.192550884:main thread : main Q: params: type 0, enq-only 0, disk
assisted 0, spoolDir '', maxFileSz 1048576, maxQSize 100000, lqsize 0, pqsize
0, child 0, full delay 97000, light delay 70000, deq batch size 256, high
wtrmrk 80000, low wtrmrk 20000, discardmrk 98000, max wrkr 2, min msgs f. wrkr
40000
4421.192556241:main thread : main Q:Reg: finalizing construction of worker
thread pool (numworkerThreads 2)
4421.192560576:main thread : main Q:Reg/w0: finalizing construction of
worker instance data (for 2 actions)
4421.192566217:main thread : main Q:Reg/w1: finalizing construction of
worker instance data (for 2 actions)
4421.192569611:main thread : main Q: queue finished initialization
4421.192574167:main thread : Main processing queue is initialized and running
4421.192577766:main thread : running module ../plugins/imdiag/.libs/imdiag
with config 0x7ff03c869440, term mode: cooperative/SIGTTIN
4421.192624871:main thread : running module
../plugins/imuxsock/.libs/imuxsock with config 0x7ff03c869fe0, term mode:
cooperative/SIGTTIN
4421.192661762:7ff03bddf700: thread created, tid 37057, name
'in:../plugins/imdiag/.l'
4421.192697525:7ff03bddf700: set thread name to 'in:../plugins/imdiag/.l'
4421.193017782:imdiag.c : caller requested object 'nsd_ptcp', not found
(iRet -3003)
4421.193032133:imdiag.c : Requested to load module 'lmnsd_ptcp'
4421.193037000:imdiag.c : loading module '../runtime/.libs/lmnsd_ptcp.so'
4421.193050203:main thread : configuration 0x7ff03c85e180 activated
4421.193227702:7ff03b5de700: thread created, tid 37058, name 'in:imuxsock'
4421.193244638:7ff03b5de700: set thread name to 'in:imuxsock'
4421.193587708:imuxsock.c : --------imuxsock calling select, active file
descriptors (max 4): 4
4421.193611304:imdiag.c : source file nsd_ptcp.c requested reference for
module 'lmnetstrms', reference count now 4
4421.193645300:imdiag.c : module lmnsd_ptcp of type 2 being loaded
(keepType=0).
4421.193653436:imdiag.c : entry point 'isCompatibleWithFeature' not
present in module
4421.193657401:imdiag.c : entry point 'setModCnf' not present in module
4421.193660372:imdiag.c : entry point 'getModCnfName' not present in
module
4421.193663187:imdiag.c : entry point 'beginCnfLoad' not present in module
4421.193667088:imdiag.c : source file netstrms.c requested reference for
module 'lmnsd_ptcp', reference count now 1
4421.193671832:imdiag.c : creating tcp listen socket on port 13500
4421.193681399:main thread : main Q: qqueueAdd: entry added, size now log 1,
phys 1 entries
4421.193696479:main thread : main Q:Reg: high activity - starting 1
additional worker thread(s).
4421.193845078:imdiag.c : Allocating buffer for 20 TCP sessions.
4421.194075803:main thread : main Q:Reg: started with state 0, num workers
now 1
4421.194084733:main thread : main Q: EnqueueMsg advised worker start
4421.194088653:main thread : rsyslogd: writing pidfile 'rsyslog.pid'.
4421.194101294:7ff039fff700: thread created, tid 37059, name 'rs:main Q:Reg'
4421.194889751:main Q:Reg/w0 : wti 0x7ff03c82c3e0: worker starting
4421.194910073:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 objects and
enqueued 0 objects
4421.194913666:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 1, phys 1
4421.194920837:main Q:Reg/w0 : processBATCH: batch of 1 elements must be
processed
4421.194939659:main Q:Reg/w0 : processBATCH: next msg 0: [origin
software="rsyslogd" swVersion="8.8.0" x-pid="37054"
x-info="http://www.rsyslog.com";] start
4421.194945591:main Q:Reg/w0 :
4421.194964469:imdiag.c : nsdpoll_ptcp uses epoll_create1()
4421.194993846:imdiag.c : tcpsrv uses epoll() interface, nsdpoll driver
found
4421.195004349:imdiag.c : Trying to add listener 0, pUsr=0x7ff03a8270c0
4421.195009071:imdiag.c : adding nsdpoll entry 0/0x7ff03a8270c0, sock 5
4421.195019084:main Q:Reg/w0 : PROPFILT
4421.195050636:main Q:Reg/w0 : Property.: 'syslogtag'
4421.195077529:main Q:Reg/w0 : Operation: 'contains'
4421.195091247:main Q:Reg/w0 : Value....: 'rsyslogd'
4421.195102485:imdiag.c : Added listener 0
4421.195129192:imdiag.c : Trying to add listener 1, pUsr=0x7ff03a8270c0
4421.195137233:imdiag.c : adding nsdpoll entry 1/0x7ff03a8270c0, sock 6
4421.195143436:imdiag.c : Added listener 1
4421.195147924:imdiag.c : doing epoll_wait for max 128 events
4421.195155721:main thread : rsyslogd: initialization completed,
transitioning to regular run mode
4421.195214468:main Q:Reg/w0 : Filter: check for property 'syslogtag' (value
'rsyslogd:') contains 'rsyslogd': TRUE
4421.195227149:main Q:Reg/w0 : PROPFILT condition result is 1
4421.195231284:main Q:Reg/w0 : ACTION 0 [builtin:omfile:?startupfile]
4421.195241211:main Q:Reg/w0 : executing action 0
4421.195245174:main Q:Reg/w0 : Called action, logging to builtin:omfile
4421.195256727:main Q:Reg/w0 : action 0 is transactional - executing in commit
phase
4421.195267774:main Q:Reg/w0 : wti 0x7ff03c82c3e0: we need to create a new
action worker instance for action 0
4421.195274136:main Q:Reg/w0 : Action 0 transitioned to state: itx
4421.195277829:main Q:Reg/w0 : PRIFILT '*.notice'
4421.195284876:main Q:Reg/w0 : pmask: 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
4421.195346408:main Q:Reg/w0 : PRIFILT condition result is 0
4421.195349243:main Q:Reg/w0 : END batch execution phase, entering to commit
phase
4421.195352482:main Q:Reg/w0 : actionCommitAll: action 0, state 1, nbr to
commit 1 isTransactional 1
4421.195355508:main Q:Reg/w0 : doTransaction: have commitTransaction IF, using
that, pWrkrInfo 0x7ff03c8131e0
4421.195358518:main Q:Reg/w0 : entering actionCallCommitTransaction(), state:
itx, actionNbr 0, nMsgs 1
4421.195361730:main Q:Reg/w0 : omfile: file to log to: rsyslogd.started
4421.195399386:main Q:Reg/w0 : file stream rsyslogd.started params: flush
interval 0, async write 0
4421.195405266:main Q:Reg/w0 : Added new entry 0 for file cache, file
'rsyslogd.started'.
4421.195408943:main Q:Reg/w0 : omfile: write to stream, pData->pStrm
0x7ff038022080, lenBuf 156, strt data 2015-03-03T21:27:01.193090+01:00
vm-gentoo-x64 rsyslogd: [origin software="rsyslogd" swVersion="8.8.0"
x-pid="37054" x-info="htt
4421.195413673:main Q:Reg/w0 : strm 0x7ff038022080: file -1(rsyslogd.started)
flush, buflen 156
4421.195417483:main Q:Reg/w0 : strmPhysWrite, stream 0x7ff038022080, len 156
4421.195423956:main Q:Reg/w0 : file './rsyslogd.started' opened as #8 with
mode 420
4421.195431570:main Q:Reg/w0 : strm 0x7ff038022080: opened file
'./rsyslogd.started' for WRITE as 8
4421.195442452:main Q:Reg/w0 : strm 0x7ff038022080: file 8 write wrote 156
bytes
4421.195446103:main Q:Reg/w0 : Action 0 transitioned to state: rdy
4421.195449489:main Q:Reg/w0 : Action 0 transitioned to state: itx
4421.195452639:main Q:Reg/w0 : Action 0 transitioned to state: rdy
4421.195455617:main Q:Reg/w0 : actionCommit, in retry loop, iRet 0
4421.195458547:main Q:Reg/w0 : processBATCH: batch of 1 elements has been
processed
4421.195461692:main Q:Reg/w0 : regular consumer finished, iret=0, szlog 0 sz
phys 1
4421.195466482:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 1 objects and
enqueued 0 objects
4421.195469742:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 0, phys 0
4421.195472885:main Q:Reg/w0 : regular consumer finished, iret=4, szlog 0 sz
phys 0
4421.195475773:main Q:Reg/w0 : main Q:Reg/w0: worker IDLE, waiting for work.
4421.289611891:imuxsock.c : Message from UNIX socket: #4
4421.289653510:imuxsock.c : main Q: qqueueAdd: entry added, size now log 1,
phys 1 entries
4421.289697013:imuxsock.c : main Q: EnqueueMsg advised worker start
4421.289710030:imuxsock.c : --------imuxsock calling select, active file
descriptors (max 4): 4
4421.289760285:main Q:Reg/w0 : wti 0x7ff03c82c3e0: worker awoke from idle
processing
4421.289782685:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 objects and
enqueued 0 objects
4421.289789160:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 1, phys 1
4421.289798016:main Q:Reg/w0 : processBATCH: batch of 1 elements must be
processed
4421.289803924:main Q:Reg/w0 : processBATCH: next msg 0: <5>Mar 3 21:27:01
vm-gentoo-x64 root: test
4421.289809975:main Q:Reg/w0 : PROPFILT
4421.289822229:main Q:Reg/w0 : Property.: 'syslogtag'
4421.289834790:main Q:Reg/w0 : Operation: 'contains'
4421.289850615:main Q:Reg/w0 : Value....: 'rsyslogd'
4421.289865132:main Q:Reg/w0 : Filter: check for property 'syslogtag' (value
'vm-gentoo-x64') contains 'rsyslogd': FALSE
4421.289874613:main Q:Reg/w0 : PROPFILT condition result is 0
4421.289879268:main Q:Reg/w0 : PRIFILT '*.notice'
4421.289891426:main Q:Reg/w0 : pmask: 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
4421.290049370:main Q:Reg/w0 : PRIFILT condition result is 1
4421.290054563:main Q:Reg/w0 : ACTION 1
[builtin:omfile:./rsyslog.out.log;RSYSLOG_DebugFormat]
4421.290080692:main Q:Reg/w0 : executing action 1
4421.290086649:main Q:Reg/w0 : Called action, logging to builtin:omfile
4421.290122161:main Q:Reg/w0 : action 1 is transactional - executing in commit
phase
4421.290130780:main Q:Reg/w0 : wti 0x7ff03c82c3e0: we need to create a new
action worker instance for action 1
4421.290137621:main Q:Reg/w0 : Action 1 transitioned to state: itx
4421.290143664:main Q:Reg/w0 : END batch execution phase, entering to commit
phase
4421.290149706:main Q:Reg/w0 : actionCommitAll: action 0, state 0, nbr to
commit 0 isTransactional 1
4421.290155465:main Q:Reg/w0 : actionCommitAll: action 1, state 1, nbr to
commit 0 isTransactional 1
4421.290160775:main Q:Reg/w0 : doTransaction: have commitTransaction IF, using
that, pWrkrInfo 0x7ff03c813250
4421.290166291:main Q:Reg/w0 : entering actionCallCommitTransaction(), state:
itx, actionNbr 1, nMsgs 1
4421.290224435:main Q:Reg/w0 : file stream rsyslog.out.log params: flush
interval 0, async write 0
4421.290234467:main Q:Reg/w0 : omfile: write to stream, pData->pStrm
0x7ff03c856580, lenBuf 414, strt data Debug line with all properties:
FROMHOST: 'vm-gentoo-x64', fromhost-ip: '127.0.0.1', HOSTNAME: 'vm-gentoo-x64',
PRI: 5,
syslogta
4421.290243439:main Q:Reg/w0 : strm 0x7ff03c856580: file -1(rsyslog.out.log)
flush, buflen 414
4421.290249681:main Q:Reg/w0 : strmPhysWrite, stream 0x7ff03c856580, len 414
4421.290259880:main Q:Reg/w0 : file './rsyslog.out.log' opened as #9 with mode
420
4421.290269884:main Q:Reg/w0 : strm 0x7ff03c856580: opened file
'./rsyslog.out.log' for WRITE as 9
4421.290289761:main Q:Reg/w0 : strm 0x7ff03c856580: file 9 write wrote 414
bytes
4421.290296310:main Q:Reg/w0 : Action 1 transitioned to state: rdy
4421.290302062:main Q:Reg/w0 : Action 1 transitioned to state: itx
4421.290307382:main Q:Reg/w0 : Action 1 transitioned to state: rdy
4421.290312519:main Q:Reg/w0 : actionCommit, in retry loop, iRet 0
4421.290317749:main Q:Reg/w0 : processBATCH: batch of 1 elements has been
processed
4421.290323437:main Q:Reg/w0 : regular consumer finished, iret=0, szlog 0 sz
phys 1
4421.290330826:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 1 objects and
enqueued 0 objects
4421.290336192:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 0, phys 0
4421.290341911:main Q:Reg/w0 : regular consumer finished, iret=4, szlog 0 sz
phys 0
4421.290346802:main Q:Reg/w0 : main Q:Reg/w0: worker IDLE, waiting for work.
4421.399881928:imdiag.c : epoll returned 1 entries
4421.399939976:imdiag.c : epoll push ppusr[0]: 0x7ff03a8270c0
4421.399948539:imdiag.c : tcpsrv: ready to process 1 event entries
4421.399954472:imdiag.c : tcpsrv: processing item 0, pUsr 0x7ff03a8270c0,
bAbortConn
4421.399959766:imdiag.c : New connect on NSD 0x7ff03a83f100.
4421.399978449:imdiag.c : dnscache: entry (nil) found
4421.400511171:imdiag.c : adding nsdpoll entry 0/0x7ff03a8321d0, sock 10
4421.400532484:imdiag.c : New session created with NSD 0x7ff03a8321d0.
4421.400538984:imdiag.c : doing epoll_wait for max 128 events
4421.400545524:imdiag.c : epoll returned 1 entries
4421.400550554:imdiag.c : epoll push ppusr[0]: 0x7ff03a8321d0
4421.400555580:imdiag.c : tcpsrv: ready to process 1 event entries
4421.400560943:imdiag.c : tcpsrv: processing item 0, pUsr 0x7ff03a8321d0,
bAbortConn
4421.400579588:imdiag.c : netstream 0x7ff03a83f2c0 with new data
4421.400607872:imdiag.c : imdiag received command 'waitmainqueueempty'
4422.152159523:imdiag.c : imdiag: mainqueue empty
4422.152201515:imdiag.c : doing epoll_wait for max 128 events
4422.152515907:imdiag.c : epoll returned 1 entries
4422.152536481:imdiag.c : epoll push ppusr[0]: 0x7ff03a8321d0
4422.152543965:imdiag.c : tcpsrv: ready to process 1 event entries
4422.152549816:imdiag.c : tcpsrv: processing item 0, pUsr 0x7ff03a8321d0,
bAbortConn
4422.152555703:imdiag.c : netstream 0x7ff03a83f2c0 with new data
4422.152566259:imdiag.c : removing nsdpoll entry 0/0x7ff03a8321d0, sock 10
4422.152654460:imdiag.c : doing epoll_wait for max 128 events
4423.159036334:main thread : DoDie called.
4423.159078016:main thread : exiting on signal 15
4423.159083957:main thread : Terminating input threads...
4423.159091222:main thread : request term via SIGTTIN for input thread
'../plugins/imdiag/.libs/imdiag' 0x3bddf700
4423.159205127:imdiag.c : removing nsdpoll entry 0/0x7ff03a8270c0, sock 5
4423.159231562:imdiag.c : removing nsdpoll entry 1/0x7ff03a8270c0, sock 6
4423.159242349:imdiag.c : thrdStarter: usrThrdMain
../plugins/imdiag/.libs/imdiag - 0x7ff03bddf700 returned with iRet 0, exiting
now.
4423.159340400:main thread : input thread term: thread
../plugins/imdiag/.libs/imdiag returned normally and is terminated
4423.159362078:main thread : non-cancel input thread termination succeeded
for thread ../plugins/imdiag/.libs/imdiag 0x3bddf700
4423.159749628:imdiag.c : destructor for debug call stack 0x7ff03a80e000
called
4423.159926403:main thread : request term via SIGTTIN for input thread
'imuxsock' 0x3b5de700
4423.160048127:imuxsock.c : thrdStarter: usrThrdMain imuxsock -
0x7ff03b5de700 returned with iRet 0, exiting now.
4423.160152494:main thread : input thread term: thread imuxsock returned
normally and is terminated
4423.160202175:main thread : non-cancel input thread termination succeeded
for thread imuxsock 0x3b5de700
4423.160339237:imuxsock.c : destructor for debug call stack 0x7ff03a00e000
called
4423.160476484:main thread : imuxsock: unlinking unix socket file[1]
testbench_socket
4423.160546872:main thread : main Q: qqueueAdd: entry added, size now log 1,
phys 1 entries
4423.160593924:main thread : main Q: EnqueueMsg advised worker start
4423.160650854:main Q:Reg/w0 : wti 0x7ff03c82c3e0: worker awoke from idle
processing
4423.160674027:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 objects and
enqueued 0 objects
4423.160681293:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 1, phys 1
4423.160690201:main Q:Reg/w0 : processBATCH: batch of 1 elements must be
processed
4423.160696494:main Q:Reg/w0 : processBATCH: next msg 0: [origin
software="rsyslogd" swVersion="8.8.0" x-pid="37054"
x-info="http://www.rsyslog.com";] exiting on signal 15.
4423.160702767:main Q:Reg/w0 : PROPFILT
4423.160714386:main Q:Reg/w0 : Property.: 'syslogtag'
4423.160726438:main Q:Reg/w0 : Operation: 'contains'
4423.160742204:main Q:Reg/w0 : Value....: 'rsyslogd'
4423.160756504:main Q:Reg/w0 : Filter: check for property 'syslogtag' (value
'rsyslogd:') contains 'rsyslogd': TRUE
4423.160766153:main Q:Reg/w0 : PROPFILT condition result is 1
4423.160771016:main Q:Reg/w0 : ACTION 0 [builtin:omfile:?startupfile]
4423.160783823:main Q:Reg/w0 : executing action 0
4423.160789038:main Q:Reg/w0 : Called action, logging to builtin:omfile
4423.160798296:main Q:Reg/w0 : action 0 is transactional - executing in commit
phase
4423.160804664:main Q:Reg/w0 : Action 0 transitioned to state: itx
4423.160809718:main Q:Reg/w0 : PRIFILT '*.notice'
4423.160821393:main Q:Reg/w0 : pmask: 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F 3F
4423.160925666:main Q:Reg/w0 : PRIFILT condition result is 0
4423.160930313:main Q:Reg/w0 : END batch execution phase, entering to commit
phase
4423.160935751:main Q:Reg/w0 : actionCommitAll: action 0, state 1, nbr to
commit 1 isTransactional 1
4423.160941282:main Q:Reg/w0 : doTransaction: have commitTransaction IF, using
that, pWrkrInfo 0x7ff03c8131e0
4423.160946495:main Q:Reg/w0 : entering actionCallCommitTransaction(), state:
itx, actionNbr 0, nMsgs 1
4423.160951983:main Q:Reg/w0 : omfile: file to log to: rsyslogd.started
4423.160958519:main Q:Reg/w0 : omfile: write to stream, pData->pStrm
0x7ff038022080, lenBuf 172, strt data 2015-03-03T21:27:03.160536+01:00
vm-gentoo-x64 rsyslogd: [origin software="rsyslogd" swVersion="8.8.0"
x-pid="37054" x-info="htt
4423.160965379:main Q:Reg/w0 : strm 0x7ff038022080: file 8(rsyslogd.started)
flush, buflen 172
4423.160984476:main Q:Reg/w0 : strmPhysWrite, stream 0x7ff038022080, len 172
4423.160995961:main Q:Reg/w0 : strm 0x7ff038022080: file 8 write wrote 172
bytes
4423.161002036:main Q:Reg/w0 : Action 0 transitioned to state: rdy
4423.161067098:main Q:Reg/w0 : Action 0 transitioned to state: itx
4423.161076061:main Q:Reg/w0 : Action 0 transitioned to state: rdy
4423.161081312:main Q:Reg/w0 : actionCommit, in retry loop, iRet 0
4423.161086797:main Q:Reg/w0 : actionCommitAll: action 1, state 0, nbr to
commit 0 isTransactional 1
4423.161091847:main Q:Reg/w0 : processBATCH: batch of 1 elements has been
processed
4423.161097263:main Q:Reg/w0 : regular consumer finished, iret=0, szlog 0 sz
phys 1
4423.161105664:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 1 objects and
enqueued 0 objects
4423.161111057:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 0, phys 0
4423.161116704:main Q:Reg/w0 : regular consumer finished, iret=4, szlog 0 sz
phys 0
4423.161121593:main Q:Reg/w0 : main Q:Reg/w0: worker IDLE, waiting for work.
4423.161151097:main thread : Terminating main queue...
4423.161171988:main thread : main Q: initiating worker thread shutdown
sequence
4423.161178070:main thread : main Q: trying shutdown of regular workers
4423.161219599:main thread : sent SIGTTIN to worker thread 0x39fff700
4423.161230400:main thread : main Q:Reg: waiting 1499ms on worker thread
termination, 1 still running
4423.161256964:main Q:Reg/w0 : wti 0x7ff03c82c3e0: worker awoke from idle
processing
4423.161278173:main Q:Reg/w0 : DeleteProcessedBatch: we deleted 0 objects and
enqueued 0 objects
4423.161285527:main Q:Reg/w0 : doDeleteBatch: delete batch from store, new
sizes: log 0, phys 0
4423.161291907:main Q:Reg/w0 : regular consumer finished, iret=4, szlog 0 sz
phys 0
4423.161297447:main Q:Reg/w0 : wti 0x7ff03c82c3e0: terminating worker
terminateRet=5, bInactivityTOOccured=0
4423.161304528:main Q:Reg/w0 : DDDD: wti 0x7ff03c82c3e0: worker cleanup action
instances
4423.161310204:main Q:Reg/w0 : wti 0x7ff03c82c3e0, action 0, ptr 0x7ff03801f058
4423.161317504:main Q:Reg/w0 : wti 0x7ff03c82c3e0, action 1, ptr 0x7ff03801f068
4423.161322961:main Q:Reg/w0 : wti 0x7ff03c82c3e0: worker exiting
4423.161328606:main Q:Reg/w0 : main Q:Reg: Worker thread 7ff03c82c3e0,
terminated, num workers now 0
4423.161477137:main thread : main Q: regular queue workers shut down.
4423.161497544:main thread : main Q: checking to see if we need to cancel
any worker threads of the primary queue
4423.161533654:main thread : main Q: worker threads terminated, remaining
queue size log 0, phys 0.
4423.161547289:main thread : main Q: queue (type 0) will lose 0 messages,
destroying...
4423.162101242:main thread : Terminating outputs...
4423.162117149:main thread : destructing ruleset 0x7ff03c864500, name
0x7ff03c834a00
4423.162127130:main thread : Removing entry 0 for file 'rsyslogd.started'
from dynaCache.
4423.162133488:main thread : strm 0x7ff038022080: file 8(rsyslogd.started)
closing
4423.162139307:main thread : strm 0x7ff038022080: file 8(rsyslogd.started)
flush, buflen 0 (no need to flush)
4423.162148367:main thread : DDDD: strmCloseFile: 8
4423.162188340:main thread : strm 0x7ff03c856580: file 9(rsyslog.out.log)
closing
4423.162198321:main thread : strm 0x7ff03c856580: file 9(rsyslog.out.log)
flush, buflen 0 (no need to flush)
4423.162228475:main thread : DDDD: strmCloseFile: 9
4423.162241253:main thread : all primary multi-thread sources have been
terminated - now doing aux cleanup...
4423.162272667:main thread : destructing current config...
4423.162291697:main thread : calling freeCnf(0x7ff03c81b220) for module
'builtin:omfile'
4423.162298878:main thread : calling freeCnf(0x7ff03c810230) for module
'builtin:ompipe'
4423.162304578:main thread : calling freeCnf(0x7ff03c810260) for module
'builtin:omfwd'
4423.162310497:main thread : calling freeCnf(0x7ff03c866840) for module
'../plugins/imuxsock/.libs/imuxsock'
4423.162345502:main Q:Reg/w0 : destructor for debug call stack 0x7ff03800e000
called
4423.162382261:main thread : destructing parser 'rsyslog.rfc5424'
4423.162418247:main thread : destructing parser 'rsyslog.rfc3164'
4423.162460074:main thread : module lmnet NOT unloaded because it still has
a refcount of 5
4423.162469476:main thread : Unloading module builtin:omfile
4423.162475948:main thread : module lmnet NOT unloaded because it still has
a refcount of 5
4423.162480893:main thread : Unloading module builtin:ompipe
4423.162486113:main thread : module lmnet NOT unloaded because it still has
a refcount of 5
4423.162490974:main thread : Unloading module builtin-shell
4423.162496289:main thread : module lmnet NOT unloaded because it still has
a refcount of 5
4423.162500967:main thread : Unloading module builtin:omdiscard
4423.162506255:main thread : module lmnet NOT unloaded because it still has
a refcount of 5
4423.162511048:main thread : Unloading module builtin:omfwd
4423.162542709:main thread : file omfwd.c released module 'lmnet', reference
count now 4
4423.162553082:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162558396:main thread : Unloading module builtin:omusrmsg
4423.162563725:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162568440:main thread : Unloading module builtin:pmrfc5424
4423.162574121:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162604102:main thread : Unloading module builtin:pmrfc3164
4423.162610778:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162615784:main thread : Unloading module builtin:smfile
4423.162620877:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162625549:main thread : Unloading module builtin:smtradfile
4423.162630670:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162635259:main thread : Unloading module builtin:smfwd
4423.162640099:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162644924:main thread : Unloading module builtin:smtradfwd
4423.162651253:main thread : module lmnet NOT unloaded because it still has
a refcount of 4
4423.162656259:main thread : module lmnetstrms NOT unloaded because it still
has a refcount of 4
4423.162660996:main thread : module lmtcpsrv NOT unloaded because it still
has a refcount of 2
4423.162665801:main thread : Unloading module ../plugins/imdiag/.libs/imdiag
4423.162699671:main thread : file netstrms.c released module 'lmnsd_ptcp',
reference count now 0
4423.162706813:main thread : module 'lmnsd_ptcp' has zero reference count,
unloading...
4423.162711582:main thread : Unloading module lmnsd_ptcp
4423.162720898:main thread : file nsd_ptcp.c released module 'lmnetstrms',
reference count now 3
4423.162820035:main thread : file imdiag.c released module 'lmnet',
reference count now 3
4423.162831643:main thread : file imdiag.c released module 'lmnetstrms',
reference count now 2
4423.162838440:main thread : file imdiag.c released module 'lmtcpsrv',
reference count now 1
4423.162845087:main thread : file imdiag.c released module 'lmtcpsrv',
reference count now 0
4423.162850365:main thread : module 'lmtcpsrv' has zero reference count,
unloading...
4423.162855015:main thread : Unloading module lmtcpsrv
4423.163090053:main thread : tcpsrv: info: worker 0 was called 0 times
4423.163274734:main thread : tcpsrv: info: worker 1 was called 0 times
4423.163480704:main thread : tcpsrv: info: worker 2 was called 0 times
4423.163746859:main thread : tcpsrv: info: worker 3 was called 0 times
4423.163764803:main thread : file tcpsrv.c released module 'lmnetstrms',
reference count now 1
4423.163772777:main thread : file tcpsrv.c released module 'lmnet',
reference count now 2
4423.163781538:main thread : file tcps_sess.c released module 'lmnetstrms',
reference count now 0
4423.163786961:main thread : module 'lmnetstrms' has zero reference count,
unloading...
4423.163801621:main thread : Unloading module lmnetstrms
4423.163879733:main thread : module lmnet NOT unloaded because it still has
a refcount of 2
4423.163888438:main thread : Unloading module
../plugins/imuxsock/.libs/imuxsock
4423.163915656:main thread : module lmnet NOT unloaded because it still has
a refcount of 2
4423.163925640:main thread : file conf.c released module 'lmnet', reference
count now 1
4423.163935653:main thread : rsyslog runtime de-initialized, current users 0
4423.163940775:main thread : Clean shutdown completed, bye
Debug line with all properties:
FROMHOST: 'vm-gentoo-x64', fromhost-ip: '127.0.0.1', HOSTNAME: 'vm-gentoo-x64',
PRI: 5,
syslogtag 'vm-gentoo-x64', programname: 'vm-gentoo-x64', APP-NAME:
'vm-gentoo-x64', PROCID: '-', MSGID: '-',
TIMESTAMP: 'Mar 3 21:27:01', STRUCTURED-DATA: '-',
msg: ' root: test'
escaped msg: ' root: test'
inputname: imuxsock rawmsg: '<5>Mar 3 21:27:01 vm-gentoo-x64 root: test'
$!:
$.:
$/:
_______________________________________________
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.
