[2.1.2-scala] Oops, unexpected message received in NettyServer (please report this problem)

[Michael Allman]

Hello,

I'm attempting to upgrade our play app's codebase from 2.1.1 to 2.1.2. The first thing I tried was posting a json doc to one of our endpoints with curl. Unfortunately, the request just hangs. In the application log file, I see three messages:

2013-07-12 19:00:12,212 - [ERROR] - from play in New I/O worker #1 

Oops, unexpected message received in NettyServer (please report this problem): org.jboss.netty.handler.codec.http.DefaultHttpChunk@5cdee293

2013-07-12 19:00:12,212 - [ERROR] - from play in New I/O worker #1 

Oops, unexpected message received in NettyServer (please report this problem): org.jboss.netty.handler.codec.http.DefaultHttpChunk@3e91432c

2013-07-12 19:00:12,212 - [ERROR] - from play in New I/O worker #1 

Oops, unexpected message received in NettyServer (please report this problem): org.jboss.netty.handler.codec.http.HttpChunk$1@32d11dcb

If I ctrl-c the first curl request, subsequent requests succeed seemingly without error. However, every time I restart the play server, the first curl request I make hangs. This happens if I start with "play run" or "play start". Any ideas on where to go from here?

Thank you.

Michael

[ibsdevs]

Same here: 

[error] play - Oops, unexpected message received in NettyServer (please report this problem): org.jboss.netty.handler.codec.http.DefaultHttpChunk@3723ae83

Controller method signature is as follows:

def uploadData(modelid: String, importid: Long) = BasicAuth(parse.temporaryFile)(Administrator){ ... }

Any ideas?

[James Roper]

Could you rerun your curl command with "--trace -"?  This should cause curl to dump everything going over the wire, and let us see what's going on.

--
You received this message because you are subscribed to the Google Groups "play-framework" group.
To unsubscribe from this group and stop receiving emails from it, send an email to play-framewor...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.
 
 

--

James Roper
Software Engineer

Typesafe – Build reactive apps!
Twitter: @jroper

[Michael Allman]

Here's the output:

== Info: About to connect() to localhost port 9000 (#0)
== Info: Trying ::1...
== Info: Connected to localhost (::1) port 9000 (#0)
=> Send header, 158 bytes (0x9e)
0000: 50 4f 53 54 20 2f 62 69 64 20 48 54 54 50 2f 31 POST /bid HTTP/1
0010: 2e 31 0d 0a 55 73 65 72 2d 41 67 65 6e 74 3a 20 .1..User-Agent:
0020: 63 75 72 6c 2f 37 2e 32 39 2e 30 0d 0a 48 6f 73 curl/7.29.0..Hos
0030: 74 3a 20 6c 6f 63 61 6c 68 6f 73 74 3a 39 30 30 t: localhost:900
0040: 30 0d 0a 41 63 63 65 70 74 3a 20 2a 2f 2a 0d 0a 0..Accept: */*..
0050: 43 6f 6e 74 65 6e 74 2d 54 79 70 65 3a 20 61 70 Content-Type: ap
0060: 70 6c 69 63 61 74 69 6f 6e 2f 6a 73 6f 6e 0d 0a plication/json..
0070: 43 6f 6e 74 65 6e 74 2d 4c 65 6e 67 74 68 3a 20 Content-Length:
0080: 32 31 39 34 0d 0a 45 78 70 65 63 74 3a 20 31 30 2194..Expect: 10
0090: 30 2d 63 6f 6e 74 69 6e 75 65 0d 0a 0d 0a 0-continue....
== Info: Done waiting for 100-continue
=> Send data, 2194 bytes (0x892)
0000: 7b 20 20 22 69 64 22 3a 22 35 39 65 31 32 64 62 { "id":"59e12db
0010: 63 2d 32 63 32 33 2d 34 30 35 37 2d 38 35 31 62 c-2c23-4057-851b
0020: 2d 31 39 63 37 63 35 36 35 39 32 33 61 22 2c 20 -19c7c565923a",
0030: 20 22 75 73 65 72 22 3a 7b 20 20 20 20 22 64 6e "user":{ "dn
0040: 74 22 3a 30 2c 20 20 20 20 22 75 69 64 22 3a 22 t":0, "uid":"
0050: 30 38 46 43 46 39 32 33 31 34 2d 45 45 30 36 2d 08FCF92314-EE06-
0060: 34 41 39 46 2d 42 46 36 39 2d 36 32 36 39 43 32 4A9F-BF69-6269C2
0070: 45 46 43 38 35 37 22 20 20 7d 2c 20 20 22 74 6d EFC857" }, "tm
0080: 61 78 22 3a 33 30 30 2c 20 20 22 72 65 73 74 72 ax":300, "restr
0090: 69 63 74 69 6f 6e 73 22 3a 7b 20 20 20 20 22 62 ictions":{ "b
00a0: 61 64 76 22 3a 5b 20 20 20 20 20 20 22 61 70 70 adv":[ "app
00b0: 6c 65 2e 63 6f 6d 22 2c 20 20 20 20 20 20 22 67 le.com", "g
00c0: 6f 2d 74 65 78 74 2e 6d 65 2f 22 2c 20 20 20 20 o-text.me/",
00d0: 20 20 22 68 65 79 77 69 72 65 2e 63 6f 6d 2f 22 "heywire.com/"
00e0: 2c 20 20 20 20 20 20 22 68 74 74 70 3a 2f 2f 64 , "http://d
00f0: 72 61 77 62 72 69 64 2e 67 65 2f 22 2c 20 20 20 rawbrid.ge/",
0100: 20 20 20 22 68 74 74 70 3a 2f 2f 69 74 75 6e 65 "http://itune
0110: 73 2e 61 70 70 6c 65 2e 63 6f 6d 2f 75 73 2f 61 s.apple.com/us/a
0120: 70 70 2f 74 65 78 74 2d 6d 65 21 2d 32 2d 66 72 pp/text-me!-2-fr
0130: 65 65 2d 74 65 78 74 69 6e 67 2d 76 6f 69 63 65 ee-texting-voice
0140: 2f 69 64 35 31 34 34 38 35 39 36 34 3f 6d 74 3d /id514485964?mt=
0150: 38 22 2c 20 20 20 20 20 20 22 68 74 74 70 3a 2f 8", "http:/
0160: 2f 69 74 75 6e 65 73 2e 61 70 70 6c 65 2e 63 6f /itunes.apple.co
0170: 6d 2f 75 73 2f 61 70 70 2f 74 65 78 74 2d 6d 65 m/us/app/text-me
0180: 21 2d 66 72 65 65 2d 74 65 78 74 69 6e 67 2d 73 !-free-texting-s
0190: 6d 73 2f 69 64 33 33 38 30 38 38 34 33 32 3f 6d ms/id338088432?m
01a0: 74 3d 38 22 2c 20 20 20 20 20 20 22 68 74 74 70 t=8", "http
01b0: 73 3a 2f 2f 70 6c 61 79 2e 67 6f 6f 67 6c 65 2e s://play.google.
01c0: 63 6f 6d 2f 73 74 6f 72 65 2f 61 70 70 73 2f 64 com/store/apps/d
01d0: 65 74 61 69 6c 73 3f 69 64 3d 63 6f 6d 2e 74 65 etails?id=com.te
01e0: 78 74 6d 65 69 6e 63 2e 74 65 78 74 6d 65 26 68 xtmeinc.textme&h
01f0: 6c 3d 65 6e 22 2c 20 20 20 20 20 20 22 69 6e 66 l=en", "inf
0200: 6f 2e 74 69 6b 6c 2e 6d 6f 62 69 22 2c 20 20 20 o.tikl.mobi",
0210: 20 20 20 22 69 74 75 6e 65 73 2e 61 70 70 6c 65 "itunes.apple
0220: 2e 63 6f 6d 2f 75 73 2f 61 70 70 2f 74 65 78 74 .com/us/app/text
0230: 6e 66 6c 79 2f 69 64 35 35 36 39 36 35 32 34 37 nfly/id556965247
0240: 3f 6d 74 3d 38 22 2c 20 20 20 20 20 20 22 6c 69 ?mt=8", "li
0250: 6e 65 2e 6e 61 76 65 72 2e 6a 70 2f 65 6e 2f 22 ne.naver.jp/en/"
0260: 2c 20 20 20 20 20 20 22 6c 69 6e 65 32 2e 63 6f , "line2.co
0270: 6d 22 2c 20 20 20 20 20 20 22 6d 65 73 73 65 6e m", "messen
0280: 67 65 72 2e 79 61 68 6f 6f 2e 63 6f 6d 2f 22 2c ger.yahoo.com/",
0290: 20 20 20 20 20 20 22 74 65 78 74 69 65 2e 6d 65 "textie.me
02a0: 2f 22 2c 20 20 20 20 20 20 22 77 65 62 2e 73 61 /", "web.sa
02b0: 6d 73 75 6e 67 63 68 61 74 6f 6e 2e 63 6f 6d 2f msungchaton.com/
02c0: 22 2c 20 20 20 20 20 20 22 77 77 77 2e 61 69 6d ", "www.aim
02d0: 2e 63 6f 6d 2f 22 2c 20 20 20 20 20 20 22 77 77 .com/", "ww
02e0: 77 2e 63 68 61 74 6f 6e 2e 63 6f 6d 2f 22 2c 20 w.chaton.com/",
02f0: 20 20 20 20 20 22 77 77 77 2e 66 61 63 65 62 6f "www.facebo
0300: 6f 6b 2e 63 6f 6d 2f 67 6f 63 68 61 74 22 2c 20 ok.com/gochat",
0310: 20 20 20 20 20 22 77 77 77 2e 66 61 63 65 62 6f "www.facebo
0320: 6f 6b 2e 63 6f 6d 2f 6d 6f 62 69 6c 65 2f 6d 65 ok.com/mobile/me
0330: 73 73 65 6e 67 65 72 22 2c 20 20 20 20 20 20 22 ssenger", "
0340: 77 77 77 2e 67 6f 67 69 69 67 61 6d 65 73 2e 63 www.gogiigames.c
0350: 6f 6d 22 2c 20 20 20 20 20 20 22 77 77 77 2e 69 om", "www.i
0360: 6e 64 6f 6f 6e 61 2e 63 6f 6d 2f 65 6e 2f 22 2c ndoona.com/en/",
0370: 20 20 20 20 20 20 22 77 77 77 2e 69 6e 66 69 6e "www.infin
0380: 69 74 65 73 6d 73 61 70 70 2e 63 6f 6d 2f 22 2c itesmsapp.com/",
0390: 20 20 20 20 20 20 22 77 77 77 2e 6a 61 63 6b 61 "www.jacka
03a0: 6e 64 6a 69 6c 6c 2e 63 6f 6d 22 2c 20 20 20 20 ndjill.com",
03b0: 20 20 22 77 77 77 2e 6b 69 6b 2e 63 6f 6d 22 2c "www.kik.com",
03c0: 20 20 20 20 20 20 22 77 77 77 2e 6d 6f 63 6f 73 "www.mocos
03d0: 70 61 63 65 2e 63 6f 6d 2f 22 2c 20 20 20 20 20 pace.com/",
03e0: 20 22 77 77 77 2e 6f 6f 76 6f 6f 2e 63 6f 6d 22 "www.oovoo.com"
03f0: 2c 20 20 20 20 20 20 22 77 77 77 2e 70 69 6e 67 , "www.ping
0400: 65 72 2e 63 6f 6d 22 2c 20 20 20 20 20 20 22 77 er.com", "w
0410: 77 77 2e 73 6b 79 70 65 2e 63 6f 6d 22 2c 20 20 ww.skype.com",
0420: 20 20 20 20 22 77 77 77 2e 74 61 6c 6b 61 74 6f "www.talkato
0430: 6e 65 2e 63 6f 6d 2f 22 2c 20 20 20 20 20 20 22 ne.com/", "
0440: 77 77 77 2e 74 61 6c 6b 66 72 65 65 2e 63 6f 6d www.talkfree.com
0450: 22 2c 20 20 20 20 20 20 22 77 77 77 2e 74 61 6e ", "www.tan
0460: 67 6f 2e 6d 65 22 2c 20 20 20 20 20 20 22 77 77 go.me", "ww
0470: 77 2e 74 65 78 74 70 6c 75 73 2e 63 6f 6d 22 2c w.textplus.com",
0480: 20 20 20 20 20 20 22 77 77 77 2e 76 69 62 65 72 "www.viber
0490: 2e 63 6f 6d 22 2c 20 20 20 20 20 20 22 77 77 77 .com", "www
04a0: 2e 76 6f 6e 61 67 65 2e 63 6f 6d 22 2c 20 20 20 .vonage.com",
04b0: 20 20 20 22 77 77 77 2e 76 6f 78 65 72 2e 63 6f "www.voxer.co
04c0: 6d 22 2c 20 20 20 20 20 20 22 77 77 77 2e 76 74 m", "www.vt
04d0: 6f 6b 61 70 70 2e 63 6f 6d 2f 22 2c 20 20 20 20 okapp.com/",
04e0: 20 20 22 77 77 77 2e 77 65 63 68 61 74 61 70 70 "www.wechatapp
04f0: 2e 63 6f 6d 2f 22 2c 20 20 20 20 20 20 22 77 77 .com/", "ww
0500: 77 2e 77 68 61 74 73 61 70 70 2e 63 6f 6d 22 20 w.whatsapp.com"
0510: 20 20 20 5d 20 20 7d 2c 20 20 22 70 66 22 3a 30 ] }, "pf":0
0520: 2e 30 30 30 30 31 30 2c 20 20 22 69 6d 70 22 3a .000010, "imp":
0530: 5b 20 20 20 20 7b 20 20 20 20 20 20 22 77 22 3a [ { "w":
0540: 37 32 38 2c 20 20 20 20 20 20 22 69 6e 73 74 6c 728, "instl
0550: 22 3a 30 2c 20 20 20 20 20 20 22 64 69 73 70 6c ":0, "displ
0560: 61 79 6d 61 6e 61 67 65 72 76 65 72 22 3a 22 31 aymanagerver":"1
0570: 2e 39 2e 30 2e 31 31 22 2c 20 20 20 20 20 20 22 .9.0.11", "
0580: 69 6d 70 69 64 22 3a 22 41 39 65 31 32 64 62 63 impid":"A9e12dbc
0590: 2d 32 63 32 33 2d 34 30 35 37 2d 38 35 31 62 2d -2c23-4057-851b-
05a0: 31 39 63 37 63 35 36 35 39 32 33 61 22 2c 20 20 19c7c565923a",
05b0: 20 20 20 20 22 68 22 3a 39 30 2c 20 20 20 20 20 "h":90,
05c0: 20 22 61 70 69 22 3a 33 2c 20 20 20 20 20 20 22 "api":3, "
05d0: 62 61 74 74 72 22 3a 5b 20 20 20 20 20 20 20 20 battr":[
05e0: 22 39 22 2c 20 20 20 20 20 20 20 20 22 31 30 22 "9", "10"
05f0: 2c 20 20 20 20 20 20 20 20 22 31 34 22 20 20 20 , "14"
0600: 20 20 20 5d 20 20 20 20 7d 20 20 5d 2c 20 20 22 ] } ], "
0610: 61 70 70 22 3a 7b 20 20 20 20 22 70 75 62 22 3a app":{ "pub":
0620: 22 45 6e 66 6c 69 63 6b 2c 20 49 6e 63 2e 22 2c "Enflick, Inc.",
0630: 20 20 20 20 22 63 61 74 22 3a 5b 20 20 20 20 20 "cat":[
0640: 20 22 73 6f 63 69 61 6c 5f 6e 65 74 77 6f 72 6b "social_network
0650: 69 6e 67 22 2c 20 20 20 20 20 20 22 75 74 69 6c ing", "util
0660: 69 74 69 65 73 22 20 20 20 20 5d 2c 20 20 20 20 ities" ],
0670: 22 67 6c 6f 62 61 6c 5f 61 69 64 22 3a 22 33 37 "global_aid":"37
0680: 31 37 36 39 36 37 37 22 2c 20 20 20 20 22 70 61 1769677", "pa
0690: 69 64 22 3a 30 2c 20 20 20 20 22 61 69 64 22 3a id":0, "aid":
06a0: 22 61 67 6c 74 62 33 62 31 79 69 31 70 62 6d 6e "agltb3b1yi1pbmn
06b0: 79 64 61 73 73 61 30 66 77 63 62 6a 73 36 37 38 ydassa0fwcbjs678
06c0: 75 64 61 22 2c 20 20 20 20 22 6e 61 6d 65 22 3a uda", "name":
06d0: 22 54 6f 75 63 68 e2 84 a2 22 2c 20 20 20 20 22 "Touch...", "
06e0: 70 69 64 22 3a 22 30 30 34 37 64 31 61 35 62 63 pid":"0047d1a5bc
06f0: 35 65 33 33 31 31 65 32 38 31 63 31 31 32 33 31 5e3311e281c11231
0700: 33 39 32 35 35 39 65 34 22 20 20 7d 2c 20 20 22 392559e4" }, "
0710: 64 65 76 69 63 65 22 3a 7b 20 20 20 20 22 6a 73 device":{ "js
0720: 22 3a 31 2c 20 20 20 20 22 63 69 74 79 22 3a 22 ":1, "city":"
0730: 43 6f 6c 62 6f 72 6e 65 22 2c 20 20 20 20 22 63 Colborne", "c
0740: 61 72 72 69 65 72 22 3a 22 57 69 2d 46 69 22 2c arrier":"Wi-Fi",
0750: 20 20 20 20 22 6d 6f 64 65 6c 22 3a 22 69 50 61 "model":"iPa
0760: 64 22 2c 20 20 20 20 22 64 70 69 64 22 3a 22 42 d", "dpid":"B
0770: 43 46 39 32 33 31 34 2d 45 45 30 36 2d 34 41 39 CF92314-EE06-4A9
0780: 46 2d 42 46 36 39 2d 36 32 36 39 43 32 45 46 43 F-BF69-6269C2EFC
0790: 38 35 37 22 2c 20 20 20 20 22 6d 61 6b 65 22 3a 857", "make":
07a0: 22 41 70 70 6c 65 22 2c 20 20 20 20 22 63 6f 75 "Apple", "cou
07b0: 6e 74 72 79 22 3a 22 55 53 41 22 2c 20 20 20 20 ntry":"USA",
07c0: 22 69 70 22 3a 22 32 31 33 2e 32 30 35 2e 32 33 "ip":"213.205.23
07d0: 34 2e 33 35 22 2c 20 20 20 20 22 73 74 61 74 65 4.35", "state
07e0: 22 3a 22 48 39 22 2c 20 20 20 20 22 75 61 22 3a ":"H9", "ua":
07f0: 22 4d 6f 7a 69 6c 6c 61 2f 35 2e 30 20 28 69 50 "Mozilla/5.0 (iP
0800: 68 6f 6e 65 3b 20 43 50 55 20 69 50 68 6f 6e 65 hone; CPU iPhone
0810: 20 4f 53 20 36 5f 31 5f 33 20 6c 69 6b 65 20 4d OS 6_1_3 like M
0820: 61 63 20 4f 53 20 58 29 20 41 70 70 6c 65 57 65 ac OS X) AppleWe
0830: 62 4b 69 74 2f 35 33 36 2e 32 36 20 28 4b 48 54 bKit/536.26 (KHT
0840: 4d 4c 2c 20 6c 69 6b 65 20 47 65 63 6b 6f 29 20 ML, like Gecko)
0850: 4d 6f 62 69 6c 65 2f 31 30 42 33 32 39 22 2c 20 Mobile/10B329",
0860: 20 20 20 22 6f 73 76 22 3a 22 36 2e 31 2e 33 22 "osv":"6.1.3"
0870: 2c 20 20 20 20 22 6f 73 22 3a 22 69 50 68 6f 6e , "os":"iPhon
0880: 65 20 4f 53 22 20 20 7d 2c 20 20 22 61 74 22 3a e OS" }, "at":
0890: 32 7d 2}
<= Recv header, 23 bytes (0x17)
0000: 48 54 54 50 2f 31 2e 31 20 31 30 30 20 43 6f 6e HTTP/1.1 100 Con
0010: 74 69 6e 75 65 0d 0a tinue..

> You received this message because you are subscribed to a topic in the Google Groups "play-framework" group.
> To unsubscribe from this topic, visit https://groups.google.com/d/topic/play-framework/PgFfqkT0Zgg/unsubscribe.
> To unsubscribe from this group and all its topics, send an email to play-framewor...@googlegroups.com.

[James Roper]

Does it pause at all before it outputs "== Info: Done waiting for 100-continue"?  It looks like curl's not waiting long enough for the 100 continue response, because Play sends it eventually.

[James Roper]

I've had a careful look and found a race condition in our request body handling.  We will fix this and release 2.1.3 soon.

[James Roper]

By the way, this only happens when using Expect: 100-continue.  The main client that does this is curl, no browsers use it.  And the time that the race condition can happen is very small, it's only the first request to Play, when things are being compiled/jitted etc that is likely to trigger it.

[Michael Allman]

Thanks for helping with this, James. I'm glad I understand the triggering conditions now.

Michael

[ibsdevs]

Our file upload works with play 2.1.1.

2.1.2 returns the mentioned error.

It only uploads a file using the parse.temporaryFile (body parser).

Any ideas about the reason that a simple upload doesn't work with 2.1.2.