trafficserver-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Manas Agarwal <manasagarwa...@gmail.com>
Subject Regarding TSTextLogObjectWrite
Date Mon, 04 Feb 2013 10:46:59 GMT
Hi All,


I am trying to write to log object for custom log file.
I am getting the following back trace -
#0  0x0000003912630265 in raise () from /lib64/libc.so.6
#1  0x0000003912631d10 in abort () from /lib64/libc.so.6
#2  0x00002b3fed3cda4e in ink_die_die_die (retval=1) at ink_error.cc:43
#3  0x00002b3fed3cdccd in ink_fatal_va (return_code=1,
message_format=0x2b3fed3e9133 "%s:%d: failed assert `%s`", ap=0x4128d0f0)
at ink_error.cc:65
#4  0x00002b3fed3cde9a in ink_fatal (return_code=1,
message_format=0x2b3fed3e9133 "%s:%d: failed assert `%s`") at
ink_error.cc:73
#5  0x00002b3fed3cc2a7 in _ink_assert (expression=0x718af6
"m_unaligned_buffer", file=0x718857 "LogBuffer.cc", line=207) at
ink_assert.cc:38
#6  0x00000000005c70d3 in LogBuffer::checkout_write (this=0x2aaab2027700,
write_offset=0x4128d3c8, write_size=2016) at LogBuffer.cc:207
#7  0x00000000005e42ee in LogObject::_checkout_write (this=0x1b5e5a50,
write_offset=0x4128d3c8, bytes_needed=2016) at LogObject.cc:424
#8  0x00000000005e4eb1 in LogObject::log (this=0x1b5e5a50, lad=0x0,
    text_entry=0x4128d460 "2013-02-04T14:41:00ZIST+0530 CORRELATION_ID:
d44aaf8caeb5df578673e8d5245475bb, Host Setting: URL Path is
10.224.236.1148051Record-Manager-Mock/rest/rm/RecordingInfo?/RecordingInfo\255\276\357\276\357\276\357\276\357\276\357"...)
at LogObject.cc:579
#9  0x00000000005e52a4 in TextLogObject::va_write (this=0x1b5e5a50,
    format=0x1e00ed78 "2013-02-04T14:41:00ZIST+0530 CORRELATION_ID:
d44aaf8caeb5df578673e8d5245475bb, Host Setting: URL Path is
10.224.236.1148051Record-Manager-Mock/rest/rm/RecordingInfo?/RecordingInfo\255\276\357\276\357\276\357\276\357\276\357"...,
ap=0x412914a0) at LogObject.cc:876
#10 0x00000000004e6909 in TSTextLogObjectWrite (the_object=0x1b5e5a50,
    format=0x1e00ed78 "2013-02-04T14:41:00ZIST+0530 CORRELATION_ID:
d44aaf8caeb5df578673e8d5245475bb, Host Setting: URL Path is
10.224.236.1148051Record-Manager-Mock/rest/rm/RecordingInfo?/RecordingInfo\255\276\357\276\357\276\357\276\357\276\357"...)
at InkAPI.cc:6959
#11 0x00002aaaaf056d34 in CNSLogger::writeAppLog (this=0x1b5b55b0,
f_logLevel=<value optimized out>, f_strMsg=<value optimized out>) at
../src/CNSLogger.cpp:280
#12 0x00002aaaaf03493d in S7Command::server_request (this=<value optimized
out>, f_ptrContainer=<value optimized out>, f_tsHttpTxn=<value optimized
out>,
    f_contp=<value optimized out>) at ../src/S7Command.cpp:116
#13 0x00002aaaaf0465f4 in CNSApp::server_remap (f_ptrCtx=0x2aaab207a1f0,
f_tsHttpTxn=0x2aaac5aad510, f_pCont=0x2aaab9d9b810) at ../src/CNSApp.cpp:437
#14 0x00002aaaaf04067f in ats_event_handler(TSCont, ._23, void *)
(contp=0x2aaab9d9b810, event=TS_EVENT_HTTP_POST_REMAP, edata=<value
optimized out>)
    at ../src/CNSAppInterface.cpp:286
#15 0x00000000004deaa0 in INKContInternal::handle_event
(this=0x2aaab9d9b810, event=60017, edata=0x2aaac5aad510) at InkAPI.cc:991
#16 0x00000000004d4d3d in Continuation::handleEvent (this=0x2aaab9d9b810,
event=60017, data=0x2aaac5aad510) at
../iocore/eventsystem/I_Continuation.h:146
#17 0x00000000004de875 in APIHook::invoke (this=0x2aaab1ff26d0,
event=60017, edata=0x2aaac5aad510) at InkAPI.cc:1210
#18 0x00000000005675e9 in HttpSM::state_api_callout (this=0x2aaac5aad510,
event=0, data=0x0) at HttpSM.cc:1379
#19 0x0000000000567b17 in HttpSM::do_api_callout_internal
(this=0x2aaac5aad510) at HttpSM.cc:4624
#20 0x0000000000572fef in HttpSM::do_api_callout (this=0x2aaac5aad510) at
HttpSM.cc:500
#21 0x000000000056ce12 in HttpSM::set_next_state (this=0x2aaac5aad510) at
HttpSM.cc:6643
#22 0x000000000055a25f in HttpSM::call_transact_and_set_next_state
(this=0x2aaac5aad510, f=0) at HttpSM.cc:6609
#23 0x000000000056cf2b in HttpSM::set_next_state (this=0x2aaac5aad510) at
HttpSM.cc:6659
#24 0x000000000055a25f in HttpSM::call_transact_and_set_next_state
(this=0x2aaac5aad510, f=0) at HttpSM.cc:6609
#25 0x000000000056ea76 in HttpSM::handle_api_return (this=0x2aaac5aad510)
at HttpSM.cc:1521
#26 0x00000000005678a4 in HttpSM::state_api_callout (this=0x2aaac5aad510,
event=0, data=0x0) at HttpSM.cc:1453
---Type <return> to continue, or q <return> to quit---
#27 0x0000000000567b17 in HttpSM::do_api_callout_internal
(this=0x2aaac5aad510) at HttpSM.cc:4624
#28 0x0000000000572fef in HttpSM::do_api_callout (this=0x2aaac5aad510) at
HttpSM.cc:500
#29 0x000000000056ce12 in HttpSM::set_next_state (this=0x2aaac5aad510) at
HttpSM.cc:6643
#30 0x000000000055a25f in HttpSM::call_transact_and_set_next_state
(this=0x2aaac5aad510, f=0) at HttpSM.cc:6609
#31 0x000000000056ea76 in HttpSM::handle_api_return (this=0x2aaac5aad510)
at HttpSM.cc:1521
#32 0x00000000005678a4 in HttpSM::state_api_callout (this=0x2aaac5aad510,
event=60000, data=0x0) at HttpSM.cc:1453
#33 0x000000000056bda5 in HttpSM::state_api_callback (this=0x2aaac5aad510,
event=60000, data=0x0) at HttpSM.cc:1272
#34 0x00000000004e8e22 in TSHttpTxnReenable(TSHttpTxn, ._184)
(txnp=0x2aaac5aad510, event=TS_EVENT_HTTP_CONTINUE) at InkAPI.cc:5589
#35 0x00002aaaaf03ff2d in ats_event_handler(TSCont, ._23, void *)
(contp=0x2aaab9d9b810, event=2986616080, edata=<value optimized out>)
    at ../src/CNSAppInterface.cpp:355
#36 0x00000000004deaa0 in INKContInternal::handle_event
(this=0x2aaab9d9b810, event=60002, edata=0x2aaac5aad510) at InkAPI.cc:991
#37 0x00000000004d4d3d in Continuation::handleEvent (this=0x2aaab9d9b810,
event=60002, data=0x2aaac5aad510) at
../iocore/eventsystem/I_Continuation.h:146
#38 0x00000000004de875 in APIHook::invoke (this=0x2aaab1ff26f0,
event=60002, edata=0x2aaac5aad510) at InkAPI.cc:1210
#39 0x00000000005675e9 in HttpSM::state_api_callout (this=0x2aaac5aad510,
event=0, data=0x0) at HttpSM.cc:1379
#40 0x0000000000567b17 in HttpSM::do_api_callout_internal
(this=0x2aaac5aad510) at HttpSM.cc:4624
#41 0x0000000000572fef in HttpSM::do_api_callout (this=0x2aaac5aad510) at
HttpSM.cc:500
#42 0x000000000056ce12 in HttpSM::set_next_state (this=0x2aaac5aad510) at
HttpSM.cc:6643
#43 0x000000000055a25f in HttpSM::call_transact_and_set_next_state
(this=0x2aaac5aad510, f=0x599e9e
<HttpTransact::ModifyRequest(HttpTransact::State*)>)
    at HttpSM.cc:6609
#44 0x000000000056e81a in HttpSM::state_read_client_request_header
(this=0x2aaac5aad510, event=100, data=0x1b500510) at HttpSM.cc:783
#45 0x0000000000568178 in HttpSM::main_handler (this=0x2aaac5aad510,
event=100, data=0x1b500510) at HttpSM.cc:2444
#46 0x00000000004d4d3d in Continuation::handleEvent (this=0x2aaac5aad510,
event=100, data=0x1b500510) at ../iocore/eventsystem/I_Continuation.h:146
#47 0x00000000006b8451 in read_signal_and_update (event=100, vc=0x1b500400)
at UnixNetVConnection.cc:138
#48 0x00000000006b8bd0 in read_from_net (nh=0x2aaaaaf461e8, vc=0x1b500400,
thread=0x2aaaaaf43010) at UnixNetVConnection.cc:320
#49 0x00000000006b8ce1 in UnixNetVConnection::net_read_io (this=0x1b500400,
nh=0x2aaaaaf461e8, lthread=0x2aaaaaf43010) at UnixNetVConnection.cc:816
#50 0x00000000006b2dc4 in NetHandler::mainNetEvent (this=0x2aaaaaf461e8,
event=5, e=0x1b37b450) at UnixNet.cc:372
#51 0x00000000004d4d3d in Continuation::handleEvent (this=0x2aaaaaf461e8,
event=5, data=0x1b37b450) at ../iocore/eventsystem/I_Continuation.h:146
#52 0x00000000006d8ad1 in EThread::process_event (this=0x2aaaaaf43010,
e=0x1b37b450, calling_code=5) at UnixEThread.cc:142
#53 0x00000000006d8ff0 in EThread::execute (this=0x2aaaaaf43010) at
UnixEThread.cc:264
#54 0x00000000006d8483 in spawn_thread_internal (a=0x1b3418c0) at
Thread.cc:88
#55 0x0000003912e0673d in start_thread () from /lib64/libpthread.so.0
#56 0x00000039126d40cd in clone () from /lib64/libc.so.6

If you look into line 9 and 10, the data is passing successfully into the
api. But still it fails.
Also it doesn't fail for all the calls. The fail rate is like once in 1-2
hours.

Any suggestions.

Regards,
Manas Agarwal

Mime
View raw message