{"action":"created","issue":{"url":"https://api.github.com/repos/grpc/grpc/issues/6422","repository_url":"https://api.github.com/repos/grpc/grpc","labels_url":"https://api.github.com/repos/grpc/grpc/issues/6422/labels{/name}","comments_url":"https://api.github.com/repos/grpc/grpc/issues/6422/comments","events_url":"https://api.github.com/repos/grpc/grpc/issues/6422/events","html_url":"https://github.com/grpc/grpc/issues/6422","id":152934719,"number":6422,"title":"bins/opt/h2_full+trace_test disappearing_server","user":{"login":"dgquintas","id":120217,"avatar_url":"https://avatars.githubusercontent.com/u/120217?v=3","gravatar_id":"","url":"https://api.github.com/users/dgquintas","html_url":"https://github.com/dgquintas","followers_url":"https://api.github.com/users/dgquintas/followers","following_url":"https://api.github.com/users/dgquintas/following{/other_user}","gists_url":"https://api.github.com/users/dgquintas/gists{/gist_id}","starred_url":"https://api.github.com/users/dgquintas/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/dgquintas/subscriptions","organizations_url":"https://api.github.com/users/dgquintas/orgs","repos_url":"https://api.github.com/users/dgquintas/repos","events_url":"https://api.github.com/users/dgquintas/events{/privacy}","received_events_url":"https://api.github.com/users/dgquintas/received_events","type":"User","site_admin":false},"labels":[{"url":"https://api.github.com/repos/grpc/grpc/labels/core","name":"core","color":"006b75"},{"url":"https://api.github.com/repos/grpc/grpc/labels/flaky%20test","name":"flaky test","color":"eb6420"}],"state":"open","locked":false,"assignee":null,"milestone":null,"comments":1,"created_at":"2016-05-04T05:46:38Z","updated_at":"2016-05-04T06:57:37Z","closed_at":null,"body":"```\r\nD0504 05:28:19.663038300   18071 test_config.c:278]          test slowdown: machine=1.000000 build=1.000000 total=1.000000\r\nE0504 05:28:19.663195088   18071 trace.c:131]                Unknown trace var: 'doesnt-exist'\r\nI0504 05:28:19.663207799   18071 init.c:187]                 grpc_init(void)\r\nE0504 05:28:19.663212586   18071 trace.c:131]                Unknown trace var: 'also-doesnt-exist'\r\nI0504 05:28:19.663218758   18071 init.c:187]                 grpc_init(void)\r\nD0504 05:28:19.666894446   18073 tcp_client_posix.c:290]     CLIENT_CONNECT: ipv6:[::1]:32767: asynchronously connecting\r\nD0504 05:28:19.666925905   18073 tcp_client_posix.c:126]     CLIENT_CONNECT: ipv6:[::1]:32767: on_writable: success=1\r\nE0504 05:28:19.666932865   18073 tcp_client_posix.c:173]     failed to connect to 'ipv6:[::1]:32767': socket error: connection refused\r\nD0504 05:28:19.666953863   18073 tcp_client_posix.c:99]      CLIENT_CONNECT: ipv6:[::1]:32767: on_alarm: success=0\r\nD0504 05:28:19.667057269   18073 tcp_client_posix.c:290]     CLIENT_CONNECT: ipv4:127.0.0.1:32767: asynchronously connecting\r\nD0504 05:28:19.667085681   18073 tcp_client_posix.c:126]     CLIENT_CONNECT: ipv4:127.0.0.1:32767: on_writable: success=1\r\nD0504 05:28:19.667096754   18073 tcp_client_posix.c:99]      CLIENT_CONNECT: ipv4:127.0.0.1:32767: on_alarm: success=0\r\nD0504 05:28:19.667118916   18073 tcp_posix.c:401]            WRITE 0x7f61bc001cb0: 47 45 54 20 2f 67 65 74 20 48 54 54 50 2f 31 2e 30 0d 0a 48 6f 73 74 3a 20 6c 6f 63 61 6c 68 6f 73 74 3a 33 32 37 36 37 0d 0a 43 6f 6e 6e 65 63 74 69 6f 6e 3a 20 63 6c 6f 73 65 0d 0a 55 73 65 72 2d 41 67 65 6e 74 3a 20 67 72 70 63 2d 68 74 74 70 63 6c 69 2f 30 2e 30 0d 0a 0d 0a 'GET /get HTTP/1.0..Host: localhost:32767..Connection: close..User-Agent: grpc-httpcli/0.0....'\r\nD0504 05:28:19.667769483   18071 tcp_posix.c:163]            read: success=1\r\nD0504 05:28:19.667794001   18071 tcp_posix.c:167]            READ 0x7f61bc001cb0: 48 54 54 50 2f 31 2e 30 20 32 30 30 20 4f 4b 0d 0a 53 65 72 76 65 72 3a 20 42 61 73 65 48 54 54 50 2f 30 2e 33 20 50 79 74 68 6f 6e 2f 32 2e 37 2e 39 0d 0a 44 61 74 65 3a 20 57 65 64 2c 20 30 34 20 4d 61 79 20 32 30 31 36 20 30 35 3a 32 38 3a 31 39 20 47 4d 54 0d 0a 43 6f 6e 74 65 6e 74 2d 54 79 70 65 3a 20 74 65 78 74 2f 70 6c 61 69 6e 0d 0a 0d 0a 31 31 31 36 'HTTP/1.0 200 OK..Server: BaseHTTP/0.3 Python/2.7.9..Date: Wed, 04 May 2016 05:28:19 GMT..Content-Type: text/plain....1116'\r\nD0504 05:28:19.667808650   18071 tcp_posix.c:163]            read: success=0\r\nI0504 05:28:19.667856115   18071 init.c:192]                 grpc_shutdown(void)\r\nI0504 05:28:19.667873070   18071 completion_queue.c:124]     grpc_completion_queue_create(reserved=(nil))\r\nI0504 05:28:19.667882546   18071 disappearing_server.c:194]  disappearing_server_test/chttp2/fullstack\r\nI0504 05:28:19.667891194   18071 channel_create.c:219]       grpc_insecure_channel_create(target=0x1d67150, args=(nil), reserved=(nil))\r\nI0504 05:28:19.667917482   18071 server.c:920]               grpc_server_create((nil), (nil))\r\nI0504 05:28:19.668685796   18071 server.c:904]               grpc_server_register_completion_queue(server=0x1d689f0, cq=0x1d68490, reserved=(nil))\r\nI0504 05:28:19.668703555   18071 server_chttp2.c:95]         grpc_server_add_insecure_http2_port(server=0x1d689f0, addr=localhost:1116)\r\nI0504 05:28:19.669062888   18071 server.c:1004]              grpc_server_start(server=0x1d689f0)\r\nI0504 05:28:19.669093412   18071 channel.c:201]              grpc_channel_create_call(channel=0x1d68690, parent_call=(nil), propagation_mask=ffff, cq=0x1d68490, method=/foo, host=foo.test.google.fr:1234, deadline=gpr_timespec { tv_sec: 4414607, tv_nsec: 306717950, clock_type: 0 }, reserved=(nil))\r\nI0504 05:28:19.669116295   18071 metadata_array.c:42]        grpc_metadata_array_init(array=0x7ffc41d94130)\r\nI0504 05:28:19.669123257   18071 metadata_array.c:42]        grpc_metadata_array_init(array=0x7ffc41d94150)\r\nI0504 05:28:19.669129704   18071 metadata_array.c:42]        grpc_metadata_array_init(array=0x7ffc41d94170)\r\nI0504 05:28:19.669136367   18071 call_details.c:42]          grpc_call_details_init(cd=0x7ffc41d94190)\r\nI0504 05:28:19.669151179   18071 call.c:1478]                grpc_call_start_batch(call=0x1d69970, ops=0x7ffc41d941d0, nops=4, tag=0x1, reserved=(nil))\r\nI0504 05:28:19.669160051   18071 call.c:1190]                ops[0]: SEND_INITIAL_METADATA\r\nI0504 05:28:19.669166388   18071 call.c:1190]                ops[1]: SEND_CLOSE_FROM_CLIENT\r\nI0504 05:28:19.669173071   18071 call.c:1190]                ops[2]: RECV_INITIAL_METADATA ptr=0x7ffc41d94130\r\nI0504 05:28:19.669181588   18071 call.c:1190]                ops[3]: RECV_STATUS_ON_CLIENT metadata=0x7ffc41d94150 status=0x7ffc41d94110 details=0x7ffc41d94120\r\nI0504 05:28:19.669207150   18071 client_channel.c:109]       OP[client-channel:0x1d69ed0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 66 6f 6f '/foo', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip' deadline=4414607.306717950} SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_TRAILING_METADATA\r\nI0504 05:28:19.669249621   18071 server.c:1290]              grpc_server_request_call(server=0x1d689f0, call=0x7ffc41d94118, details=0x7ffc41d94190, initial_metadata=0x7ffc41d94170, cq_bound_to_call=0x1d68490, cq_for_notification=0x1d68490, tag=0x65)\r\nI0504 05:28:19.669266824   18071 completion_queue.c:299]     grpc_completion_queue_next(cc=0x1d68490, deadline=gpr_timespec { tv_sec: 4414612, tv_nsec: 306893516, clock_type: 0 }, reserved=(nil))\r\nD0504 05:28:19.669477540   18074 connectivity_state.c:90]    CONWATCH: 0x7f61bc001740 (null): get IDLE\r\nD0504 05:28:19.669500018   18074 connectivity_state.c:149]   SET: 0x1d687a8 client_channel: IDLE --> IDLE [new_lb+resolver]\r\nD0504 05:28:19.669511196   18074 connectivity_state.c:104]   CONWATCH: 0x7f61bc001740 (null): from IDLE [cur=IDLE] notify=0x7f61bc001e98\r\nD0504 05:28:19.669522616   18074 connectivity_state.c:104]   CONWATCH: 0x7f61bc001c20 subchannel: from IDLE [cur=IDLE] notify=0x7f61bc001888\r\nD0504 05:28:19.669532243   18074 connectivity_state.c:149]   SET: 0x7f61bc001c20 subchannel: IDLE --> CONNECTING [state_change]\r\nD0504 05:28:19.669628326   18074 tcp_client_posix.c:290]     CLIENT_CONNECT: ipv6:[::1]:1116: asynchronously connecting\r\nD0504 05:28:19.669650655   18074 connectivity_state.c:149]   SET: 0x7f61bc001740 (null): IDLE --> CONNECTING [connecting_changed]\r\nD0504 05:28:19.669656042   18071 tcp_server_posix.c:348]     SERVER_CONNECT: incoming connection: ipv6:[::1]:47002\r\nD0504 05:28:19.669722345   18071 connectivity_state.c:104]   CONWATCH: 0x1d6dd48 server_transport: from IDLE [cur=READY] notify=0x1d694c8\r\nD0504 05:28:19.669746655   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 00 00 06 04 00 00 00 00 00 00 04 00 00 ff ff '...............'\r\nD0504 05:28:19.669783621   18071 connectivity_state.c:104]   CONWATCH: 0x1d6dd48 server_transport: from READY [cur=READY] notify=0x1d694c8\r\nD0504 05:28:19.669802115   18071 parsing.c:164]              FLOW   parsed: server   transport CREDIT       global.announce_incoming_window(0) by                   announce_bytes(983041) giving 983041\r\nD0504 05:28:19.669812395   18071 parsing.c:166]              FLOW   parsed: server   transport CREDIT           parsing.incoming_window(65535) by                   announce_bytes(983041) giving 1048576\r\nD0504 05:28:19.669825894   18071 writing.c:163]              FLOW    write: server   transport DEBIT   global.announce_incoming_window(983041) by                        announced(983041) giving 0\r\nD0504 05:28:19.669834986   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 00 00 04 08 00 00 00 00 00 00 0f 00 01 '.............'\r\nD0504 05:28:19.669932310   18071 tcp_client_posix.c:126]     CLIENT_CONNECT: ipv6:[::1]:1116: on_writable: success=1\r\nD0504 05:28:19.669959369   18071 tcp_client_posix.c:99]      CLIENT_CONNECT: ipv6:[::1]:1116: on_alarm: success=0\r\nD0504 05:28:19.669660349   18074 connectivity_state.c:104]   CONWATCH: 0x7f61bc001c20 subchannel: from CONNECTING [cur=CONNECTING] notify=0x7f61bc002958\r\nD0504 05:28:19.670021208   18071 connectivity_state.c:104]   CONWATCH: 0x1d70078 client_transport: from READY [cur=READY] notify=0x1d692b0\r\nD0504 05:28:19.670038690   18071 connectivity_state.c:149]   SET: 0x7f61bc001c20 subchannel: CONNECTING --> READY [connected]\r\nD0504 05:28:19.670054048   18071 parsing.c:164]              FLOW   parsed: client   transport CREDIT       global.announce_incoming_window(0) by                   announce_bytes(983041) giving 983041\r\nD0504 05:28:19.670064890   18071 parsing.c:166]              FLOW   parsed: client   transport CREDIT           parsing.incoming_window(65535) by                   announce_bytes(983041) giving 1048576\r\nD0504 05:28:19.670076662   18071 tcp_posix.c:401]            WRITE 0x1d69100: 50 52 49 20 2a 20 48 54 54 50 2f 32 2e 30 0d 0a 0d 0a 53 4d 0d 0a 0d 0a 'PRI * HTTP/2.0....SM....'\r\nD0504 05:28:19.670118387   18071 connectivity_state.c:149]   SET: 0x7f61bc001740 (null): CONNECTING --> READY [connecting_ready]\r\nD0504 05:28:19.670131047   18071 connectivity_state.c:104]   CONWATCH: 0x1d70078 client_transport: from READY [cur=READY] notify=0x7f61bc0016d8\r\nD0504 05:28:19.670145824   18071 tcp_posix.c:163]            read: success=1\r\nD0504 05:28:19.670155300   18071 tcp_posix.c:167]            READ 0x1d69100: 00 00 06 04 00 00 00 00 00 00 04 00 00 ff ff 00 00 04 08 00 00 00 00 00 00 0f 00 01 '............................'\r\nD0504 05:28:19.670167956   18071 writing.c:163]              FLOW    write: client   transport DEBIT   global.announce_incoming_window(983041) by                        announced(983041) giving 0\r\nD0504 05:28:19.670184376   18071 frame_settings.c:248]       CHTTP2:CLI: got setting 4 = 65535\r\nD0504 05:28:19.670195544   18071 frame_window_update.c:114]  FLOW    parse: client   transport CREDIT               parsing.outgoing_window(0) by                  received_update(983041) giving 983041\r\nD0504 05:28:19.670205806   18071 parsing.c:150]              FLOW   parsed: client   transport MOVE                  global.outgoing_window(0) <-          parsing.outgoing_window(983041) giving 983041\r\nD0504 05:28:19.670214022   18071 tcp_posix.c:401]            WRITE 0x1d69100: 00 00 04 08 00 00 00 00 00 00 0f 00 01 '.............'\r\nI0504 05:28:19.670253395   18071 http_client_filter.c:143]   OP[http-client:0x1d6b2a8]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 66 6f 6f '/foo', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip' deadline=4414607.306717950} SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_TRAILING_METADATA\r\nI0504 05:28:19.670301268   18071 connected_channel.c:70]     OP[connected:0x1d6b2c0]: SEND_INITIAL_METADATA{key=3a 73 63 68 65 6d 65 ':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method' value=50 4f 53 54 'POST', key=3a 70 61 74 68 ':path' value=2f 66 6f 6f '/foo', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers', key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65 6e 74 'user-agent' value=67 72 70 63 2d 63 2f 30 2e 31 34 2e 30 2d 64 65 76 20 28 6c 69 6e 75 78 29 'grpc-c/0.14.0-dev (linux)' deadline=4414607.306717950} SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_TRAILING_METADATA\r\nD0504 05:28:19.670322482   18071 chttp2_transport.c:813]     HTTP:CLI: Allocating new grpc_chttp2_stream 0x1d6b3d8 to id 1\r\nD0504 05:28:19.670341095   18071 writing.c:77]               FLOW    write: client   transport MOVE             writing.outgoing_window(65535) <-           global.outgoing_window(983041) giving 1048576\r\nD0504 05:28:19.670353162   18071 writing.c:94]               FLOW    write: client   stream[1] MOVE                 writing.outgoing_window(0) <-            global.outgoing_window(65535) giving 65535\r\nD0504 05:28:19.670370508   18071 tcp_posix.c:401]            WRITE 0x1d69100: 00 00 00 04 01 00 00 00 00 '.........'\r\nD0504 05:28:19.670408608   18071 tcp_posix.c:401]            WRITE 0x1d69100: 00 00 12 04 00 00 00 00 00 00 02 00 00 00 00 00 03 00 00 00 00 00 04 00 00 ff ff '...........................'\r\nD0504 05:28:19.670417286   18071 tcp_posix.c:401]            WRITE 0x1d69100: 00 00 f3 01 04 00 00 00 01 40 07 '.........@.'\r\nD0504 05:28:19.670424194   18071 tcp_posix.c:401]            WRITE 0x1d69100: 3a 73 63 68 65 6d 65 ':scheme'\r\nD0504 05:28:19.670431036   18071 tcp_posix.c:401]            WRITE 0x1d69100: 04 '.'\r\nD0504 05:28:19.670437613   18071 tcp_posix.c:401]            WRITE 0x1d69100: 68 74 74 70 'http'\r\nD0504 05:28:19.670444331   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 07 '@.'\r\nD0504 05:28:19.670450255   18071 tcp_posix.c:401]            WRITE 0x1d69100: 3a 6d 65 74 68 6f 64 ':method'\r\nD0504 05:28:19.670456197   18071 tcp_posix.c:401]            WRITE 0x1d69100: 04 '.'\r\nD0504 05:28:19.670462411   18071 tcp_posix.c:401]            WRITE 0x1d69100: 50 4f 53 54 'POST'\r\nD0504 05:28:19.670467823   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 05 '@.'\r\nD0504 05:28:19.670478935   18071 tcp_posix.c:401]            WRITE 0x1d69100: 3a 70 61 74 68 ':path'\r\nD0504 05:28:19.670486943   18071 tcp_posix.c:401]            WRITE 0x1d69100: 04 2f 66 6f 6f 40 0a './foo@.'\r\nD0504 05:28:19.670493829   18071 tcp_posix.c:401]            WRITE 0x1d69100: 3a 61 75 74 68 6f 72 69 74 79 ':authority'\r\nD0504 05:28:19.670499918   18071 tcp_posix.c:401]            WRITE 0x1d69100: 17 '.'\r\nD0504 05:28:19.670508270   18071 tcp_posix.c:401]            WRITE 0x1d69100: 66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234'\r\nD0504 05:28:19.670514989   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 0d '@.'\r\nD0504 05:28:19.670521785   18071 tcp_posix.c:401]            WRITE 0x1d69100: 67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding'\r\nD0504 05:28:19.670528496   18071 tcp_posix.c:401]            WRITE 0x1d69100: 08 '.'\r\nD0504 05:28:19.670534818   18071 tcp_posix.c:401]            WRITE 0x1d69100: 69 64 65 6e 74 69 74 79 'identity'\r\nD0504 05:28:19.670541265   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 14 '@.'\r\nD0504 05:28:19.670548521   18071 tcp_posix.c:401]            WRITE 0x1d69100: 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding'\r\nD0504 05:28:19.670555392   18071 tcp_posix.c:401]            WRITE 0x1d69100: 15 '.'\r\nD0504 05:28:19.670563301   18071 tcp_posix.c:401]            WRITE 0x1d69100: 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip'\r\nD0504 05:28:19.670569790   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 02 '@.'\r\nD0504 05:28:19.670575717   18071 tcp_posix.c:401]            WRITE 0x1d69100: 74 65 'te'\r\nD0504 05:28:19.670581947   18071 tcp_posix.c:401]            WRITE 0x1d69100: 08 '.'\r\nD0504 05:28:19.670588192   18071 tcp_posix.c:401]            WRITE 0x1d69100: 74 72 61 69 6c 65 72 73 'trailers'\r\nD0504 05:28:19.670594495   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 0c '@.'\r\nD0504 05:28:19.670600922   18071 tcp_posix.c:401]            WRITE 0x1d69100: 63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type'\r\nD0504 05:28:19.670607029   18071 tcp_posix.c:401]            WRITE 0x1d69100: 10 '.'\r\nD0504 05:28:19.670613623   18071 tcp_posix.c:401]            WRITE 0x1d69100: 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'\r\nD0504 05:28:19.670625924   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 0a '@.'\r\nD0504 05:28:19.670633017   18071 tcp_posix.c:401]            WRITE 0x1d69100: 75 73 65 72 2d 61 67 65 6e 74 'user-agent'\r\nD0504 05:28:19.670639577   18071 tcp_posix.c:401]            WRITE 0x1d69100: 19 '.'\r\nD0504 05:28:19.670647494   18071 tcp_posix.c:401]            WRITE 0x1d69100: 67 72 70 63 2d 63 2f 30 2e 31 34 2e 30 2d 64 65 76 20 28 6c 69 6e 75 78 29 'grpc-c/0.14.0-dev (linux)'\r\nD0504 05:28:19.670653900   18071 tcp_posix.c:401]            WRITE 0x1d69100: 40 0c '@.'\r\nD0504 05:28:19.670664127   18071 tcp_posix.c:401]            WRITE 0x1d69100: 67 72 70 63 2d 74 69 6d 65 6f 75 74 'grpc-timeout'\r\nD0504 05:28:19.670671750   18071 tcp_posix.c:401]            WRITE 0x1d69100: 02 35 53 00 00 00 00 01 00 00 00 01 '.5S.........'\r\nD0504 05:28:19.670737933   18071 tcp_posix.c:163]            read: success=1\r\nD0504 05:28:19.670769853   18071 tcp_posix.c:167]            READ 0x1d6a480: 50 52 49 20 2a 20 48 54 54 50 2f 32 2e 30 0d 0a 0d 0a 53 4d 0d 0a 0d 0a 00 00 04 08 00 00 00 00 00 00 0f 00 01 00 00 00 04 01 00 00 00 00 00 00 12 04 00 00 00 00 00 00 02 00 00 00 00 00 03 00 00 00 00 00 04 00 00 ff ff 00 00 f3 01 04 00 00 00 01 40 07 3a 73 63 68 65 6d 65 04 68 74 74 70 40 07 3a 6d 65 74 68 6f 64 04 50 4f 53 54 40 05 3a 70 61 74 68 04 2f 66 6f 6f 40 0a 3a 61 75 74 68 6f 72 69 74 79 17 66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 40 0d 67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 08 69 64 65 6e 74 69 74 79 40 14 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 15 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 40 02 74 65 08 74 72 61 69 6c 65 72 73 40 0c 63 6f 6e 74 65 6e 74 2d 74 79 70 65 10 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 40 0a 75 73 65 72 2d 61 67 65 6e 74 19 67 72 70 63 2d 63 2f 30 2e 31 34 2e 30 2d 64 65 76 20 28 6c 69 6e 75 78 29 40 0c 67 72 70 63 2d 74 69 6d 65 6f 75 74 02 35 53 00 00 00 00 01 00 00 00 01 'PRI * HTTP/2.0....SM..............................................................@.:scheme.http@.:method.POST@.:path./foo@.:authority.foo.test.google.fr:1234@.grpc-encoding.identity@.grpc-accept-encoding.identity,deflate,gzip@.te.trailers@.content-type.application/grpc@.user-agent.grpc-c/0.14.0-dev (linux)@.grpc-timeout.5S.........'\r\nD0504 05:28:19.670788061   18071 frame_window_update.c:114]  FLOW    parse: server   transport CREDIT               parsing.outgoing_window(0) by                  received_update(983041) giving 983041\r\nD0504 05:28:19.670796620   18071 frame_settings.c:248]       CHTTP2:SVR: got setting 2 = 0\r\nD0504 05:28:19.670802902   18071 frame_settings.c:248]       CHTTP2:SVR: got setting 3 = 0\r\nD0504 05:28:19.670808909   18071 frame_settings.c:248]       CHTTP2:SVR: got setting 4 = 65535\r\nI0504 05:28:19.670822388   18071 call.c:1190]                ops[0]: RECV_INITIAL_METADATA ptr=0x1d74ac8\r\nI0504 05:28:19.670830740   18071 server.c:738]               OP[server:0x1d749f8]: RECV_INITIAL_METADATA\r\nI0504 05:28:19.670838027   18071 http_server_filter.c:210]   OP[http-server:0x1d74a10]: RECV_INITIAL_METADATA\r\nI0504 05:28:19.670845145   18071 connected_channel.c:70]     OP[connected:0x1d74a40]: RECV_INITIAL_METADATA\r\nI0504 05:28:19.670858686   18071 parsing.c:611]              HTTP:1:HDR:SVR: :scheme: http\r\nI0504 05:28:19.670866990   18071 parsing.c:611]              HTTP:1:HDR:SVR: :method: POST\r\nI0504 05:28:19.670875298   18071 parsing.c:611]              HTTP:1:HDR:SVR: :path: /foo\r\nI0504 05:28:19.670884582   18071 parsing.c:611]              HTTP:1:HDR:SVR: :authority: foo.test.google.fr:1234\r\nI0504 05:28:19.670893164   18071 parsing.c:611]              HTTP:1:HDR:SVR: grpc-encoding: identity\r\nI0504 05:28:19.670901176   18071 parsing.c:611]              HTTP:1:HDR:SVR: grpc-accept-encoding: identity,deflate,gzip\r\nI0504 05:28:19.670909388   18071 parsing.c:611]              HTTP:1:HDR:SVR: te: trailers\r\nI0504 05:28:19.670917491   18071 parsing.c:611]              HTTP:1:HDR:SVR: content-type: application/grpc\r\nI0504 05:28:19.670933651   18071 parsing.c:611]              HTTP:1:HDR:SVR: user-agent: grpc-c/0.14.0-dev (linux)\r\nI0504 05:28:19.670994875   18071 parsing.c:611]              HTTP:1:HDR:SVR: grpc-timeout: 5S\r\nD0504 05:28:19.671022217   18071 parsing.c:150]              FLOW   parsed: server   transport MOVE                  global.outgoing_window(0) <-          parsing.outgoing_window(983041) giving 983041\r\nD0504 05:28:19.671040281   18071 writing.c:77]               FLOW    write: server   transport MOVE             writing.outgoing_window(65535) <-           global.outgoing_window(983041) giving 1048576\r\nD0504 05:28:19.671052631   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 00 00 00 04 01 00 00 00 00 '.........'\r\nI0504 05:28:19.671095745   18071 completion_queue.c:233]     grpc_cq_end_op(exec_ctx=0x7ffc41d93ff0, cc=0x1d68490, tag=0x65, success=1, done=0x427e10, done_arg=0x7f61c3ce7fa0, storage=0x7f61c3ce7fd0)\r\nI0504 05:28:19.671109220   18071 completion_queue.c:350]     RETURN_EVENT[0x1d68490]: OP_COMPLETE: tag:0x65 OK\r\nI0504 05:28:19.671117701   18071 server.c:1122]              grpc_server_shutdown_and_notify(server=0x1d689f0, cq=0x1d68490, tag=0x3e8)\r\nD0504 05:28:19.671153978   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 00 00 17 07 00 00 00 00 00 00 00 00 01 00 00 00 00 '.................'\r\nD0504 05:28:19.671165535   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 53 65 72 76 65 72 20 73 68 75 74 64 6f 77 6e 'Server shutdown'\r\nI0504 05:28:19.671192058   18071 call.c:1478]                grpc_call_start_batch(call=0x1d744b0, ops=0x7ffc41d941d0, nops=3, tag=0x66, reserved=(nil))\r\nI0504 05:28:19.671202785   18071 call.c:1190]                ops[0]: SEND_INITIAL_METADATA\r\nI0504 05:28:19.671210605   18071 call.c:1190]                ops[1]: SEND_STATUS_FROM_SERVER status=12 details=xyz\r\nI0504 05:28:19.671219227   18071 call.c:1190]                ops[2]: RECV_CLOSE_ON_SERVER cancelled=0x7ffc41d94114\r\nI0504 05:28:19.671238023   18071 server.c:738]               OP[server:0x1d749f8]: SEND_INITIAL_METADATA{ deadline=4414607.308634404} SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status' value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=78 79 7a 'xyz'} RECV_TRAILING_METADATA\r\nI0504 05:28:19.671252794   18071 http_server_filter.c:210]   OP[http-server:0x1d74a10]: SEND_INITIAL_METADATA{ deadline=4414607.308634404} SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status' value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=78 79 7a 'xyz'} RECV_TRAILING_METADATA\r\nI0504 05:28:19.671280510   18071 connected_channel.c:70]     OP[connected:0x1d74a40]: SEND_INITIAL_METADATA{key=3a 73 74 61 74 75 73 ':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip' deadline=4414607.308634404} SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status' value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=78 79 7a 'xyz'} RECV_TRAILING_METADATA\r\nD0504 05:28:19.671295849   18071 writing.c:94]               FLOW    write: server   stream[1] MOVE                 writing.outgoing_window(0) <-            global.outgoing_window(65535) giving 65535\r\nD0504 05:28:19.671310574   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 00 00 81 01 04 00 00 00 01 40 07 '.........@.'\r\nD0504 05:28:19.671317545   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 3a 73 74 61 74 75 73 ':status'\r\nD0504 05:28:19.671323792   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 03 '.'\r\nD0504 05:28:19.671329865   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 32 30 30 '200'\r\nD0504 05:28:19.671335724   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 40 0c '@.'\r\nD0504 05:28:19.671347479   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type'\r\nD0504 05:28:19.671353959   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 10 '.'\r\nD0504 05:28:19.671360658   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'\r\nD0504 05:28:19.671366910   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 40 0d '@.'\r\nD0504 05:28:19.671373341   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding'\r\nD0504 05:28:19.671386802   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 08 '.'\r\nD0504 05:28:19.671394098   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 69 64 65 6e 74 69 74 79 'identity'\r\nD0504 05:28:19.671400694   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 40 14 '@.'\r\nD0504 05:28:19.671408367   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding'\r\nD0504 05:28:19.671414947   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 15 '.'\r\nD0504 05:28:19.671422802   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip'\r\nD0504 05:28:19.671429058   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 40 0c '@.'\r\nD0504 05:28:19.671435639   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 67 72 70 63 2d 74 69 6d 65 6f 75 74 'grpc-timeout'\r\nD0504 05:28:19.671442112   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 02 35 53 '.5S'\r\nD0504 05:28:19.671448860   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 00 00 22 01 05 00 00 00 01 40 0b '..\"......@.'\r\nD0504 05:28:19.671455989   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'\r\nD0504 05:28:19.671462678   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 02 31 32 40 0c '.12@.'\r\nD0504 05:28:19.671468944   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message'\r\nD0504 05:28:19.671475817   18071 tcp_posix.c:401]            WRITE 0x1d6a480: 03 78 79 7a '.xyz'\r\nD0504 05:28:19.671505640   18071 chttp2_transport.c:1638]    set connectivity_state=4\r\nD0504 05:28:19.671515309   18071 connectivity_state.c:149]   SET: 0x1d6dd48 server_transport: READY --> FATAL_FAILURE [close_transport]\r\nI0504 05:28:19.671525672   18071 completion_queue.c:233]     grpc_cq_end_op(exec_ctx=0x7ffc41d940b0, cc=0x1d68490, tag=0x3e8, success=1, done=0x427de0, done_arg=0x1d689f0, storage=0x1d69360)\r\nD0504 05:28:19.671534536   18071 tcp_posix.c:163]            read: success=0\r\nI0504 05:28:19.671560144   18071 completion_queue.c:233]     grpc_cq_end_op(exec_ctx=0x7ffc41d940b0, cc=0x1d68490, tag=0x66, success=1, done=0x424270, done_arg=0x1d74508, storage=0x1d74510)\r\nI0504 05:28:19.671571838   18071 completion_queue.c:299]     grpc_completion_queue_next(cc=0x1d68490, deadline=gpr_timespec { tv_sec: 4414612, tv_nsec: 309198943, clock_type: 0 }, reserved=(nil))\r\nI0504 05:28:19.671580629   18071 completion_queue.c:350]     RETURN_EVENT[0x1d68490]: OP_COMPLETE: tag:0x3e8 OK\r\nI0504 05:28:19.671588866   18071 completion_queue.c:299]     grpc_completion_queue_next(cc=0x1d68490, deadline=gpr_timespec { tv_sec: 4414612, tv_nsec: 309198943, clock_type: 0 }, reserved=(nil))\r\nI0504 05:28:19.671597344   18071 completion_queue.c:350]     RETURN_EVENT[0x1d68490]: OP_COMPLETE: tag:0x66 OK\r\nI0504 05:28:19.671604958   18071 completion_queue.c:299]     grpc_completion_queue_next(cc=0x1d68490, deadline=gpr_timespec { tv_sec: 4414612, tv_nsec: 309198943, clock_type: 0 }, reserved=(nil))\r\nD0504 05:28:19.671635194   18071 tcp_posix.c:163]            read: success=1\r\nD0504 05:28:19.671653155   18071 tcp_posix.c:167]            READ 0x1d69100: 00 00 00 04 01 00 00 00 00 00 00 17 07 00 00 00 00 00 00 00 00 01 00 00 00 00 53 65 72 76 65 72 20 73 68 75 74 64 6f 77 6e 00 00 81 01 04 00 00 00 01 40 07 3a 73 74 61 74 75 73 03 32 30 30 40 0c 63 6f 6e 74 65 6e 74 2d 74 79 70 65 10 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 40 0d 67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 08 69 64 65 6e 74 69 74 79 40 14 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 15 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 40 0c 67 72 70 63 2d 74 69 6d 65 6f 75 74 02 35 53 00 00 22 01 05 00 00 00 01 40 0b 67 72 70 63 2d 73 74 61 74 75 73 02 31 32 40 0c 67 72 70 63 2d 6d 65 73 73 61 67 65 03 78 79 7a '..........................Server shutdown.........@.:status.200@.content-type.application/grpc@.grpc-encoding.identity@.grpc-accept-encoding.identity,deflate,gzip@.grpc-timeout.5S..\"......@.grpc-status.12@.grpc-message.xyz'\r\nI0504 05:28:19.671675760   18071 parsing.c:611]              HTTP:1:HDR:CLI: :status: 200\r\nI0504 05:28:19.671686268   18071 parsing.c:611]              HTTP:1:HDR:CLI: content-type: application/grpc\r\nI0504 05:28:19.671694583   18071 parsing.c:611]              HTTP:1:HDR:CLI: grpc-encoding: identity\r\nI0504 05:28:19.671702770   18071 parsing.c:611]              HTTP:1:HDR:CLI: grpc-accept-encoding: identity,deflate,gzip\r\nI0504 05:28:19.671710880   18071 parsing.c:611]              HTTP:1:HDR:CLI: grpc-timeout: 5S\r\nI0504 05:28:19.671720475   18071 parsing.c:657]              HTTP:1:TRL:CLI: grpc-status: 12\r\nI0504 05:28:19.671729122   18071 parsing.c:657]              HTTP:1:TRL:CLI: grpc-message: xyz\r\nD0504 05:28:19.671737942   18071 chttp2_transport.c:786]     got goaway [0]: 53 65 72 76 65 72 20 73 68 75 74 64 6f 77 6e 'Server shutdown'\r\nD0504 05:28:19.671744648   18071 chttp2_transport.c:1638]    set connectivity_state=4\r\nD0504 05:28:19.671750897   18071 connectivity_state.c:149]   SET: 0x1d70078 client_transport: READY --> FATAL_FAILURE [got_goaway]\r\nD0504 05:28:19.671766548   18071 connectivity_state.c:149]   SET: 0x7f61bc001740 (null): READY --> FATAL_FAILURE [selected_changed]\r\nD0504 05:28:19.671774433   18071 connectivity_state.c:149]   SET: 0x7f61bc001c20 subchannel: READY --> FATAL_FAILURE [reflect_child]\r\nD0504 05:28:19.671783659   18071 tcp_posix.c:163]            read: success=0\r\nI0504 05:28:19.671796362   18071 completion_queue.c:233]     grpc_cq_end_op(exec_ctx=0x7ffc41d93ff0, cc=0x1d68490, tag=0x1, success=1, done=0x424270, done_arg=0x1d699c8, storage=0x1d699d0)\r\nD0504 05:28:19.671807986   18071 chttp2_transport.c:1638]    set connectivity_state=4\r\nD0504 05:28:19.671815495   18071 connectivity_state.c:149]   SET: 0x1d70078 client_transport: FATAL_FAILURE --> FATAL_FAILURE [close_transport]\r\nI0504 05:28:19.671855957   18071 completion_queue.c:350]     RETURN_EVENT[0x1d68490]: OP_COMPLETE: tag:0x1 OK\r\nI0504 05:28:19.671867847   18071 metadata_array.c:47]        grpc_metadata_array_destroy(array=0x7ffc41d94130)\r\nI0504 05:28:19.671874389   18071 metadata_array.c:47]        grpc_metadata_array_destroy(array=0x7ffc41d94150)\r\nI0504 05:28:19.671880335   18071 metadata_array.c:47]        grpc_metadata_array_destroy(array=0x7ffc41d94170)\r\nI0504 05:28:19.671887063   18071 call_details.c:47]          grpc_call_details_destroy(cd=0x7ffc41d94190)\r\nI0504 05:28:19.671893631   18071 call.c:648]                 grpc_call_destroy(c=0x1d69970)\r\nI0504 05:28:19.671902585   18071 call.c:648]                 grpc_call_destroy(c=0x1d744b0)\r\nI0504 05:28:19.671909227   18071 metadata_array.c:47]        grpc_metadata_array_destroy(array=0x1d74ac8)\r\nI0504 05:28:19.671922258   18071 server.c:1188]              grpc_server_destroy(server=0x1d689f0)\r\nI0504 05:28:19.671979794   18071 server.c:920]               grpc_server_create((nil), (nil))\r\nI0504 05:28:19.672737910   18071 server.c:904]               grpc_server_register_completion_queue(server=0x1d689f0, cq=0x1d68490, reserved=(nil))\r\nI0504 05:28:19.672752680   18071 server_chttp2.c:95]         grpc_server_add_insecure_http2_port(server=0x1d689f0, addr=localhost:1116)\r\nI0504 05:28:19.672935342   18071 server.c:1004]              grpc_server_start(server=0x1d689f0)\r\nI0504 05:28:19.672954671   18071 channel.c:201]              grpc_channel_create_call(channel=0x1d68690, parent_call=(nil), propagation_mask=ffff, cq=0x1d68490, method=/foo, host=foo.test.google.fr:1234, deadline=gpr_timespec { tv_sec: 4414607, tv_nsec: 310580194, clock_type: 0 }, reserved=(nil))\r\nI0504 05:28:19.672973898   18071 metadata_array.c:42]        grpc_metadata_array_init(array=0x7ffc41d94130)\r\nI0504 05:28:19.672981297   18071 metadata_array.c:42]        grpc_metadata_array_init(array=0x7ffc41d94150)\r\nI0504 05:28:19.672987243   18071 metadata_array.c:42]        grpc_metadata_array_init(array=0x7ffc41d94170)\r\nI0504 05:28:19.672993022   18071 call_details.c:42]          grpc_call_details_init(cd=0x7ffc41d94190)\r\nI0504 05:28:19.672999613   18071 call.c:1478]                grpc_call_start_batch(call=0x1d69970, ops=0x7ffc41d941d0, nops=4, tag=0x1, reserved=(nil))\r\nI0504 05:28:19.673006660   18071 call.c:1190]                ops[0]: SEND_INITIAL_METADATA\r\nI0504 05:28:19.673013432   18071 call.c:1190]                ops[1]: SEND_CLOSE_FROM_CLIENT\r\nI0504 05:28:19.673019964   18071 call.c:1190]                ops[2]: RECV_INITIAL_METADATA ptr=0x7ffc41d94130\r\nI0504 05:28:19.673027809   18071 call.c:1190]                ops[3]: RECV_STATUS_ON_CLIENT metadata=0x7ffc41d94150 status=0x7ffc41d94110 details=0x7ffc41d94120\r\nI0504 05:28:19.673050113   18071 client_channel.c:109]       OP[client-channel:0x1d69ed0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 66 6f 6f '/foo', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip' deadline=4414607.310580194} SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_TRAILING_METADATA\r\nI0504 05:28:19.673152247   18071 http_client_filter.c:143]   OP[http-client:0x1d6b238]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 66 6f 6f '/foo', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip' deadline=4414607.310580194} SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_TRAILING_METADATA\r\nI0504 05:28:19.673203339   18071 connected_channel.c:70]     OP[connected:0x1d6b250]: SEND_INITIAL_METADATA{key=3a 73 63 68 65 6d 65 ':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method' value=50 4f 53 54 'POST', key=3a 70 61 74 68 ':path' value=2f 66 6f 6f '/foo', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=66 6f 6f 2e 74 65 73 74 2e 67 6f 6f 67 6c 65 2e 66 72 3a 31 32 33 34 'foo.test.google.fr:1234', key=67 72 70 63 2d 65 6e 63 6f 64 69 6e 67 'grpc-encoding' value=69 64 65 6e 74 69 74 79 'identity', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers', key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65 6e 74 'user-agent' value=67 72 70 63 2d 63 2f 30 2e 31 34 2e 30 2d 64 65 76 20 28 6c 69 6e 75 78 29 'grpc-c/0.14.0-dev (linux)' deadline=4414607.310580194} SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_TRAILING_METADATA\r\nD0504 05:28:19.673215395   18071 chttp2_transport.c:813]     HTTP:CLI: Allocating new grpc_chttp2_stream 0x1d6b368 to id 3\r\nI0504 05:28:19.673225588   18071 server.c:1290]              grpc_server_request_call(server=0x1d689f0, call=0x7ffc41d94118, details=0x7ffc41d94190, initial_metadata=0x7ffc41d94170, cq_bound_to_call=0x1d68490, cq_for_notification=0x1d68490, tag=0x65)\r\nI0504 05:28:19.673235506   18071 completion_queue.c:299]     grpc_completion_queue_next(cc=0x1d68490, deadline=gpr_timespec { tv_sec: 4414612, tv_nsec: 310862662, clock_type: 0 }, reserved=(nil))\r\nD0504 05:28:19.673266097   18074 connectivity_state.c:149]   SET: 0x1d687a8 client_channel: IDLE --> CONNECTING [lb_changed]\r\nD0504 05:28:19.673280905   18074 connectivity_state.c:104]   CONWATCH: 0x7f61bc001740 (null): from CONNECTING [cur=FATAL_FAILURE] notify=0x7f61bc001b28\r\nD0504 05:28:19.673291082   18074 connectivity_state.c:149]   SET: 0x1d687a8 client_channel: CONNECTING --> TRANSIENT_FAILURE [lb_changed]\r\nD0504 05:28:19.673430957   18074 connectivity_state.c:149]   SET: 0x7f61bc001740 (null): FATAL_FAILURE --> FATAL_FAILURE [shutdown]\r\nD0504 05:28:19.673444794   18074 connectivity_state.c:101]   CONWATCH: 0x1d70078 client_transport: unsubscribe notify=0x7f61bc0016d8\r\nD0504 05:28:19.673454646   18074 connectivity_state.c:90]    CONWATCH: 0x7f61bc002840 (null): get IDLE\r\nD0504 05:28:19.673462671   18074 connectivity_state.c:149]   SET: 0x1d687a8 client_channel: TRANSIENT_FAILURE --> IDLE [new_lb+resolver]\r\nD0504 05:28:19.673469966   18074 connectivity_state.c:104]   CONWATCH: 0x7f61bc002840 (null): from IDLE [cur=IDLE] notify=0x7f61bc001e98\r\nI0504 05:28:24.674449439   18071 client_channel.c:109]       OP[client-channel:0x1d69ed0]: CANCEL:4\r\nI0504 05:28:24.674499309   18071 http_client_filter.c:143]   OP[http-client:0x1d6b238]: CANCEL:4\r\nI0504 05:28:24.674507738   18071 connected_channel.c:70]     OP[connected:0x1d6b250]: CANCEL:4\r\nI0504 05:28:24.674543840   18071 completion_queue.c:233]     grpc_cq_end_op(exec_ctx=0x7ffc41d93ff0, cc=0x1d68490, tag=0x1, success=1, done=0x424270, done_arg=0x1d699c8, storage=0x1d699d0)\r\nI0504 05:28:24.674556840   18071 completion_queue.c:350]     RETURN_EVENT[0x1d68490]: OP_COMPLETE: tag:0x1 OK\r\nE0504 05:28:24.674565344   18071 cq_verifier.c:248]          event not found: OP_COMPLETE: tag:0x1 OK\r\nE0504 05:28:24.674571724   18071 cq_verifier.c:251]          have tags: 0x65\r\n\r\n\r\n\r\n*******************************\r\nCaught signal 6\r\nbins/opt/h2_full+trace_test[0x502013]\r\n/lib/x86_64-linux-gnu/libpthread.so.0(+0xf8d0)[0x7f61c4ab08d0]\r\n/lib/x86_64-linux-gnu/libc.so.6(gsignal+0x37)[0x7f61c472b067]\r\n/lib/x86_64-linux-gnu/libc.so.6(abort+0x148)[0x7f61c472c448]\r\nbins/opt/h2_full+trace_test[0x419433]\r\nbins/opt/h2_full+trace_test[0x40b3dc]\r\nbins/opt/h2_full+trace_test[0x40b7d4]\r\nbins/opt/h2_full+trace_test[0x403a85]\r\nbins/opt/h2_full+trace_test[0x40302b]\r\n/lib/x86_64-linux-gnu/libc.so.6(__libc_start_main+0xf5)[0x7f61c4717b45]\r\nbins/opt/h2_full+trace_test[0x4030e7]\r\nCommand terminated by signal 6\r\nreal 8.90\r\nuser 0.00\r\nsys 0.00\r\n\r\nFAILED: bins/opt/h2_full+trace_test disappearing_server [ret=134, pid=18070]\r\n```"},"comment":{"url":"https://api.github.com/repos/grpc/grpc/issues/comments/216761116","html_url":"https://github.com/grpc/grpc/issues/6422#issuecomment-216761116","issue_url":"https://api.github.com/repos/grpc/grpc/issues/6422","id":216761116,"user":{"login":"dgquintas","id":120217,"avatar_url":"https://avatars.githubusercontent.com/u/120217?v=3","gravatar_id":"","url":"https://api.github.com/users/dgquintas","html_url":"https://github.com/dgquintas","followers_url":"https://api.github.com/users/dgquintas/followers","following_url":"https://api.github.com/users/dgquintas/following{/other_user}","gists_url":"https://api.github.com/users/dgquintas/gists{/gist_id}","starred_url":"https://api.github.com/users/dgquintas/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/dgquintas/subscriptions","organizations_url":"https://api.github.com/users/dgquintas/orgs","repos_url":"https://api.github.com/users/dgquintas/repos","events_url":"https://api.github.com/users/dgquintas/events{/privacy}","received_events_url":"https://api.github.com/users/dgquintas/received_events","type":"User","site_admin":false},"created_at":"2016-05-04T06:57:37Z","updated_at":"2016-05-04T06:57:37Z","body":"Similar issue on windows in unrelated PR (https://github.com/grpc/grpc/pull/6329) which is effectly a no-op in terms of code.\r\n\r\n```\r\nD0504 06:20:58.267000000  4464 test_config.c:278] test slowdown: machine=1.000000 build=1.000000 total=1.000000\r\n\r\nI0504 06:20:58.269000000  4464 binary_metadata.c:55] test_request_response_with_metadata_and_payload/chttp2/fullstack_compression\r\n\r\nE0504 06:21:04.277000000  4464 cq_verifier.c:248] event not found: OP_COMPLETE: tag:00000001 OK\r\n\r\nE0504 06:21:04.277000000  4464 cq_verifier.c:251] have tags: 00000065\r\n\r\nAbort handler called.\r\n\r\n*** 0: 000000000118723F  - 0000000000000000\r\n\r\n*** 1: 0000000001187747  - 0000000000000000\r\n\r\n*** 2: 00000000011EA3D9  - 0000000000000000\r\n\r\n*** 3: 00000000011E4274  - 0000000000000000\r\n\r\n*** 4: 0000000001253EC6  - 0000000000000000\r\n\r\n*** 5: 00000000011C4B3D  - 0000000000000000\r\n\r\n*** 6: 00000000011C53D0  - 0000000000000000\r\n\r\n*** 7: 00000000011C2AB7  - 0000000000000000\r\n\r\n*** 8: 000000000118134A  - 0000000000000000\r\n\r\n*** 9: 00000000011E45DA  - 0000000000000000\r\n\r\n*** 10: 00000000011E472D  - 0000000000000000\r\n\r\n*** 11: 00000000758A338A BaseThreadInitThunk - 00000000758A3378\r\n\r\n*** 12: 0000000077389902 RtlInitializeExceptionChain - 000000007738989F\r\n\r\n*** 13: 00000000773898D5 RtlInitializeExceptionChain - 000000007738989F\r\n\r\n\r\nFAILED: vsprojects/Debug/h2_compress_nosec_test.exe binary_metadata [ret=1, pid=8680]\r\n```"}}