[TS-3289] UnixNetVConnection::mainEvent got an unexpected event: VC_EVENT_WRITE_COMPLETE

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Cannot Reproduce
• Component/s: HTTP
• Labels: crash

Environment

Fedora 21, ATS 5.1.2 compiled from source, curl 7.37.0. The original server is Jetty 9.2.

• Assignee: Unassigned
• Reporter: Changming Sun
• Created: 11/Jan/15 07:55
• Updated: 16/Aug/16 21:28
• Resolved: 16/Aug/16 21:28

Description

I was posting a file through curl

$ curl -d '@yjp201411140810.jar' http://127.0.0.1:18080/

But traffic_server crashed because UnixNetVConnection::mainEvent got an unexpected event: VC_EVENT_WRITE_COMPLETE.

FATAL: UnixNetVConnection.cc:1005: failed assert `event == EVENT_IMMEDIATE || event == EVENT_INTERVAL`

#0 0x00002ab43083c8c7 in __GI_raise (sig=sig@entry=6) at ../sysdeps/unix/sysv/linux/raise.c:55
#1 0x00002ab43083e52a in __GI_abort () at abort.c:89
#2 0x00002ab42daea1bd in ink_die_die_die (retval=1) at ink_error.cc:43
#3 0x00002ab42daea28c in ink_fatal_va(int, const char *, typedef __va_list_tag __va_list_tag *) (return_code=1,
fmt=0x2ab42daf7ad0 "%s:%d: failed assert `%s`", ap=0x7fff8de04f08) at ink_error.cc:67
#4 0x00002ab42daea32f in ink_fatal (return_code=1, message_format=0x2ab42daf7ad0 "%s:%d: failed assert `%s`")
at ink_error.cc:75
#5 0x00002ab42dae8c59 in _ink_assert (expression=0x7eebd8 "event == EVENT_IMMEDIATE || event == EVENT_INTERVAL",
file=0x7ee945 "UnixNetVConnection.cc", line=1005) at ink_assert.cc:37
#6 0x0000000000745676 in UnixNetVConnection::mainEvent (this=0x2ab4400008c0, event=103, e=0x2ab440000a48)
at UnixNetVConnection.cc:1005
#7 0x00000000004f8aba in Continuation::handleEvent (this=0x2ab4400008c0, event=103, data=0x2ab440000a48)
at ../iocore/eventsystem/I_Continuation.h:146
#8 0x00000000007427e8 in write_signal_and_update (event=103, vc=0x2ab4400008c0) at UnixNetVConnection.cc:152
#9 0x0000000000742918 in write_signal_done (event=103, nh=0x2ab43280db20, vc=0x2ab4400008c0)
at UnixNetVConnection.cc:179
#10 0x0000000000743986 in write_to_net_io (nh=0x2ab43280db20, vc=0x2ab4400008c0, thread=0x2ab43280a010)
at UnixNetVConnection.cc:489
#11 0x00000000007431b5 in write_to_net (nh=0x2ab43280db20, vc=0x2ab4400008c0, thread=0x2ab43280a010)
at UnixNetVConnection.cc:351
#12 0x000000000073c969 in NetHandler::mainNetEvent (this=0x2ab43280db20, event=5, e=0x253f730) at UnixNet.cc:415
#13 0x00000000004f8aba in Continuation::handleEvent (this=0x2ab43280db20, event=5, data=0x253f730)
at ../iocore/eventsystem/I_Continuation.h:146
#14 0x0000000000764777 in EThread::process_event (this=0x2ab43280a010, e=0x253f730, calling_code=5)
at UnixEThread.cc:144
#15 0x0000000000764d2c in EThread::execute (this=0x2ab43280a010) at UnixEThread.cc:268
#16 0x000000000052a3b1 in main (argv=0x7fff8de05598) at Main.cc:1690

ATS version:
Apache Traffic Server - traffic_server - 5.1.2 - (build # 01114 on Jan 11 2015 at 14:16:27)

I've changed the following lines in records.config
CONFIG proxy.config.http.server_ports STRING 18080
CONFIG proxy.config.diags.debug.enabled INT 0
CONFIG proxy.config.diags.debug.tags STRING http.|dns.
CONFIG proxy.config.log.logfile_dir STRING /var/log/trafficserver
CONFIG proxy.config.http.send_100_continue_response INT 1

And this is the remap.config: (only one line)
map / http://www.somerealhost.com/