Skip to content

Instantly share code, notes, and snippets.

@mzabsky

mzabsky/gist:e6145fbe34906522958acaccafd85b18

Created August 18, 2021 16:34
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save mzabsky/e6145fbe34906522958acaccafd85b18 to your computer and use it in GitHub Desktop.
Save mzabsky/e6145fbe34906522958acaccafd85b18 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
I0818 18:33:08.412345 0 ..\..\..\src\core\lib\surface\init.cc:167: grpc_init(void)
I0818 18:33:08.412482 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1099: chand=000002B1C90A3528: creating client_channel for channel stack 000002B1C90A34B8
I0818 18:33:08.412592 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1C9098970: SET 8019 now 3019 call 000002B1C90989A0[00007FFBB7C22610]
I0818 18:33:08.412700 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 5 with queue_deadline_cap=3018 => is_first_timer=false
E0818 18:33:08.412929 0 ..\..\..\src\core\ext\filters\client_channel\http_proxy.cc:82: 'https' scheme not supported in proxy URI
I0818 18:33:08.414331 2962567298944 ..\..\..\src\core\lib\iomgr\timer_manager.cc:204: wait ended: was_timed:1 kicked:0
I0818 18:33:08.415027 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:719: TIMER CHECK BEGIN: now=3022 next=9223372036854775807 tls_min=2017 glob_min=3019
I0818 18:33:08.415149 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:614: .. shard[15]->min_deadline = 3019
I0818 18:33:08.415253 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[15]: heap_empty=true
I0818 18:33:08.415365 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[15]->queue_deadline_cap --> 4021
I0818 18:33:08.415491 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[15] popped 0
I0818 18:33:09.159896 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[15]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.160813 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[14]: heap_empty=true
I0818 18:33:09.160916 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[14]->queue_deadline_cap --> 4021
I0818 18:33:09.164683 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[14] popped 0
I0818 18:33:09.164811 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[14]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.164891 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[13]: heap_empty=true
I0818 18:33:09.164974 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[13]->queue_deadline_cap --> 4021
I0818 18:33:09.165041 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[13] popped 0
I0818 18:33:09.165095 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[13]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.165164 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[12]: heap_empty=true
I0818 18:33:09.165227 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[12]->queue_deadline_cap --> 4021
I0818 18:33:09.165328 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[12] popped 0
I0818 18:33:09.165383 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[12]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.165521 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[11]: heap_empty=true
I0818 18:33:09.165660 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[11]->queue_deadline_cap --> 4021
I0818 18:33:09.165777 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[11] popped 0
I0818 18:33:09.166538 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[11]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.168320 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[10]: heap_empty=true
I0818 18:33:09.171015 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[10]->queue_deadline_cap --> 4021
I0818 18:33:09.171679 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[10] popped 0
I0818 18:33:09.186724 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[10]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.187451 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[9]: heap_empty=true
I0818 18:33:09.187550 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[9]->queue_deadline_cap --> 4021
I0818 18:33:09.190760 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[9] popped 0
I0818 18:33:09.190880 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[9]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.191031 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[8]: heap_empty=true
I0818 18:33:09.191141 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[8]->queue_deadline_cap --> 4021
I0818 18:33:09.191252 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[8] popped 0
I0818 18:33:09.191368 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[8]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.191466 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[7]: heap_empty=true
I0818 18:33:09.191560 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[7]->queue_deadline_cap --> 4021
I0818 18:33:09.191666 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[7] popped 0
I0818 18:33:09.191766 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[7]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.191885 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[6]: heap_empty=true
I0818 18:33:09.192068 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[6]->queue_deadline_cap --> 4021
I0818 18:33:09.192184 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[6] popped 0
I0818 18:33:09.192238 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[6]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.192295 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[5]: heap_empty=true
I0818 18:33:09.192346 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[5]->queue_deadline_cap --> 4022
I0818 18:33:09.192439 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[5] popped 0
I0818 18:33:09.192517 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[5]->min_deadline 3019 --> 4023, now=3022
I0818 18:33:09.192602 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[4]: heap_empty=true
I0818 18:33:09.192678 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[4]->queue_deadline_cap --> 4021
I0818 18:33:09.192758 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[4] popped 0
I0818 18:33:09.192834 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[4]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.192915 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[3]: heap_empty=true
I0818 18:33:09.192989 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[3]->queue_deadline_cap --> 4021
I0818 18:33:09.193071 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[3] popped 0
I0818 18:33:09.193157 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[3]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.193280 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[2]: heap_empty=true
I0818 18:33:09.193390 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[2]->queue_deadline_cap --> 4021
I0818 18:33:09.193480 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[2] popped 0
I0818 18:33:09.193559 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[2]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.193641 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[1]: heap_empty=true
I0818 18:33:09.193698 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[1]->queue_deadline_cap --> 4021
I0818 18:33:09.193861 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[1] popped 0
I0818 18:33:09.193948 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[1]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.194073 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[0]: heap_empty=true
I0818 18:33:09.194151 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[0]->queue_deadline_cap --> 4021
I0818 18:33:09.194203 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[0] popped 0
I0818 18:33:09.194249 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[0]->min_deadline 3019 --> 4022, now=3022
I0818 18:33:09.194301 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:741: TIMER CHECK END: r=1; next=4022
I0818 18:33:09.194345 2962567298944 ..\..\..\src\core\lib\iomgr\timer_manager.cc:188: sleep for a 1000 milliseconds
I0818 18:33:10.458292 2962567298944 ..\..\..\src\core\lib\iomgr\timer_manager.cc:204: wait ended: was_timed:1 kicked:0
I0818 18:33:10.458643 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:719: TIMER CHECK BEGIN: now=5066 next=9223372036854775807 tls_min=3019 glob_min=4022
I0818 18:33:10.460039 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:614: .. shard[0]->min_deadline = 4022
I0818 18:33:10.460194 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[0]: heap_empty=true
I0818 18:33:10.460328 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[0]->queue_deadline_cap --> 6066
I0818 18:33:10.460496 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[0] popped 0
I0818 18:33:10.460695 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[0]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.460911 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[1]: heap_empty=true
I0818 18:33:10.461024 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[1]->queue_deadline_cap --> 6066
I0818 18:33:10.461158 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[1] popped 0
I0818 18:33:10.461279 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[1]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.461395 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[2]: heap_empty=true
I0818 18:33:10.461514 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[2]->queue_deadline_cap --> 6066
I0818 18:33:10.461672 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[2] popped 0
I0818 18:33:10.461829 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[2]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.462016 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[3]: heap_empty=true
I0818 18:33:10.462150 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[3]->queue_deadline_cap --> 6066
I0818 18:33:10.462307 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[3] popped 0
I0818 18:33:10.462469 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[3]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.462624 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[4]: heap_empty=true
I0818 18:33:10.462786 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[4]->queue_deadline_cap --> 6066
I0818 18:33:10.462938 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[4] popped 0
I0818 18:33:10.463080 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[4]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.463205 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[6]: heap_empty=true
I0818 18:33:10.463379 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[6]->queue_deadline_cap --> 6066
I0818 18:33:10.463502 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[6] popped 0
I0818 18:33:10.463638 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[6]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.463793 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[7]: heap_empty=true
I0818 18:33:10.463951 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[7]->queue_deadline_cap --> 6066
I0818 18:33:10.464096 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[7] popped 0
I0818 18:33:10.464234 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[7]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.464404 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[8]: heap_empty=true
I0818 18:33:10.464566 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[8]->queue_deadline_cap --> 6066
I0818 18:33:10.464740 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[8] popped 0
I0818 18:33:10.464911 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[8]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.465083 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[9]: heap_empty=true
I0818 18:33:10.465206 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[9]->queue_deadline_cap --> 6066
I0818 18:33:10.465366 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[9] popped 0
I0818 18:33:10.465518 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[9]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.465677 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[10]: heap_empty=true
I0818 18:33:10.465785 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[10]->queue_deadline_cap --> 6066
I0818 18:33:10.465966 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[10] popped 0
I0818 18:33:10.466075 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[10]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.466174 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[11]: heap_empty=true
I0818 18:33:10.466268 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[11]->queue_deadline_cap --> 6066
I0818 18:33:10.466364 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[11] popped 0
I0818 18:33:10.466464 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[11]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.466557 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[12]: heap_empty=true
I0818 18:33:10.466676 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[12]->queue_deadline_cap --> 6066
I0818 18:33:10.466794 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[12] popped 0
I0818 18:33:10.466905 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[12]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.467026 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[13]: heap_empty=true
I0818 18:33:10.467120 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[13]->queue_deadline_cap --> 6066
I0818 18:33:10.467222 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[13] popped 0
I0818 18:33:10.467322 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[13]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.467413 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[14]: heap_empty=true
I0818 18:33:10.467487 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[14]->queue_deadline_cap --> 6066
I0818 18:33:10.467561 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[14] popped 0
I0818 18:33:10.467623 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[14]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.467687 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[15]: heap_empty=true
I0818 18:33:10.467742 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[15]->queue_deadline_cap --> 6066
I0818 18:33:10.467796 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[15] popped 0
I0818 18:33:10.467891 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[15]->min_deadline 4022 --> 6067, now=5066
I0818 18:33:10.467982 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[5]: heap_empty=true
I0818 18:33:10.468043 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[5]->queue_deadline_cap --> 6066
I0818 18:33:10.468114 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[5] popped 0
I0818 18:33:10.468174 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[5]->min_deadline 4023 --> 6067, now=5066
I0818 18:33:10.468234 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:741: TIMER CHECK END: r=1; next=6067
I0818 18:33:10.468317 2962567298944 ..\..\..\src\core\lib\iomgr\timer_manager.cc:188: sleep for a 1001 milliseconds
I0818 18:33:10.470191 0 ..\..\..\src\core\lib\surface\completion_queue.cc:522: grpc_completion_queue_create_internal(completion_type=1, polling_type=0)
I0818 18:33:10.472646 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1879: chand=000002B1C90A3528 calld=000002B1C90723C0: created call
I0818 18:33:10.479471 0 ..\..\..\src\core\lib\surface\call.cc:1983: grpc_call_start_batch(call=000002B1C9071A20, ops=000000DFA3B7D720, nops=6, tag=000002B1C6CBD7C0, reserved=0000000000000000)
I0818 18:33:10.479597 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[0]: SEND_INITIAL_METADATA(nil)
I0818 18:33:10.479680 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[1]: SEND_MESSAGE ptr=0x2b1c6bdb690
I0818 18:33:10.479758 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[2]: SEND_CLOSE_FROM_CLIENT
I0818 18:33:10.479834 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[3]: RECV_INITIAL_METADATA ptr=0x2b1c6cbd7f8
I0818 18:33:10.479914 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[4]: RECV_MESSAGE ptr=0x2b1c6cbd810
I0818 18:33:10.479991 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[5]: RECV_STATUS_ON_CLIENT metadata=0x2b1c6cbd820 status=0x2b1c6cbd838 details=0x2b1c6cbd840
I0818 18:33:10.480105 0 ..\..\..\src\core\lib\surface\call.cc:641: OP[client-channel:000002B1C90723A0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.480327 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2034: chand=000002B1C90A3528 calld=000002B1C90723C0: adding pending batch at index 0
I0818 18:33:10.480460 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1986: chand=000002B1C90A3528 calld=000002B1C90723C0: grabbing resolution mutex to apply service config
I0818 18:33:10.480542 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:179: ConnectivityStateTracker client_channel[000002B1C90A35E8]: get current state: IDLE
I0818 18:33:10.480594 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2198: chand=000002B1C90A3528 calld=000002B1C90723C0: adding to resolver queued picks list
I0818 18:33:10.480653 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:179: ConnectivityStateTracker client_channel[000002B1C90A35E8]: get current state: IDLE
I0818 18:33:10.480713 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1565: chand=000002B1C90A3528: starting name resolution
I0818 18:33:10.480783 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:156: ConnectivityStateTracker client_channel[000002B1C90A35E8]: IDLE -> CONNECTING (started resolving, OK)
I0818 18:33:10.480844 0 ..\..\..\src\core\lib\iomgr\executor.cc:307: EXECUTOR (resolver-executor) try to schedule 000002B1C6BE5CD0 (short) to thread 0
I0818 18:33:10.480900 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1578: chand=000002B1C90A3528: created resolver=000002B1C909ABE0
I0818 18:33:10.480930 2962567286336 ..\..\..\src\core\lib\iomgr\executor.cc:245: EXECUTOR (resolver-executor) [0]: execute
I0818 18:33:10.481045 2962567286336 ..\..\..\src\core\lib\iomgr\executor.cc:124: EXECUTOR (resolver-executor) run 000002B1C6BE5CD0
I0818 18:33:10.481089 0 ..\..\..\src\core\lib\surface\completion_queue.cc:1227: grpc_completion_queue_pluck(cq=000002B1C9083940, tag=000002B1C6CBD7C0, deadline=gpr_timespec { tv_sec: 9223372036854775807, tv_nsec: 0, clock_type: 1 }, reserved=0000000000000000)
I0818 18:33:10.481425 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1231: chand=000002B1C90A3528: got resolver result
I0818 18:33:10.481506 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1285: chand=000002B1C90A3528: resolver returned no service config. Using default service config for channel.
I0818 18:33:10.481659 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1465: chand=000002B1C90A3528: resolver returned updated service config: "{}"
I0818 18:33:10.481736 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1492: chand=000002B1C90A3528: using ConfigSelector 0000000000000000
I0818 18:33:10.481794 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1425: chand=000002B1C90A3528: created new LB policy 000002B1C6C4CDC0
I0818 18:33:10.481848 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1407: chand=000002B1C90A3528: Updating child policy 000002B1C6C4CDC0
I0818 18:33:10.481904 2962567286336 ..\..\..\src\core\ext\filters\client_channel\lb_policy\child_policy_handler.cc:214: [child_policy_handler 000002B1C6C4CDC0] creating new child policy pick_first
I0818 18:33:10.481964 2962567286336 ..\..\..\src\core\ext\filters\client_channel\lb_policy\pick_first\pick_first.cc:147: Pick First 000002B1C92903F0 created.
I0818 18:33:10.482016 2962567286336 ..\..\..\src\core\ext\filters\client_channel\lb_policy\child_policy_handler.cc:275: [child_policy_handler 000002B1C6C4CDC0] created new LB policy "pick_first" (000002B1C92903F0)
I0818 18:33:10.482072 2962567286336 ..\..\..\src\core\ext\filters\client_channel\lb_policy\child_policy_handler.cc:233: [child_policy_handler 000002B1C6C4CDC0] updating child policy 000002B1C92903F0
I0818 18:33:10.482128 2962567286336 ..\..\..\src\core\ext\filters\client_channel\lb_policy\pick_first\pick_first.cc:266: Pick First 000002B1C92903F0 received update with 6 addresses
I0818 18:33:10.482191 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:363: [pick_first 000002B1C92903F0] Creating subchannel list 000002B1A6421D40 for 6 subchannels
I0818 18:33:10.482346 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:456: chand=000002B1C90A3528: creating subchannel wrapper 000002B1C6BCC1A0 for subchannel 000002B1C6BBD180
I0818 18:33:10.482421 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:383: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 0: Created subchannel 000002B1C6BCC1A0 for address [fe80::448c:2a50:a4b0:7e41%2510]:8002 args={}
I0818 18:33:10.482541 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:456: chand=000002B1C90A3528: creating subchannel wrapper 000002B1C6BCCD20 for subchannel 000002B1C6BBCAA0
I0818 18:33:10.482624 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:383: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 1: Created subchannel 000002B1C6BCCD20 for address [fe80::24b1:90a:b4f3:6672%256]:8002 args={}
I0818 18:33:10.482743 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:456: chand=000002B1C90A3528: creating subchannel wrapper 000002B1C6BCC4A0 for subchannel 000002B1C6BBCEC0
I0818 18:33:10.482826 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:383: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 2: Created subchannel 000002B1C6BCC4A0 for address [fe80::7c81:e8a9:3a6:a6cb%257]:8002 args={}
I0818 18:33:10.482942 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:456: chand=000002B1C90A3528: creating subchannel wrapper 000002B1C6BCC520 for subchannel 000002B1C6BBD2E0
I0818 18:33:10.483022 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:383: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 3: Created subchannel 000002B1C6BCC520 for address 172.29.129.196:8002 args={}
I0818 18:33:10.483141 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:456: chand=000002B1C90A3528: creating subchannel wrapper 000002B1C6BCC8A0 for subchannel 000002B1C92B4C00
I0818 18:33:10.483222 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:383: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 4: Created subchannel 000002B1C6BCC8A0 for address 192.168.49.1:8002 args={}
I0818 18:33:10.483335 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:456: chand=000002B1C90A3528: creating subchannel wrapper 000002B1C6BCC920 for subchannel 000002B1C92B47E0
I0818 18:33:10.483415 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:383: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 5: Created subchannel 000002B1C6BCC920 for address 192.168.118.1:8002 args={}
I0818 18:33:10.483506 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:310: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 0 of 6 (subchannel 000002B1C6BCC1A0): starting watch (from IDLE)
I0818 18:33:10.483765 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1C6CBF858: SET 25089 now 5089 call 000002B1C6CBF888[00007FFBB7CF0F70]
I0818 18:33:10.483919 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 6 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.484040 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1503: chand=000002B1C90A3528: switching to ConfigSelector 0000000000000000
I0818 18:33:10.484605 2962567286336 ..\..\..\src\core\lib\transport\connectivity_state.cc:179: ConnectivityStateTracker client_channel[000002B1C90A35E8]: get current state: CONNECTING
I0818 18:33:10.484755 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2212: chand=000002B1C90A3528 calld=000002B1C90723C0: applying service config to call
I0818 18:33:10.484896 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2183: chand=000002B1C90A3528 calld=000002B1C90723C0: removing from resolver queued picks list
I0818 18:33:10.485062 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:628: chand=000002B1C90A3528: connectivity change for subchannel wrapper 000002B1C6BCC1A0 subchannel 000002B1C6BBD180; hopping into work_serializer
I0818 18:33:10.485190 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:663: chand=000002B1C90A3528: processing connectivity change in work serializer for subchannel wrapper 000002B1C6BCC1A0 subchannel 000002B1C6BBD180 watcher=000002B1C92578E0
I0818 18:33:10.487295 2962567286336 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:243: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 0 of 6 (subchannel 000002B1C6BCC1A0): connectivity changed: state=CONNECTING, shutting_down=0, pending_watcher=000002B1C92578E0
I0818 18:33:10.489310 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:995: chand=000002B1C90A3528: update: state=CONNECTING status=(OK) picker=000002B1C92581C0
I0818 18:33:10.489416 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1C6CBF858: CANCEL pending=true
I0818 18:33:10.489519 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2409: chand=000002B1C90A3528 calld=000002B1C90723C0: creating dynamic call stack on channel_stack=000002B1C9258560
I0818 18:33:10.489620 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:356: chand=000002B1C95740A0 dynamic_termination_calld=000002B1C90A3528: create lb_call=000002B1C9072770
I0818 18:33:10.489714 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2111: chand=000002B1C90A3528 calld=000002B1C90723C0: starting 1 pending batches on dynamic_call=000002B1C9072680
I0818 18:33:10.489815 2962567286336 ..\..\..\src\core\lib\channel\handshaker.cc:61: handshake_manager 000002B1C9291D70: adding handshaker http_connect [000002B1A6422960] at index 0
I0818 18:33:10.489916 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1C9291DD8: SET 25089 now 5089 call 000002B1C9291E08[00007FFBB79AC062]
I0818 18:33:10.489987 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 7 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.490071 2962567286336 ..\..\..\src\core\lib\channel\handshaker.cc:88: handshake_manager 000002B1C9291D70: error="No Error" shutdown=0 index=0, args={endpoint=0x2b1c90fe2b0, args=0x2b1c9257660 {size=6: grpc.primary_user_agent=grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64), grpc.client_channel_factory=0x2b1c90ab6c0, grpc.server_uri=dns:///MATEJ-ZABSKY:8002, grpc.subchannel_pool=0x2b1c6c57dd0, grpc.default_authority=MATEJ-ZABSKY:8002, grpc.subchannel_address=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002}, read_buffer=0x2b1c909af70 (length=0), exit_early=0}
I0818 18:33:10.490221 2962567286336 ..\..\..\src\core\lib\channel\handshaker.cc:135: handshake_manager 000002B1C9291D70: calling handshaker http_connect [000002B1A6422960] at index 0
I0818 18:33:10.490318 2962567286336 ..\..\..\src\core\ext\filters\client_channel\dynamic_filters.cc:66: OP[dynamic_filter_termination:000002B1C90726F0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.490543 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2586: chand=000002B1C90A3528 lb_call=000002B1C9072770: adding pending batch at index 0
I0818 18:33:10.490717 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2745: chand=000002B1C90A3528 lb_call=000002B1C9072770: grabbing data plane mutex to perform pick
I0818 18:33:10.490895 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2988: chand=000002B1C90A3528 lb_call=000002B1C9072770: LB pick returned QUEUE (subchannel=0000000000000000, error="No Error")
I0818 18:33:10.491073 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2906: chand=000002B1C90A3528 lb_call=000002B1C9072770: adding to queued picks list
I0818 18:33:10.491158 2962567286336 ..\..\..\src\core\lib\channel\handshaker.cc:88: handshake_manager 000002B1C9291D70: error="No Error" shutdown=0 index=1, args={endpoint=0x2b1c90fe2b0, args=0x2b1c9257660 {size=6: grpc.primary_user_agent=grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64), grpc.client_channel_factory=0x2b1c90ab6c0, grpc.server_uri=dns:///MATEJ-ZABSKY:8002, grpc.subchannel_pool=0x2b1c6c57dd0, grpc.default_authority=MATEJ-ZABSKY:8002, grpc.subchannel_address=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002}, read_buffer=0x2b1c909af70 (length=0), exit_early=0}
I0818 18:33:10.491404 2962567286336 ..\..\..\src\core\lib\channel\handshaker.cc:121: handshake_manager 000002B1C9291D70: handshaking complete -- scheduling on_handshake_done with error="No Error"
I0818 18:33:10.491631 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1C9291DD8: CANCEL pending=true
I0818 18:33:10.491837 2962567286336 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2156: chand=000002B1C90A3528 calld=000002B1C90723C0: cancelling resolver queued pick: error="No Error" self=000002B1C6BD4580 calld->resolver_pick_canceller=0000000000000000
I0818 18:33:10.492067 2962567286336 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state IDLE -> WRITING [TRANSPORT_FLOW_CONTROL]
I0818 18:33:10.492193 2962567286336 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state WRITING -> WRITING+MORE [INITIAL_WRITE]
I0818 18:33:10.492287 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1C928F6B0: SET 25089 now 5089 call 000002B1C928F6E0[00007FFBB799D468]
I0818 18:33:10.492380 2962567286336 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 3 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.492460 2962567286336 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C90FE2B0 win_read
I0818 18:33:10.492577 2962567286336 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state WRITING+MORE -> WRITING [begin write in current thread]
I0818 18:33:10.492694 2962567286336 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 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....'
I0818 18:33:10.492797 2962567286336 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 24 04 00 00 00 00 00 00 02 00 00 00 00 00 03 00 00 00 00 00 04 00 40 00 00 00 05 00 40 00 00 00 06 00 00 20 00 fe 03 00 00 00 01 '..$.....................@.....@...... .......'
I0818 18:33:10.492923 2962567286336 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 04 08 00 00 00 00 00 00 3f 00 01 '..........?..'
I0818 18:33:10.493059 2962567286336 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state WRITING -> IDLE [finish writing]
I0818 18:33:10.493260 2962567286336 ..\..\..\src\core\lib\iomgr\executor.cc:224: EXECUTOR (resolver-executor) [0]: step (sub_depth=1)
I0818 18:33:10.525742 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:187: TCP:000002B1C90FE2B0 on_read
I0818 18:33:10.525860 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:216: READ 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 12 04 00 00 00 00 00 00 03 00 00 00 64 00 04 00 01 80 00 00 06 00 00 80 00 00 00 04 08 00 00 00 00 00 00 01 00 01 00 00 00 04 01 00 00 00 00 '..............d..................................'
I0818 18:33:10.526611 0 ..\..\..\src\core\ext\transport\chttp2\transport\frame_settings.cc:231: CHTTP2:CLI:ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002: got setting MAX_CONCURRENT_STREAMS = 100
I0818 18:33:10.526739 0 ..\..\..\src\core\ext\transport\chttp2\transport\frame_settings.cc:224: 000002B1C95DC660[cli] adding 32769 for initial_window change
I0818 18:33:10.526851 0 ..\..\..\src\core\ext\transport\chttp2\transport\frame_settings.cc:231: CHTTP2:CLI:ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002: got setting INITIAL_WINDOW_SIZE = 98304
I0818 18:33:10.526913 0 ..\..\..\src\core\ext\transport\chttp2\transport\frame_settings.cc:231: CHTTP2:CLI:ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002: got setting MAX_HEADER_LIST_SIZE = 32768
I0818 18:33:10.527031 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C90FE2B0 win_read
I0818 18:33:10.527105 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1C928F6B0: CANCEL pending=true
I0818 18:33:10.527225 0 ..\..\..\src\core\ext\filters\client_channel\subchannel.cc:1065: New connected subchannel at 000002B1C92B7680 for subchannel 000002B1C6BBD180
I0818 18:33:10.527297 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1842: perform_transport_op[t=000002B1C95DC660]: START_CONNECTIVITY_WATCH:watcher=0x2b1c92b7860:from=READY BIND_POLLSET_SET
I0818 18:33:10.527413 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:628: chand=000002B1C90A3528: connectivity change for subchannel wrapper 000002B1C6BCC1A0 subchannel 000002B1C6BBD180; hopping into work_serializer
I0818 18:33:10.527515 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:663: chand=000002B1C90A3528: processing connectivity change in work serializer for subchannel wrapper 000002B1C6BCC1A0 subchannel 000002B1C6BBD180 watcher=000002B1C92578E0
I0818 18:33:10.527723 0 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:243: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 0 of 6 (subchannel 000002B1C6BCC1A0): connectivity changed: state=READY, shutting_down=0, pending_watcher=000002B1C92578E0
I0818 18:33:10.527942 0 ..\..\..\src\core\ext\filters\client_channel\lb_policy\pick_first\pick_first.cc:450: Pick First 000002B1C92903F0 selected subchannel 000002B1C6BCC1A0
I0818 18:33:10.528171 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:995: chand=000002B1C90A3528: update: state=READY status=(OK) picker=000002B1C9257240
I0818 18:33:10.528316 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:156: ConnectivityStateTracker client_channel[000002B1C90A35E8]: CONNECTING -> READY (helper, OK)
I0818 18:33:10.528491 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:1647: chand=000002B1C90A3528: updating subchannel wrapper 000002B1C6BCC1A0 data plane connected_subchannel to 000002B1C92B7680
I0818 18:33:10.528607 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2988: chand=000002B1C90A3528 lb_call=000002B1C9072770: LB pick returned COMPLETE (subchannel=000002B1C6BCC1A0, error="No Error")
I0818 18:33:10.528723 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2894: chand=000002B1C90A3528 lb_call=000002B1C9072770: removing from queued picks list
I0818 18:33:10.528842 0 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:287: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 1 of 6 (subchannel 000002B1C6BCCD20): unreffing subchannel (shutdown)
I0818 18:33:10.529052 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:476: chand=000002B1C90A3528: destroying subchannel wrapper 000002B1C6BCCD20 for subchannel 000002B1C6BBCAA0
I0818 18:33:10.529214 0 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:287: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 2 of 6 (subchannel 000002B1C6BCC4A0): unreffing subchannel (shutdown)
I0818 18:33:10.529435 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:476: chand=000002B1C90A3528: destroying subchannel wrapper 000002B1C6BCC4A0 for subchannel 000002B1C6BBCEC0
I0818 18:33:10.529564 0 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:287: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 3 of 6 (subchannel 000002B1C6BCC520): unreffing subchannel (shutdown)
I0818 18:33:10.529770 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:476: chand=000002B1C90A3528: destroying subchannel wrapper 000002B1C6BCC520 for subchannel 000002B1C6BBD2E0
I0818 18:33:10.529893 0 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:287: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 4 of 6 (subchannel 000002B1C6BCC8A0): unreffing subchannel (shutdown)
I0818 18:33:10.530018 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:476: chand=000002B1C90A3528: destroying subchannel wrapper 000002B1C6BCC8A0 for subchannel 000002B1C92B4C00
I0818 18:33:10.530146 0 T:\src\github\grpc\workspace_csharp_ext_windows_x64\src/core/ext/filters/client_channel/lb_policy/subchannel_list.h:287: [pick_first 000002B1C92903F0] subchannel list 000002B1A6421D40 index 5 of 6 (subchannel 000002B1C6BCC920): unreffing subchannel (shutdown)
I0818 18:33:10.530338 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:476: chand=000002B1C90A3528: destroying subchannel wrapper 000002B1C6BCC920 for subchannel 000002B1C92B47E0
I0818 18:33:10.530466 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2830: chand=000002B1C90A3528 lb_call=000002B1C9072770: create subchannel_call=000002B1C94D80E0: error="No Error"
I0818 18:33:10.530574 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2659: chand=000002B1C90A3528 lb_call=000002B1C9072770: starting 1 pending batches on subchannel_call=000002B1C94D80E0
I0818 18:33:10.530691 0 ..\..\..\src\core\ext\filters\client_channel\subchannel.cc:175: OP[authority:000002B1C94D8190]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.530891 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_size:000002B1C94D81A8]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.531028 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[http-client:000002B1C94D81C0]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.531177 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_decompress:000002B1C94D81D8]: 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 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod', 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 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.531744 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_compress:000002B1C94D81F0]: 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 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod', 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 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.532273 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[connected:000002B1C94D8208]: 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 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod', 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 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)', 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=61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.532665 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1646: perform_stream_op[s=000002B1C94D8E90]: 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 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod', 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 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)', 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=61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0818 18:33:10.533047 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:121: ConnectivityStateTracker client_transport[000002B1C95DC908]: add watcher 000002B1C92B7860
I0818 18:33:10.533173 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1386: perform_stream_op_locked: 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 61 75 74 68 6f 72 69 74 79 ':authority' value=4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002', key=3a 70 61 74 68 ':path' value=2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod', 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 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)', 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=61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=13 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA; on_complete = 000002B1C94D8C98
I0818 18:33:10.533704 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: :scheme: http
I0818 18:33:10.533840 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: :method: POST
I0818 18:33:10.533921 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: :authority: MATEJ-ZABSKY:8002
I0818 18:33:10.534019 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: :path: /AS.TestInterface/TestMethod
I0818 18:33:10.534070 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: te: trailers
I0818 18:33:10.534118 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: content-type: application/grpc
I0818 18:33:10.534168 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: user-agent: grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)
I0818 18:33:10.534245 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: grpc-accept-encoding: identity,deflate,gzip
I0818 18:33:10.534296 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1363: HTTP:0:HDR:CLI: accept-encoding: identity,gzip
I0818 18:33:10.534346 0 ..\..\..\src\core\ext\transport\chttp2\transport\stream_lists.cc:125: 000002B1C95DC660[0][cli]: add to waiting_for_concurrency
I0818 18:33:10.534396 0 ..\..\..\src\core\ext\transport\chttp2\transport\stream_lists.cc:71: 000002B1C95DC660[0][cli]: pop from waiting_for_concurrency
I0818 18:33:10.534447 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1161: HTTP:CLI: Transport 000002B1C95DC660 allocating new grpc_chttp2_stream 000002B1C94D8E90 to id 1
I0818 18:33:10.534500 0 ..\..\..\src\core\ext\transport\chttp2\transport\stream_lists.cc:125: 000002B1C95DC660[1][cli]: add to writable
I0818 18:33:10.534550 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state IDLE -> WRITING [START_NEW_STREAM]
I0818 18:33:10.534610 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state WRITING -> WRITING+MORE [SEND_MESSAGE]
I0818 18:33:10.534677 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1224: complete_closure_step: t=000002B1C95DC660 000002B1C94D8C98 refs=3 flags=0x0001 desc=op->on_complete err="No Error" write_state=WRITING+MORE
I0818 18:33:10.534734 0 ..\..\..\src\core\ext\transport\chttp2\transport\stream_lists.cc:71: 000002B1C95DC660[1][cli]: pop from writable
I0818 18:33:10.534787 0 ..\..\..\src\core\ext\transport\chttp2\transport\writing.cc:440: W:000002B1C95DC660 CLIENT[1] im-(sent,send)=(0,1) announce=5
I0818 18:33:10.534841 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: ':authority: MATEJ-ZABSKY:8002', elem_interned=1 [3], k_interned=1, v_interned=1
I0818 18:33:10.534898 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: ':path: /AS.TestInterface/TestMethod', elem_interned=0 [2], k_interned=1, v_interned=0
I0818 18:33:10.534951 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: 'te: trailers', elem_interned=1 [1], k_interned=1, v_interned=1
I0818 18:33:10.535014 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: 'content-type: application/grpc', elem_interned=1 [1], k_interned=1, v_interned=1
I0818 18:33:10.535068 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: 'user-agent: grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)', elem_interned=1 [3], k_interned=1, v_interned=1
I0818 18:33:10.535152 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: 'grpc-accept-encoding: identity,deflate,gzip', elem_interned=1 [1], k_interned=1, v_interned=1
I0818 18:33:10.535205 0 ..\..\..\src\core\ext\transport\chttp2\transport\hpack_encoder.cc:617: Encode: 'accept-encoding: identity,gzip', elem_interned=1 [1], k_interned=1, v_interned=1
I0818 18:33:10.535264 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1224: complete_closure_step: t=000002B1C95DC660 000002B1C94D8C98 refs=2 flags=0x0001 desc=send_initial_metadata_finished err="No Error" write_state=WRITING+MORE
I0818 18:33:10.535336 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1224: complete_closure_step: t=000002B1C95DC660 000002B1C94D8C98 refs=1 flags=0x0001 desc=send_trailing_metadata_finished err="No Error" write_state=WRITING+MORE
I0818 18:33:10.535399 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1224: complete_closure_step: t=000002B1C95DC660 000002B1C94D8C98 refs=0 flags=0x0001 desc=on_write_finished_cb err="No Error" write_state=WRITING+MORE
I0818 18:33:10.535460 0 ..\..\..\src\core\ext\transport\chttp2\transport\stream_lists.cc:125: 000002B1C95DC660[1][cli]: add to writing
I0818 18:33:10.535512 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state WRITING+MORE -> WRITING [begin write in current thread]
I0818 18:33:10.535571 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 00 04 01 00 00 00 00 '.........'
I0818 18:33:10.535623 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 01 27 01 04 00 00 00 01 86 83 40 0a '..'........@.'
I0818 18:33:10.535676 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 3a 61 75 74 68 6f 72 69 74 79 ':authority'
I0818 18:33:10.535727 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 11 '.'
I0818 18:33:10.535778 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002'
I0818 18:33:10.535830 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 40 05 '@.'
I0818 18:33:10.535881 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 3a 70 61 74 68 ':path'
I0818 18:33:10.535932 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 1c '.'
I0818 18:33:10.535983 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'
I0818 18:33:10.536064 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 40 02 '@.'
I0818 18:33:10.536115 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 74 65 'te'
I0818 18:33:10.536164 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 08 '.'
I0818 18:33:10.536214 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 74 72 61 69 6c 65 72 73 'trailers'
I0818 18:33:10.536267 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 40 0c '@.'
I0818 18:33:10.536318 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type'
I0818 18:33:10.536370 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 10 '.'
I0818 18:33:10.536421 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'
I0818 18:33:10.536473 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 40 0a '@.'
I0818 18:33:10.536524 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 75 73 65 72 2d 61 67 65 6e 74 'user-agent'
I0818 18:33:10.536575 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 5f '_'
I0818 18:33:10.536627 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 67 72 70 63 2d 63 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)'
I0818 18:33:10.536772 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 40 14 '@.'
I0818 18:33:10.536845 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding'
I0818 18:33:10.536921 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 15 '.'
I0818 18:33:10.536972 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip'
I0818 18:33:10.537027 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 40 0f '@.'
I0818 18:33:10.537078 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'accept-encoding'
I0818 18:33:10.537130 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 0d '.'
I0818 18:33:10.537180 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip'
I0818 18:33:10.537235 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 04 08 00 00 00 00 01 00 00 00 05 00 00 12 00 01 00 00 00 01 00 '.......................'
I0818 18:33:10.537291 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 00 0d 0a 0b 48 65 6c 6c 6f 20 77 6f 72 6c 64 00 00 04 08 00 00 '......Hello world......'
I0818 18:33:10.537347 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 00 00 00 00 05 '.......'
I0818 18:33:10.537430 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1C95DC660 CLIENT [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002] state WRITING -> IDLE [finish writing]
I0818 18:33:10.537484 0 ..\..\..\src\core\ext\transport\chttp2\transport\stream_lists.cc:71: 000002B1C95DC660[1][cli]: pop from writing
info: Microsoft.AspNetCore.Hosting.Diagnostics[1]
Request starting HTTP/2 POST http://MATEJ-ZABSKY:8002/AS.TestInterface/TestMethod application/grpc -
info: Microsoft.AspNetCore.Routing.EndpointMiddleware[0]
Executing endpoint 'route1'
info: Yarp.ReverseProxy.Forwarder.HttpForwarder[9]
Proxying to http://matej-zabsky:8003/AS.TestInterface/TestMethod
I0818 18:33:10.644006 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1C9290CD8: SET 125251 now 5251 call 000002B1C9290D08[00007FFBB79AC062]
I0818 18:33:10.644254 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 15 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.644391 0 ..\..\..\src\core\lib\channel\handshaker.cc:88: handshake_manager 000002B1C9290C70: error="No Error" shutdown=0 index=0, args={endpoint=0x2b1c6b8a890, args=0x2b1c9257a20 {size=0: }, read_buffer=0x2b1c962fb00 (length=0), exit_early=0}
I0818 18:33:10.644587 0 ..\..\..\src\core\lib\channel\handshaker.cc:121: handshake_manager 000002B1C9290C70: handshaking complete -- scheduling on_handshake_done with error="No Error"
I0818 18:33:10.644719 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1C9290CD8: CANCEL pending=true
I0818 18:33:10.644909 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1A6423C60: SET 7205251 now 5251 call 000002B1A6423BE0[00007FFBB7C460C0]
I0818 18:33:10.645025 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 0 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.645152 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state IDLE -> WRITING [TRANSPORT_FLOW_CONTROL]
I0818 18:33:10.645280 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state WRITING -> WRITING+MORE [INITIAL_WRITE]
I0818 18:33:10.645368 0 ..\..\..\src\core\lib\surface\init.cc:167: grpc_init(void)
I0818 18:33:10.645488 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1842: perform_transport_op[t=000002B1A6422960]: START_CONNECTIVITY_WATCH:watcher=0x2b1c92b7320:from=IDLE SET_ACCEPT_STREAM:0x7ffbb79b87b3(0x2b1c9148e88,...)
I0818 18:33:10.645607 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1C95BC148: SET 125251 now 5251 call 000002B1C95BC178[00007FFBB7ADAE80]
I0818 18:33:10.645727 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 11 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.645865 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:121: ConnectivityStateTracker server_transport[000002B1A6422C08]: add watcher 000002B1C92B7320
I0818 18:33:10.645991 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:127: ConnectivityStateTracker server_transport[000002B1A6422C08]: notifying watcher 000002B1C92B7320: IDLE -> READY
I0818 18:33:10.646134 0 ..\..\..\src\core\lib\transport\connectivity_state.cc:79: watcher 000002B1C92B7320: delivering async notification for READY (OK)
I0818 18:33:10.646244 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1A6423C60: CANCEL pending=true
I0818 18:33:10.646324 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C6B8A890 win_read
I0818 18:33:10.646382 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1A6423C60: SET 7205251 now 5251 call 000002B1A6423BE0[00007FFBB7C460C0]
I0818 18:33:10.646439 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 0 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.646498 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state WRITING+MORE -> WRITING [begin write in current thread]
I0818 18:33:10.646560 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C6B8A890 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363): 00 00 18 04 00 00 00 00 00 00 04 00 40 00 00 00 05 00 40 00 00 00 06 00 00 20 00 fe 03 00 00 00 01 '............@.....@...... .......'
I0818 18:33:10.646668 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C6B8A890 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363): 00 00 04 08 00 00 00 00 00 00 3f 00 01 '..........?..'
I0818 18:33:10.646769 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state WRITING -> IDLE [finish writing]
I0818 18:33:10.656893 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:187: TCP:000002B1C6B8A890 on_read
I0818 18:33:10.656995 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:216: READ 000002B1C6B8A890 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363): 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 06 04 00 00 00 00 00 00 02 00 00 00 00 00 00 04 08 00 00 00 00 00 03 ff 00 01 'PRI * HTTP/2.0....SM................................'
I0818 18:33:10.657110 0 ..\..\..\src\core\ext\transport\chttp2\transport\frame_settings.cc:231: CHTTP2:SVR:ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363: got setting ENABLE_PUSH = 0
I0818 18:33:10.657173 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1A6423C60: CANCEL pending=true
I0818 18:33:10.657218 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C6B8A890 win_read
I0818 18:33:10.657329 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1C95BC148: CANCEL pending=true
I0818 18:33:10.659814 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1A6423C60: SET 7205267 now 5267 call 000002B1A6423BE0[00007FFBB7C460C0]
I0818 18:33:10.659972 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 0 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.662600 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:187: TCP:000002B1C6B8A890 on_read
I0818 18:33:10.662747 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:216: READ 000002B1C6B8A890 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363): 00 00 00 04 01 00 00 00 00 '.........'
I0818 18:33:10.662886 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1A6423C60: CANCEL pending=true
I0818 18:33:10.662996 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C6B8A890 win_read
I0818 18:33:10.663141 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1A6423C60: SET 7205270 now 5270 call 000002B1A6423BE0[00007FFBB7C460C0]
I0818 18:33:10.663252 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 0 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.673070 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:187: TCP:000002B1C6B8A890 on_read
I0818 18:33:10.673906 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:216: READ 000002B1C6B8A890 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363): 00 01 93 01 04 00 00 00 01 83 86 01 11 6d 61 74 65 6a 2d 7a 61 62 73 6b 79 3a 38 30 30 33 04 1c 2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 0f 01 0d 69 64 65 6e 74 69 74 79 2c 67 7a 69 70 00 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 00 0f 78 2d 66 6f 72 77 61 72 64 65 64 2d 66 6f 72 1c 66 65 38 30 3a 3a 34 34 38 63 3a 32 61 35 30 3a 61 34 62 30 3a 37 65 34 31 25 31 30 0f 2b 5f 67 72 70 63 2d 63 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 00 10 78 2d 66 6f 72 77 61 72 64 65 64 2d 68 6f 73 74 11 4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 00 11 78 2d 66 6f 72 77 61 72 64 65 64 2d 70 72 6f 74 6f 04 68 74 74 70 00 0b 74 72 61 63 65 70 61 72 65 6e 74 37 30 30 2d 36 36 61 39 30 35 32 63 38 38 36 32 38 63 34 35 61 37 30 63 30 65 61 37 35 38 65 31 66 33 35 33 2d 39 37 30 34 66 34 33 61 38 65 39 33 34 31 34 39 2d 30 30 0f 10 10 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 '.............matej-zabsky:8003../AS.TestInterface/TestMethod...identity,gzip..grpc-accept-encoding.identity,deflate,gzip..x-forwarded-for.fe80::448c:2a50:a4b0:7e41%10.+_grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)..x-forwarded-host.MATEJ-ZABSKY:8002..x-forwarded-proto.http..traceparent700-66a9052c88628c45a70c0ea758e1f353-9704f43a8e934149-00...application/grpc'
I0818 18:33:10.685576 0 ..\..\..\src\core\lib\surface\call.cc:1573: ops[0]: RECV_INITIAL_METADATA ptr=0x2b1c9637208
I0818 18:33:10.685698 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:665: parsing initial_metadata
I0818 18:33:10.685786 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: :method: 50 4f 53 54 'POST'
I0818 18:33:10.685880 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: :scheme: 68 74 74 70 'http'
I0818 18:33:10.685953 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: :authority: 6d 61 74 65 6a 2d 7a 61 62 73 6b 79 3a 38 30 30 33 'matej-zabsky:8003'
I0818 18:33:10.686312 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: :path: 2f 41 53 2e 54 65 73 74 49 6e 74 65 72 66 61 63 65 2f 54 65 73 74 4d 65 74 68 6f 64 '/AS.TestInterface/TestMethod'
I0818 18:33:10.686422 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: accept-encoding: 69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip'
I0818 18:33:10.686596 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: grpc-accept-encoding: 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip'
I0818 18:33:10.686714 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: x-forwarded-for: 66 65 38 30 3a 3a 34 34 38 63 3a 32 61 35 30 3a 61 34 62 30 3a 37 65 34 31 25 31 30 'fe80::448c:2a50:a4b0:7e41%10'
I0818 18:33:10.686810 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: user-agent: 67 72 70 63 2d 63 73 68 61 72 70 2f 32 2e 33 39 2e 31 20 28 2e 4e 45 54 20 35 2e 30 2e 39 3b 20 43 4c 52 20 35 2e 30 2e 39 3b 20 6e 65 74 73 74 61 6e 64 61 72 64 32 2e 30 3b 20 78 36 34 29 20 67 72 70 63 2d 63 2f 31 38 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68 74 74 70 32 29 'grpc-csharp/2.39.1 (.NET 5.0.9; CLR 5.0.9; netstandard2.0; x64) grpc-c/18.0.0 (windows; chttp2)'
I0818 18:33:10.686964 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: x-forwarded-host: 4d 41 54 45 4a 2d 5a 41 42 53 4b 59 3a 38 30 30 32 'MATEJ-ZABSKY:8002'
I0818 18:33:10.687060 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: x-forwarded-proto: 68 74 74 70 'http'
I0818 18:33:10.687123 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: traceparent: 30 30 2d 36 36 61 39 30 35 32 63 38 38 36 32 38 63 34 35 61 37 30 63 30 65 61 37 35 38 65 31 66 33 35 33 2d 39 37 30 34 66 34 33 61 38 65 39 33 34 31 34 39 2d 30 30 '00-66a9052c88628c45a70c0ea758e1f353-9704f43a8e934149-00'
I0818 18:33:10.687228 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:414: HTTP:1:HDR:SVR: content-type: 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'
I0818 18:33:10.687335 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:470: TIMER 000002B1A6423C60: CANCEL pending=true
I0818 18:33:10.687398 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C6B8A890 win_read
I0818 18:33:10.687482 0 ..\..\..\src\core\lib\surface\call.cc:641: OP[server:000002B1C96370A0]: RECV_INITIAL_METADATA
I0818 18:33:10.687560 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_size:000002B1C96370B8]: RECV_INITIAL_METADATA
I0818 18:33:10.687636 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[deadline:000002B1C96370D0]: RECV_INITIAL_METADATA
I0818 18:33:10.687706 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[http-server:000002B1C96370E8]: RECV_INITIAL_METADATA
I0818 18:33:10.687782 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_decompress:000002B1C9637100]: RECV_INITIAL_METADATA
I0818 18:33:10.687907 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_compress:000002B1C9637118]: RECV_INITIAL_METADATA
I0818 18:33:10.687971 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[connected:000002B1C9637130]: RECV_INITIAL_METADATA
I0818 18:33:10.688031 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1646: perform_stream_op[s=000002B1C9637EF0]: RECV_INITIAL_METADATA
I0818 18:33:10.688093 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1386: perform_stream_op_locked: RECV_INITIAL_METADATA; on_complete = 0000000000000000
I0818 18:33:10.688334 0 ..\..\..\src\core\lib\surface\call.cc:641: OP[server:000002B1C96370A0]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.690970 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_size:000002B1C96370B8]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.691227 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[deadline:000002B1C96370D0]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.691426 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[http-server:000002B1C96370E8]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.691628 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_decompress:000002B1C9637100]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.691825 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[message_compress:000002B1C9637118]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.692022 0 ..\..\..\src\core\lib\channel\channel_stack.cc:239: OP[connected:000002B1C9637130]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.692254 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1646: perform_stream_op[s=000002B1C9637EF0]: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}
I0818 18:33:10.692452 0 ..\..\..\src\core\lib\surface\call.cc:590: grpc_call_unref(c=000002B1C9636720)
I0818 18:33:10.692554 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:367: TIMER 000002B1A6423C60: SET 7205300 now 5300 call 000002B1A6423BE0[00007FFBB7C460C0]
I0818 18:33:10.692666 0 ..\..\..\src\core\lib\iomgr\timer_generic.cc:404: .. add to shard 0 with queue_deadline_cap=6066 => is_first_timer=false
I0818 18:33:10.692751 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1386: perform_stream_op_locked: CANCEL:{"created":"@1629304390.688000000","description":"Missing :authority or :path","file":"..\..\..\src\core\lib\surface\server.cc","file_line":1421,"referenced_errors":[{"created":"@1629304390.688000000","description":"Failed processing incoming headers","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":123,"referenced_errors":[{"created":"@1629304390.688000000","description":"Missing header","file":"..\..\..\src\core\ext\filters\http\server\http_server_filter.cc","file_line":204,"key":"te"}]}]}; on_complete = 000002B1C90665B0
I0818 18:33:10.692934 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state IDLE -> WRITING [RST_STREAM]
I0818 18:33:10.693097 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:1224: complete_closure_step: t=000002B1A6422960 000002B1C90665B0 refs=0 flags=0x0000 desc=op->on_complete err="No Error" write_state=WRITING
I0818 18:33:10.693213 0 ..\..\..\src\core\lib\surface\metadata_array.cc:34: grpc_metadata_array_destroy(array=000002B1C9637208)
I0818 18:33:10.693376 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state WRITING -> WRITING [begin write in current thread]
I0818 18:33:10.693470 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:364: WRITE 000002B1C6B8A890 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363): 00 00 00 04 01 00 00 00 00 00 00 04 03 00 00 00 00 01 00 00 00 02 '......................'
I0818 18:33:10.693596 0 ..\..\..\src\core\ext\transport\chttp2\transport\chttp2_transport.cc:814: W:000002B1A6422960 SERVER [ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:56363] state WRITING -> IDLE [finish writing]
info: Yarp.ReverseProxy.Forwarder.HttpForwarder[48]
RequestBodyCanceled: Copying the request body was canceled.
System.AggregateException: One or more errors occurred. (A task was canceled.) (An error occurred while sending the request.)
---> System.Threading.Tasks.TaskCanceledException: A task was canceled.
at System.Net.Http.Http2Connection.SendStreamDataAsync(Int32 streamId, ReadOnlyMemory`1 buffer, CancellationToken cancellationToken)
at System.Net.Http.Http2Connection.Http2Stream.SendDataAsync(ReadOnlyMemory`1 buffer, CancellationToken cancellationToken)
at Yarp.ReverseProxy.Forwarder.AutoFlushingStream.WriteAsync(ReadOnlyMemory`1 buffer, CancellationToken cancellationToken)
at Yarp.ReverseProxy.Forwarder.StreamCopier.CopyAsync(Boolean isRequest, Stream input, Stream output, IClock clock, CancellationToken cancellation)
--- End of inner exception stack trace ---
---> (Inner Exception #1) System.Net.Http.HttpRequestException: An error occurred while sending the request.
---> System.IO.IOException: The request was aborted.
---> System.Net.Http.Http2StreamException: The HTTP/2 server reset the stream. HTTP/2 error code 'INTERNAL_ERROR' (0x2).
--- End of inner exception stack trace ---
at System.Net.Http.Http2Connection.ThrowRequestAborted(Exception innerException)
at System.Net.Http.Http2Connection.Http2Stream.CheckResponseBodyState()
at System.Net.Http.Http2Connection.Http2Stream.TryEnsureHeaders()
at System.Net.Http.Http2Connection.Http2Stream.ReadResponseHeadersAsync(CancellationToken cancellationToken)
at System.Net.Http.Http2Connection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
--- End of inner exception stack trace ---
at System.Net.Http.Http2Connection.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken)
at System.Net.Http.HttpConnectionPool.SendWithRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken)
at Yarp.ReverseProxy.Forwarder.HttpForwarder.SendAsync(HttpContext context, String destinationPrefix, HttpMessageInvoker httpClient, ForwarderRequestConfig requestConfig, HttpTransformer transformer)<---
info: Microsoft.AspNetCore.Routing.EndpointMiddleware[1]
Executed endpoint 'route1'
I0818 18:33:10.747299 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:187: TCP:000002B1C90FE2B0 on_read
I0818 18:33:10.747428 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:216: READ 000002B1C90FE2B0 (peer=ipv6:[fe80::448c:2a50:a4b0:7e41%2510]:8002): 00 00 31 01 05 00 00 00 01 48 03 35 30 32 61 1d 57 65 64 2c 20 31 38 20 41 75 67 20 32 30 32 31 20 31 36 3a 33 33 3a 31 30 20 47 4d 54 76 07 4b 65 73 74 72 65 6c 0f 0d 01 30 '..1......H.502a.Wed, 18 Aug 2021 16:33:10 GMTv.Kestrel...0'
I0818 18:33:10.747594 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:659: parsing Trailers-Only
I0818 18:33:10.747752 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:526: HTTP:1:TRL:CLI: :status: 35 30 32 '502'
I0818 18:33:10.747909 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:526: HTTP:1:TRL:CLI: date: 57 65 64 2c 20 31 38 20 41 75 67 20 32 30 32 31 20 31 36 3a 33 33 3a 31 30 20 47 4d 54 'Wed, 18 Aug 2021 16:33:10 GMT'
I0818 18:33:10.748060 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:526: HTTP:1:TRL:CLI: server: 4b 65 73 74 72 65 6c 'Kestrel'
I0818 18:33:10.748147 0 ..\..\..\src\core\ext\transport\chttp2\transport\parsing.cc:526: HTTP:1:TRL:CLI: content-length: 30 '0'
I0818 18:33:10.748203 0 ..\..\..\src\core\lib\iomgr\tcp_windows.cc:253: TCP:000002B1C90FE2B0 win_read
I0818 18:33:10.748292 0 ..\..\..\src\core\lib\surface\completion_queue.cc:786: cq_end_op_for_pluck(cq=000002B1C9083940, tag=000002B1C6CBD7C0, error="No Error", done=00007FFBB7A31A10, done_arg=000002B1C9072550, storage=000002B1C9072598)
I0818 18:33:10.748375 0 ..\..\..\src\core\lib\surface\completion_queue.cc:1319: RETURN_EVENT[000002B1C9083940]: OP_COMPLETE: tag:0x2b1c6cbd7c0 OK
info: Microsoft.AspNetCore.Hosting.Diagnostics[2]
Request finished HTTP/2 POST http://MATEJ-ZABSKY:8002/AS.TestInterface/TestMethod application/grpc - - 502 0 - 189.0942ms
I0818 18:33:10.761373 0 ..\..\..\src\core\lib\surface\call.cc:590: grpc_call_unref(c=000002B1C9071A20)
I0818 18:33:10.761483 0 ..\..\..\src\core\ext\filters\client_channel\client_channel.cc:2869: chand=000002B1C90A3528 lb_call=000002B1C9072770: cancelling queued pick: error="No Error" self=000002B1C9194BD0 calld->pick_canceller=0000000000000000
I0818 18:33:11.463369 2962567298944 ..\..\..\src\core\lib\iomgr\timer_manager.cc:204: wait ended: was_timed:1 kicked:0
I0818 18:33:11.463571 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:719: TIMER CHECK BEGIN: now=6071 next=9223372036854775807 tls_min=4022 glob_min=6067
I0818 18:33:11.464616 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:614: .. shard[5]->min_deadline = 6067
I0818 18:33:11.464681 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[5]: heap_empty=true
I0818 18:33:11.464752 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[5]->queue_deadline_cap --> 7071
I0818 18:33:11.464834 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[5] popped 0
I0818 18:33:11.464890 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[5]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.464981 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[15]: heap_empty=true
I0818 18:33:11.465037 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[15]->queue_deadline_cap --> 7071
I0818 18:33:11.465100 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[15] popped 0
I0818 18:33:11.465154 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[15]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.465211 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[14]: heap_empty=true
I0818 18:33:11.465267 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[14]->queue_deadline_cap --> 7071
I0818 18:33:11.465357 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[14] popped 0
I0818 18:33:11.465462 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[14]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.465558 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[13]: heap_empty=true
I0818 18:33:11.465648 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[13]->queue_deadline_cap --> 7071
I0818 18:33:11.465728 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[13] popped 0
I0818 18:33:11.465805 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[13]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.465888 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[12]: heap_empty=true
I0818 18:33:11.465964 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[12]->queue_deadline_cap --> 7071
I0818 18:33:11.466041 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[12] popped 0
I0818 18:33:11.466111 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[12]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.466185 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[11]: heap_empty=true
I0818 18:33:11.466263 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[11]->queue_deadline_cap --> 7071
I0818 18:33:11.466365 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[11] popped 0
I0818 18:33:11.466409 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[11]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.466459 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[10]: heap_empty=true
I0818 18:33:11.466503 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[10]->queue_deadline_cap --> 7071
I0818 18:33:11.466551 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[10] popped 0
I0818 18:33:11.466596 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[10]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.466645 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[9]: heap_empty=true
I0818 18:33:11.466689 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[9]->queue_deadline_cap --> 7071
I0818 18:33:11.466737 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[9] popped 0
I0818 18:33:11.466782 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[9]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.466831 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[8]: heap_empty=true
I0818 18:33:11.466875 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[8]->queue_deadline_cap --> 7071
I0818 18:33:11.466923 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[8] popped 0
I0818 18:33:11.466967 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[8]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.467016 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[7]: heap_empty=true
I0818 18:33:11.467059 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[7]->queue_deadline_cap --> 7071
I0818 18:33:11.467108 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[7] popped 0
I0818 18:33:11.467152 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[7]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.467200 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[6]: heap_empty=true
I0818 18:33:11.467243 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[6]->queue_deadline_cap --> 7071
I0818 18:33:11.467322 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[6] popped 0
I0818 18:33:11.467366 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[6]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.467415 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[4]: heap_empty=true
I0818 18:33:11.467460 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[4]->queue_deadline_cap --> 7071
I0818 18:33:11.467508 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[4] popped 0
I0818 18:33:11.467552 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[4]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.467600 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[3]: heap_empty=true
I0818 18:33:11.467643 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[3]->queue_deadline_cap --> 7071
I0818 18:33:11.467691 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[3] popped 0
I0818 18:33:11.467735 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[3]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.467783 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[2]: heap_empty=true
I0818 18:33:11.467826 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[2]->queue_deadline_cap --> 7071
I0818 18:33:11.467874 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[2] popped 0
I0818 18:33:11.467918 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[2]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.467968 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[1]: heap_empty=true
I0818 18:33:11.468012 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[1]->queue_deadline_cap --> 7071
I0818 18:33:11.468060 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[1] popped 0
I0818 18:33:11.468104 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[1]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.468152 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:537: .. shard[0]: heap_empty=true
I0818 18:33:11.468195 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:512: .. shard[0]->queue_deadline_cap --> 7071
I0818 18:33:11.468243 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:578: .. shard[0] popped 0
I0818 18:33:11.468320 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:632: .. result --> 1, shard[0]->min_deadline 6067 --> 7072, now=6071
I0818 18:33:11.468370 2962567298944 ..\..\..\src\core\lib\iomgr\timer_generic.cc:741: TIMER CHECK END: r=1; next=7072
I0818 18:33:11.468413 2962567298944 ..\..\..\src\core\lib\iomgr\timer_manager.cc:188: sleep for a 1001 milliseconds
Unhandled exception. Grpc.Core.RpcException: Status(StatusCode="Unavailable", Detail="Received http2 header with status: 502", DebugException="Grpc.Core.Internal.CoreErrorDetailException: {"created":"@1629304390.748000000","description":"Received http2 :status header with non-200 OK status","file":"..\..\..\src\core\ext\filters\http\client\http_client_filter.cc","file_line":133,"grpc_message":"Received http2 header with status: 502","grpc_status":14,"value":"502"}")
at Grpc.Core.Internal.AsyncCall`2.UnaryCall(TRequest msg)
at Grpc.Core.Calls.BlockingUnaryCall[TRequest,TResponse](CallInvocationDetails`2 call, TRequest req)
at Grpc.Core.DefaultCallInvoker.BlockingUnaryCall[TRequest,TResponse](Method`2 method, String host, CallOptions options, TRequest request)
at Grpc.Core.Interceptors.InterceptingCallInvoker.<BlockingUnaryCall>b__3_0[TRequest,TResponse](TRequest req, ClientInterceptorContext`2 ctx)
at Grpc.Core.ClientBase.ClientBaseConfiguration.ClientBaseConfigurationInterceptor.BlockingUnaryCall[TRequest,TResponse](TRequest request, ClientInterceptorContext`2 context, BlockingUnaryCallContinuation`2 continuation)
at Grpc.Core.Interceptors.InterceptingCallInvoker.BlockingUnaryCall[TRequest,TResponse](Method`2 method, String host, CallOptions options, TRequest request)
at GrpcProxyTest.Protobuf.TestInterface.TestInterfaceClient.TestMethod(TestMethodRequest request, CallOptions options) in C:\Users\matej.zabsky\source\repos\GrpcProxyTest\GrpcProxyTest\obj\Debug\net5.0\TestInterfaceGrpc.cs:line 123
at GrpcProxyTest.Protobuf.TestInterface.TestInterfaceClient.TestMethod(TestMethodRequest request, Metadata headers, Nullable`1 deadline, CancellationToken cancellationToken) in C:\Users\matej.zabsky\source\repos\GrpcProxyTest\GrpcProxyTest\obj\Debug\net5.0\TestInterfaceGrpc.cs:line 118
at GrpcProxyTest.Program.Main(String[] args) in C:\Users\matej.zabsky\source\repos\GrpcProxyTest\GrpcProxyTest\Program.cs:line 50
C:\Users\matej.zabsky\source\repos\GrpcProxyTest\GrpcProxyTest\bin\Debug\net5.0\GrpcProxyTest.exe (process 44052) exited with code 0.
To automatically close the console when debugging stops, enable Tools->Options->Debugging->Automatically close the console when debugging stops.
Press any key to close this window . . .
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment