[TS-2067] Core at LogBuffer::checkout_write()

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Duplicate
• Component/s: Logging
• Assignee: Unassigned
• Reporter: Yunkai Zhang
• Created: 26/Jul/13 03:30
• Updated: 09/Aug/13 14:34
• Resolved: 26/Jul/13 03:45

Description

NOTE: Traffic Server received Sig 11: Segmentation fault                                                                      /usr/bin/traffic_server - STACK TRACE:
/lib64/libpthread.so.0[0x3c7560f4a0]                                                                                          /usr/bin/traffic_server(_ZN9LogBuffer14checkout_writeEPmm+0x220)[0x5f0384]
/usr/bin/traffic_server(_ZN9LogObject15_checkout_writeEPmm+0xd7)[0x60bc3f]                                                    /usr/bin/traffic_server(_ZN9LogObject3logEP9LogAccessPc+0x4dc)[0x60c43e]
/usr/bin/traffic_server(_ZN16LogObjectManager3logEP9LogAccess+0x4a)[0x5f761a]                                                 /usr/bin/traffic_server(_ZN3Log6accessEP9LogAccess+0x1f5)[0x5f6379]
/usr/bin/traffic_server(_ZN6HttpSM12update_statsEv+0x204)[0x58506e]
/usr/bin/traffic_server(_ZN6HttpSM9kill_thisEv+0x2f7)[0x584d21]
/usr/bin/traffic_server(_ZN6HttpSM12main_handlerEiPv+0x260)[0x5798ea]
/usr/bin/traffic_server(_ZN12Continuation11handleEventEiPv+0x6c)[0x4f1f1e]
/usr/bin/traffic_server(_ZN10HttpTunnel12main_handlerEiPv+0xfd)[0x5bc819]
/usr/bin/traffic_server(_ZN12Continuation11handleEventEiPv+0x6c)[0x4f1f1e]
/usr/bin/traffic_server[0x6ee000]
/usr/bin/traffic_server[0x6ee0f9]
/usr/bin/traffic_server(_Z15write_to_net_ioP10NetHandlerP18UnixNetVConnectionP7EThread+0x63a)[0x6eef0a]
/usr/bin/traffic_server(_Z12write_to_netP10NetHandlerP18UnixNetVConnectionP14PollDescriptorP7EThread+0x84)[0x6ee8c9]
/usr/bin/traffic_server(_ZN10NetHandler12mainNetEventEiP5Event+0x660)[0x6eae36]
/usr/bin/traffic_server(_ZN12Continuation11handleEventEiPv+0x6c)[0x4f1f1e]
/usr/bin/traffic_server(_ZN7EThread13process_eventEP5Eventi+0xc8)[0x70f3d2]
/usr/bin/traffic_server(_ZN7EThread7executeEv+0x3ef)[0x70f8ef]
/usr/bin/traffic_server[0x70e62f]
/lib64/libpthread.so.0[0x3c756077f1]
/lib64/libc.so.6(clone+0x6d)[0x3c752e570d]

Some gdb info:

(gdb) bt
#0  0x00000000005f0384 in LogBuffer::checkout_write (this=0x2aeb30002ff0, write_offset=0x2aeb09f5f4e8, write_size=152)
    at LogBuffer.cc:287
#1  0x000000000060bc3f in LogObject::_checkout_write (this=0x1ed8800, write_offset=0x2aeb09f5f4e8, bytes_needed=152)
    at LogObject.cc:424
#2  0x000000000060c43e in LogObject::log (this=0x1ed8800, lad=0x2aeb09f5f7a0, text_entry=0x0) at LogObject.cc:579
#3  0x00000000005f761a in LogObjectManager::log (this=0x1eaf400, lad=0x2aeb09f5f7a0) at LogObject.h:395
#4  0x00000000005f6379 in Log::access (lad=0x2aeb09f5f7a0) at Log.cc:1117
#5  0x000000000058506e in HttpSM::update_stats (this=0x2aeb41379f10) at HttpSM.cc:6312
#6  0x0000000000584d21 in HttpSM::kill_this (this=0x2aeb41379f10) at HttpSM.cc:6273
#7  0x00000000005798ea in HttpSM::main_handler (this=0x2aeb41379f10, event=2301, data=0x2aeb4137ba70) at HttpSM.cc:2593
#8  0x00000000004f1f1e in Continuation::handleEvent (this=0x2aeb41379f10, event=2301, data=0x2aeb4137ba70)
    at ../iocore/eventsystem/I_Continuation.h:146
#9  0x00000000005bc819 in HttpTunnel::main_handler (this=0x2aeb4137ba70, event=103, data=0x2aebb000f050)
    at HttpTunnel.cc:1476
#10 0x00000000004f1f1e in Continuation::handleEvent (this=0x2aeb4137ba70, event=103, data=0x2aebb000f050)
    at ../iocore/eventsystem/I_Continuation.h:146
#11 0x00000000006ee000 in write_signal_and_update (event=103, vc=0x2aebb000eee0) at UnixNetVConnection.cc:153
#12 0x00000000006ee0f9 in write_signal_done (event=103, nh=0x2aeb08d542a8, vc=0x2aebb000eee0) at UnixNetVConnection.cc:180
#13 0x00000000006eef0a in write_to_net_io (nh=0x2aeb08d542a8, vc=0x2aebb000eee0, thread=0x2aeb08d51010)
    at UnixNetVConnection.cc:479
#14 0x00000000006ee8c9 in write_to_net (nh=0x2aeb08d542a8, vc=0x2aebb000eee0, pd=0x2aeb1466b010, thread=0x2aeb08d51010)
    at UnixNetVConnection.cc:352
#15 0x00000000006eae36 in NetHandler::mainNetEvent (this=0x2aeb08d542a8, event=5, e=0x2aeb0a6746e0) at UnixNet.cc:394
#16 0x00000000004f1f1e in Continuation::handleEvent (this=0x2aeb08d542a8, event=5, data=0x2aeb0a6746e0)
    at ../iocore/eventsystem/I_Continuation.h:146
#17 0x000000000070f3d2 in EThread::process_event (this=0x2aeb08d51010, e=0x2aeb0a6746e0, calling_code=5)
    at UnixEThread.cc:142
#18 0x000000000070f8ef in EThread::execute (this=0x2aeb08d51010) at UnixEThread.cc:266
#19 0x000000000070e62f in spawn_thread_internal (a=0x1b3f520) at Thread.cc:88
#20 0x0000003c756077f1 in start_thread () from /lib64/libpthread.so.0
#21 0x0000003c752e570d in clone () from /lib64/libc.so.6
(gdb) f 0
#0  0x00000000005f0384 in LogBuffer::checkout_write (this=0x2aeb30002ff0, write_offset=0x2aeb09f5f4e8, write_size=152)
    at LogBuffer.cc:287
287         entry_header->timestamp = tp.tv_sec;
(gdb) p entry_header 
$7 = (LogEntryHeader *) 0x0
(gdb) p old_s
$8 = {ival = 0, s = {offset = 0, num_entries = 0, byte_count = 0, full = 0, num_writers = 0}}
(gdb) p new_s
$9 = {ival = 563671507992744, s = {offset = 168, num_entries = 1, byte_count = 168, full = 0, num_writers = 1}}
(gdb) p m_buffer
$10 = 0x2aeb30005000 "\316\372\316\n\002"
(gdb) p *this
$11 = {write_link = {next = 0x0}, link = {<SLink<LogBuffer>> = {next = 0x0}, prev = 0x0}, static M_ID = 57301638, 
  m_unaligned_buffer = 0x2aeb30004fa0 "\350\a", m_buffer = 0x2aeb30005000 "\316\372\316\n\002", m_size = 18432, 
  m_buf_align = 512, m_write_align = 8, m_state = {ival = 923143098288024, s = {offset = 18328, num_entries = 115, 
      byte_count = 18328, full = 1, num_writers = 1}}, m_max_entries = 200, m_expiration_time = 1374692424, 
  m_owner = 0x1ed8800, m_header = 0x2aeb30005000, m_id = 57301630, m_references = 1}

Issue Links

• is duplicated by: TS-1330 Logging related segfault in 3.2.0