---
# System prepended metadata

title: RPC Submits Analysis
tags: [epfl, semesterproject]

---

# RPC Submits Analysis

I've collected the backtraces of threads which call `socket_submit_outgoing_msg` when a client writes to a file. It is not clear if I've collected all calls, since the execution aborts at various points with the message `-bash: glusterfs-client/testfile: Transport endpoint is not connected`.

The backtraces are written below. The furthest I've gotten is 7 calls.

1. `iotwr` performs `lookup`
2. `iotwr` performs `ping`
3. `iotwr` performs `lookup`
4. `iotwr` performs `ping`
5. `epoll` performs `dump`
6. `epoll` performs `ping`
7. `iotwr` performs `ping`


My guess guess is that for each server, the `iowr`s perform a lookup and the `epoll`s dump the contents to be sent.  I haven't managed to get a clean run to confirm this.

Continue:
- Get a "clean" run with all calls without exception
- Where do the dump calls come from?
- Investigate the `rpc_clnt_submit` function
    - Especially the arguments `prog` and `procnum`
- Thread continuation after `submit` call
- Where is the response by the server handled?



## Trace1 IOT
```c
#0  socket_submit_outgoing_msg (this=0x61a000014a98, msg=0x7fffee9e77b0) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000014a98, req=req@entry=0x7fffee9e77b0)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010458, prog=prog@entry=0x7fffef099540 <clnt4_0_fop_prog>,
    procnum=procnum@entry=27, cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, proghdr=proghdr@entry=0x7fffee9e7e00,
    proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1633
#3  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000021d18, req=req@entry=0x7fffee9e8190,
    frame=frame@entry=0x6110000ffcd8, prog=<optimized out>, procnum=procnum@entry=27,
    cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#4  0x00007fffef02b854 in client4_0_lookup (frame=frame@entry=0x6110000ffcd8, this=this@entry=0x621000021d18,
    data=data@entry=0x7fffee9e8400) at client-rpc-fops_v2.c:2986
#5  0x00007fffeefca376 in client_lookup (frame=frame@entry=0x6110000ffcd8, this=this@entry=0x621000021d18,
    loc=loc@entry=0x61d000310990, xdata=<optimized out>) at client.c:375
#6  0x00007fffeee779d6 in ec_wind_lookup (ec=0x61600001fe98, fop=0x61d000310698, idx=<optimized out>) at ec-generic.c:792
#7  0x00007fffeee66fc2 in ec_dispatch_mask (fop=0x61d000310698, mask=5) at ec-common.c:795
#8  0x00007fffeee676fa in ec_dispatch_all (fop=0x61d000310698) at ec-common.c:877
#9  0x00007fffeee7f84a in ec_manager_lookup (fop=<optimized out>, state=<optimized out>) at ec-generic.c:842
#10 0x00007fffeee63ed1 in __ec_manager (fop=0x61d000310698, error=<optimized out>) at ec-common.c:3017
#11 0x00007fffeee73905 in ec_manager (fop=<optimized out>, error=<optimized out>) at ec-common.c:3035
#12 0x00007fffeee80201 in ec_lookup (frame=0x6110000ff918, this=<optimized out>, target=<optimized out>,
    fop_flags=<optimized out>, func=0x7ffff749a7e0 <default_lookup_cbk>, data=0x0, loc=0x6230001ff150, xdata=0x60d0000191b8)
    at ec-generic.c:944
#13 0x00007fffeee51f74 in ec_gf_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000025918,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ec.c:1164
#14 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000026d18,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#15 0x00007fffeebd28da in gf_utime_lookup (frame=frame@entry=0x6110000ff7d8, this=this@entry=0x621000028118,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ../../../../xlators/features/utime/src/utime.c:260
#16 0x00007fffeeb98231 in wb_lookup_helper (frame=<optimized out>, this=0x621000029518, loc=0x6230001ff150,
    xdata=0x60d0000191b8) at write-behind.c:2379
#17 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001ff118) at call-stub.c:1876
#18 0x00007ffff734df9d in call_resume_keep_stub (stub=0x6230001ff118) at call-stub.c:2455
#19 0x00007fffeeba8fad in wb_do_winds (wb_inode=<optimized out>, tasks=0x7fffee9e89f0) at write-behind.c:1722
#20 0x00007fffeeba9437 in wb_process_queue (wb_inode=wb_inode@entry=0x611000044b18) at write-behind.c:1759
#21 0x00007fffeebb2d45 in wb_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x621000029518,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at write-behind.c:2401
#22 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x62100002a918,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#23 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x6110000ff558, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at quick-read.c:647
#24 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x6110000ff2d8, this=this@entry=0x62100002d118,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at md-cache.c:1337
#25 0x00007ffff74e6e51 in default_lookup_resume (frame=<optimized out>, this=0x62100002f918, loc=0x6230001f9d50,
    xdata=0x60d0000191b8) at defaults.c:2215
#26 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001f9d18) at call-stub.c:1876
#27 0x00007ffff734d8f5 in call_resume (stub=0x6230001f9d18) at call-stub.c:2390
#28 0x00007fffeead68bd in iot_worker (data=0x615000010498) at io-threads.c:227
#29 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#30 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
```
## Trace 2 IOT

```c
#0  socket_submit_outgoing_msg (this=0x61a000014a98, msg=0x7fffee9e6f30) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000014a98, req=req@entry=0x7fffee9e6f30)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010458, prog=prog@entry=0x7ffff72663c0 <clnt_ping_prog>,
    procnum=procnum@entry=2, cbkfn=cbkfn@entry=0x7ffff722b730 <rpc_clnt_ping_cbk>, proghdr=proghdr@entry=0x0,
    proghdrcount=proghdrcount@entry=0, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1633
#3  0x00007ffff722c4ca in rpc_clnt_ping (rpc=rpc@entry=0x614000010458) at rpc-clnt-ping.c:261
#4  0x00007ffff722c9a8 in rpc_clnt_start_ping (rpc_ptr=0x614000010458) at rpc-clnt-ping.c:336
#5  rpc_clnt_start_ping (rpc_ptr=0x614000010458) at rpc-clnt-ping.c:279
#6  0x00007ffff722cb0d in rpc_clnt_check_and_start_ping (rpc=0x614000010458) at rpc-clnt-ping.c:352
#7  0x00007ffff7221e5c in rpc_clnt_submit (rpc=rpc@entry=0x614000010458, prog=prog@entry=0x7fffef099540 <clnt4_0_fop_prog>,
    procnum=procnum@entry=27, cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, proghdr=proghdr@entry=0x7fffee9e7e00,
    proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1674
#8  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000021d18, req=req@entry=0x7fffee9e8190,
    frame=frame@entry=0x6110000ffcd8, prog=<optimized out>, procnum=procnum@entry=27,
    cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#9  0x00007fffef02b854 in client4_0_lookup (frame=frame@entry=0x6110000ffcd8, this=this@entry=0x621000021d18,
    data=data@entry=0x7fffee9e8400) at client-rpc-fops_v2.c:2986
#10 0x00007fffeefca376 in client_lookup (frame=frame@entry=0x6110000ffcd8, this=this@entry=0x621000021d18,
    loc=loc@entry=0x61d000310990, xdata=<optimized out>) at client.c:375
#11 0x00007fffeee779d6 in ec_wind_lookup (ec=0x61600001fe98, fop=0x61d000310698, idx=<optimized out>) at ec-generic.c:792
#12 0x00007fffeee66fc2 in ec_dispatch_mask (fop=0x61d000310698, mask=5) at ec-common.c:795
#13 0x00007fffeee676fa in ec_dispatch_all (fop=0x61d000310698) at ec-common.c:877
#14 0x00007fffeee7f84a in ec_manager_lookup (fop=<optimized out>, state=<optimized out>) at ec-generic.c:842
#15 0x00007fffeee63ed1 in __ec_manager (fop=0x61d000310698, error=<optimized out>) at ec-common.c:3017
#16 0x00007fffeee73905 in ec_manager (fop=<optimized out>, error=<optimized out>) at ec-common.c:3035
#17 0x00007fffeee80201 in ec_lookup (frame=0x6110000ff918, this=<optimized out>, target=<optimized out>,
    fop_flags=<optimized out>, func=0x7ffff749a7e0 <default_lookup_cbk>, data=0x0, loc=0x6230001ff150, xdata=0x60d0000191b8)
    at ec-generic.c:944
#18 0x00007fffeee51f74 in ec_gf_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000025918,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ec.c:1164
#19 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000026d18,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#20 0x00007fffeebd28da in gf_utime_lookup (frame=frame@entry=0x6110000ff7d8, this=this@entry=0x621000028118,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ../../../../xlators/features/utime/src/utime.c:260
    #21 0x00007fffeeb98231 in wb_lookup_helper (frame=<optimized out>, this=0x621000029518, loc=0x6230001ff150,
    xdata=0x60d0000191b8) at write-behind.c:2379
#22 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001ff118) at call-stub.c:1876
#23 0x00007ffff734df9d in call_resume_keep_stub (stub=0x6230001ff118) at call-stub.c:2455
#24 0x00007fffeeba8fad in wb_do_winds (wb_inode=<optimized out>, tasks=0x7fffee9e89f0) at write-behind.c:1722
#25 0x00007fffeeba9437 in wb_process_queue (wb_inode=wb_inode@entry=0x611000044b18) at write-behind.c:1759
#26 0x00007fffeebb2d45 in wb_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x621000029518,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at write-behind.c:2401
#27 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x62100002a918,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#28 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x6110000ff558, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at quick-read.c:647
#29 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x6110000ff2d8, this=this@entry=0x62100002d118,
 loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#28 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x6110000ff558, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at quick-read.c:647
#29 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x6110000ff2d8, this=this@entry=0x62100002d118,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at md-cache.c:1337
#30 0x00007ffff74e6e51 in default_lookup_resume (frame=<optimized out>, this=0x62100002f918, loc=0x6230001f9d50,
    xdata=0x60d0000191b8) at defaults.c:2215
#31 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001f9d18) at call-stub.c:1876
#32 0x00007ffff734d8f5 in call_resume (stub=0x6230001f9d18) at call-stub.c:2390
#33 0x00007fffeead68bd in iot_worker (data=0x615000010498) at io-threads.c:227
#34 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#35 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
```

## Trace 3 IOT
```c
#0  socket_submit_outgoing_msg (this=0x61a000013e98, msg=0x7fffee9e77b0) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000013e98, req=req@entry=0x7fffee9e77b0)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010058, prog=prog@entry=0x7fffef099540 <clnt4_0_fop_prog>,
    procnum=procnum@entry=27, cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, proghdr=proghdr@entry=0x7fffee9e7e00,
    proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1633
#3  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000024518, req=req@entry=0x7fffee9e8190,
    frame=frame@entry=0x61100015ff98, prog=<optimized out>, procnum=procnum@entry=27,
    cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#4  0x00007fffef02b854 in client4_0_lookup (frame=frame@entry=0x61100015ff98, this=this@entry=0x621000024518,
    data=data@entry=0x7fffee9e8400) at client-rpc-fops_v2.c:2986
#5  0x00007fffeefca376 in client_lookup (frame=frame@entry=0x61100015ff98, this=this@entry=0x621000024518,
    loc=loc@entry=0x61d000310990, xdata=<optimized out>) at client.c:375
#6  0x00007fffeee779d6 in ec_wind_lookup (ec=0x61600001fe98, fop=0x61d000310698, idx=<optimized out>) at ec-generic.c:792
#7  0x00007fffeee66fc2 in ec_dispatch_mask (fop=0x61d000310698, mask=1) at ec-common.c:795
#8  0x00007fffeee676fa in ec_dispatch_all (fop=0x61d000310698) at ec-common.c:877
#9  0x00007fffeee7f84a in ec_manager_lookup (fop=<optimized out>, state=<optimized out>) at ec-generic.c:842
#10 0x00007fffeee63ed1 in __ec_manager (fop=0x61d000310698, error=<optimized out>) at ec-common.c:3017
#11 0x00007fffeee73905 in ec_manager (fop=<optimized out>, error=<optimized out>) at ec-common.c:3035
#12 0x00007fffeee80201 in ec_lookup (frame=0x6110000ff918, this=<optimized out>, target=<optimized out>,
    fop_flags=<optimized out>, func=0x7ffff749a7e0 <default_lookup_cbk>, data=0x0, loc=0x6230001ff150, xdata=0x60d0000191b8)
    at ec-generic.c:944
#13 0x00007fffeee51f74 in ec_gf_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000025918,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ec.c:1164
#14 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000026d18,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#15 0x00007fffeebd28da in gf_utime_lookup (frame=frame@entry=0x6110000ff7d8, this=this@entry=0x621000028118,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ../../../../xlators/features/utime/src/utime.c:260
#16 0x00007fffeeb98231 in wb_lookup_helper (frame=<optimized out>, this=0x621000029518, loc=0x6230001ff150,
    xdata=0x60d0000191b8) at write-behind.c:2379
#17 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001ff118) at call-stub.c:1876
#18 0x00007ffff734df9d in call_resume_keep_stub (stub=0x6230001ff118) at call-stub.c:2455
#19 0x00007fffeeba8fad in wb_do_winds (wb_inode=<optimized out>, tasks=0x7fffee9e89f0) at write-behind.c:1722
#20 0x00007fffeeba9437 in wb_process_queue (wb_inode=wb_inode@entry=0x611000044b18) at write-behind.c:1759
#21 0x00007fffeebb2d45 in wb_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x621000029518,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at write-behind.c:2401
#22 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x62100002a918,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#23 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x6110000ff558, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at quick-read.c:647
#24 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x6110000ff2d8, this=this@entry=0x62100002d118,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at md-cache.c:1337
#25 0x00007ffff74e6e51 in default_lookup_resume (frame=<optimized out>, this=0x62100002f918, loc=0x6230001f9d50,
    xdata=0x60d0000191b8) at defaults.c:2215
#26 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001f9d18) at call-stub.c:1876
#27 0x00007ffff734d8f5 in call_resume (stub=0x6230001f9d18) at call-stub.c:2390
#28 0x00007fffeead68bd in iot_worker (data=0x615000010498) at io-threads.c:227
#29 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#30 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:9
```

## Trace 4 IOT

```c
#0  socket_submit_outgoing_msg (this=0x61a000013e98, msg=0x7fffee9e6f30) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000013e98, req=req@entry=0x7fffee9e6f30)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010058, prog=prog@entry=0x7ffff72663c0 <clnt_ping_prog>,
    procnum=procnum@entry=2, cbkfn=cbkfn@entry=0x7ffff722b730 <rpc_clnt_ping_cbk>, proghdr=proghdr@entry=0x0,
    proghdrcount=proghdrcount@entry=0, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1633
#3  0x00007ffff722c4ca in rpc_clnt_ping (rpc=rpc@entry=0x614000010058) at rpc-clnt-ping.c:261
#4  0x00007ffff722c9a8 in rpc_clnt_start_ping (rpc_ptr=0x614000010058) at rpc-clnt-ping.c:336
#5  rpc_clnt_start_ping (rpc_ptr=0x614000010058) at rpc-clnt-ping.c:279
#6  0x00007ffff722cb0d in rpc_clnt_check_and_start_ping (rpc=0x614000010058) at rpc-clnt-ping.c:352
#7  0x00007ffff7221e5c in rpc_clnt_submit (rpc=rpc@entry=0x614000010058, prog=prog@entry=0x7fffef099540 <clnt4_0_fop_prog>,
    procnum=procnum@entry=27, cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, proghdr=proghdr@entry=0x7fffee9e7e00,
    proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1674
#8  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000024518, req=req@entry=0x7fffee9e8190,
    frame=frame@entry=0x61100015ff98, prog=<optimized out>, procnum=procnum@entry=27,
    cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#9  0x00007fffef02b854 in client4_0_lookup (frame=frame@entry=0x61100015ff98, this=this@entry=0x621000024518,
    data=data@entry=0x7fffee9e8400) at client-rpc-fops_v2.c:2986
#10 0x00007fffeefca376 in client_lookup (frame=frame@entry=0x61100015ff98, this=this@entry=0x621000024518,
    loc=loc@entry=0x61d000310990, xdata=<optimized out>) at client.c:375
#11 0x00007fffeee779d6 in ec_wind_lookup (ec=0x61600001fe98, fop=0x61d000310698, idx=<optimized out>) at ec-generic.c:792
#12 0x00007fffeee66fc2 in ec_dispatch_mask (fop=0x61d000310698, mask=1) at ec-common.c:795
#13 0x00007fffeee676fa in ec_dispatch_all (fop=0x61d000310698) at ec-common.c:877
#14 0x00007fffeee7f84a in ec_manager_lookup (fop=<optimized out>, state=<optimized out>) at ec-generic.c:842
#15 0x00007fffeee63ed1 in __ec_manager (fop=0x61d000310698, error=<optimized out>) at ec-common.c:3017
#16 0x00007fffeee73905 in ec_manager (fop=<optimized out>, error=<optimized out>) at ec-common.c:3035
#17 0x00007fffeee80201 in ec_lookup (frame=0x6110000ff918, this=<optimized out>, target=<optimized out>,
    fop_flags=<optimized out>, func=0x7ffff749a7e0 <default_lookup_cbk>, data=0x0, loc=0x6230001ff150, xdata=0x60d0000191b8)
    at ec-generic.c:944
#18 0x00007fffeee51f74 in ec_gf_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000025918,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ec.c:1164
#19 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff918, this=this@entry=0x621000026d18,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#20 0x00007fffeebd28da in gf_utime_lookup (frame=frame@entry=0x6110000ff7d8, this=this@entry=0x621000028118,
    loc=loc@entry=0x6230001ff150, xdata=xdata@entry=0x60d0000191b8) at ../../../../xlators/features/utime/src/utime.c:260
#21 0x00007fffeeb98231 in wb_lookup_helper (frame=<optimized out>, this=0x621000029518, loc=0x6230001ff150,
    xdata=0x60d0000191b8) at write-behind.c:2379
#22 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001ff118) at call-stub.c:1876
#23 0x00007ffff734df9d in call_resume_keep_stub (stub=0x6230001ff118) at call-stub.c:2455
#24 0x00007fffeeba8fad in wb_do_winds (wb_inode=<optimized out>, tasks=0x7fffee9e89f0) at write-behind.c:1722
#25 0x00007fffeeba9437 in wb_process_queue (wb_inode=wb_inode@entry=0x611000044b18) at write-behind.c:1759
#26 0x00007fffeebb2d45 in wb_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x621000029518,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at write-behind.c:2401
#27 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x6110000ff698, this=this@entry=0x62100002a918,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#28 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x6110000ff558, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at quick-read.c:647
#29 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x6110000ff2d8, this=this@entry=0x62100002d118,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at defaults.c:3024
#28 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x6110000ff558, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at quick-read.c:647
#29 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x6110000ff2d8, this=this@entry=0x62100002d118,
    loc=loc@entry=0x6230001f9d50, xdata=xdata@entry=0x60d0000191b8) at md-cache.c:1337
#30 0x00007ffff74e6e51 in default_lookup_resume (frame=<optimized out>, this=0x62100002f918, loc=0x6230001f9d50,
    xdata=0x60d0000191b8) at defaults.c:2215
#31 0x00007ffff731dcfe in call_resume_wind (stub=0x6230001f9d18) at call-stub.c:1876
#32 0x00007ffff734d8f5 in call_resume (stub=0x6230001f9d18) at call-stub.c:2390
#33 0x00007fffeead68bd in iot_worker (data=0x615000010498) at io-threads.c:227
#34 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#35 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
```

## Trace 5 EPOLL T8

```c
#0  socket_submit_outgoing_msg (this=0x61a000014a98, msg=0x7fffef8b7c70) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000014a98, req=req@entry=0x7fffef8b7c70)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010458, prog=prog@entry=0x7fffef09da20 <clnt_dump_prog>,
    procnum=procnum@entry=1, cbkfn=cbkfn@entry=0x7fffef057320 <client_dump_version_cbk>,
    proghdr=proghdr@entry=0x7fffef8b82c0, proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>,
    progpayloadcount=<optimized out>, iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>,
    rsphdr_count=<optimized out>, rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>)
    at rpc-clnt.c:1633
#3  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000021d18, req=req@entry=0x7fffef8b85c0,
    frame=frame@entry=0x6110001763d8, prog=<optimized out>, procnum=procnum@entry=1,
    cbkfn=cbkfn@entry=0x7fffef057320 <client_dump_version_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#4  0x00007fffef0586c9 in client_handshake (this=this@entry=0x621000021d18, rpc=rpc@entry=0x614000010458)
    at client-handshake.c:1117
#5  0x00007fffeefe2781 in client_rpc_notify (rpc=rpc@entry=0x614000010458, mydata=0x621000021d18,
    event=event@entry=RPC_CLNT_CONNECT, data=data@entry=0x0) at client.c:2228
#6  0x00007ffff721f88d in rpc_clnt_notify (trans=trans@entry=0x61a000014a98, mydata=0x614000010488,
    event=event@entry=RPC_TRANSPORT_CONNECT, data=data@entry=0x61a000014a98) at rpc-clnt.c:927
#7  0x00007ffff7219984 in rpc_transport_notify (this=this@entry=0x61a000014a98, event=event@entry=RPC_TRANSPORT_CONNECT,
    data=data@entry=0x61a000014a98) at rpc-transport.c:521
#8  0x00007ffff01911fb in socket_connect_finish (this=this@entry=0x61a000014a98) at socket.c:2465
#9  0x00007ffff019a124 in socket_handle_client_connection_attempt (this=0x61a000014a98) at socket.c:2653
#10 socket_complete_connection (this=0x61a000014a98) at socket.c:2702
#11 socket_event_handler (event_thread_died=0, poll_err=<optimized out>, poll_out=4, poll_in=0, data=0x61a000014a98,
    gen=145, idx=1, fd=7) at socket.c:2759
#12 socket_event_handler (fd=fd@entry=7, idx=idx@entry=1, gen=gen@entry=145, data=data@entry=0x61a000014a98,
    poll_in=<optimized out>, poll_out=4, poll_err=<optimized out>, event_thread_died=<optimized out>) at socket.c:2710
#13 0x00007ffff73fa6c1 in event_dispatch_epoll_handler (event=0x7fffef8b8fe0, event_pool=0x629000000218) at event-epoll.c:631
#14 event_dispatch_epoll_worker (data=<optimized out>) at event-epoll.c:742
#15 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#16 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95

```

## Trace 6 EPOLL T8

```c
#0  socket_submit_outgoing_msg (this=0x61a000014a98, msg=0x7fffef8b73f0) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000014a98, req=req@entry=0x7fffef8b73f0)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010458, prog=prog@entry=0x7ffff72663c0 <clnt_ping_prog>,
    procnum=procnum@entry=2, cbkfn=cbkfn@entry=0x7ffff722b730 <rpc_clnt_ping_cbk>, proghdr=proghdr@entry=0x0,
    proghdrcount=proghdrcount@entry=0, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1633
#3  0x00007ffff722c4ca in rpc_clnt_ping (rpc=rpc@entry=0x614000010458) at rpc-clnt-ping.c:261
#4  0x00007ffff722c9a8 in rpc_clnt_start_ping (rpc_ptr=0x614000010458) at rpc-clnt-ping.c:336
#5  rpc_clnt_start_ping (rpc_ptr=0x614000010458) at rpc-clnt-ping.c:279
#6  0x00007ffff722cb0d in rpc_clnt_check_and_start_ping (rpc=0x614000010458) at rpc-clnt-ping.c:352
#7  0x00007ffff7221e5c in rpc_clnt_submit (rpc=rpc@entry=0x614000010458, prog=prog@entry=0x7fffef09da20 <clnt_dump_prog>,
    procnum=procnum@entry=1, cbkfn=cbkfn@entry=0x7fffef057320 <client_dump_version_cbk>,
    proghdr=proghdr@entry=0x7fffef8b82c0, proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>,
    progpayloadcount=<optimized out>, iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>,
    rsphdr_count=<optimized out>, rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>)
    at rpc-clnt.c:1674
#8  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000021d18, req=req@entry=0x7fffef8b85c0,
    frame=frame@entry=0x6110001763d8, prog=<optimized out>, procnum=procnum@entry=1,
    cbkfn=cbkfn@entry=0x7fffef057320 <client_dump_version_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#9  0x00007fffef0586c9 in client_handshake (this=this@entry=0x621000021d18, rpc=rpc@entry=0x614000010458)
    at client-handshake.c:1117
#10 0x00007fffeefe2781 in client_rpc_notify (rpc=rpc@entry=0x614000010458, mydata=0x621000021d18,
    event=event@entry=RPC_CLNT_CONNECT, data=data@entry=0x0) at client.c:2228
#11 0x00007ffff721f88d in rpc_clnt_notify (trans=trans@entry=0x61a000014a98, mydata=0x614000010488,
    event=event@entry=RPC_TRANSPORT_CONNECT, data=data@entry=0x61a000014a98) at rpc-clnt.c:927
#12 0x00007ffff7219984 in rpc_transport_notify (this=this@entry=0x61a000014a98, event=event@entry=RPC_TRANSPORT_CONNECT,
    data=data@entry=0x61a000014a98) at rpc-transport.c:521
#13 0x00007ffff01911fb in socket_connect_finish (this=this@entry=0x61a000014a98) at socket.c:2465
#14 0x00007ffff019a124 in socket_handle_client_connection_attempt (this=0x61a000014a98) at socket.c:2653
#15 socket_complete_connection (this=0x61a000014a98) at socket.c:2702
#16 socket_event_handler (event_thread_died=0, poll_err=<optimized out>, poll_out=4, poll_in=0, data=0x61a000014a98,
    gen=145, idx=1, fd=7) at socket.c:2759
#17 socket_event_handler (fd=fd@entry=7, idx=idx@entry=1, gen=gen@entry=145, data=data@entry=0x61a000014a98,
    poll_in=<optimized out>, poll_out=4, poll_err=<optimized out>, event_thread_died=<optimized out>) at socket.c:2710
#18 0x00007ffff73fa6c1 in event_dispatch_epoll_handler (event=0x7fffef8b8fe0, event_pool=0x629000000218) at event-epoll.c:631
#19 event_dispatch_epoll_worker (data=<optimized out>) at event-epoll.c:742
#20 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#21 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
```

## Trace 7 IOT
```c
#0  socket_submit_outgoing_msg (this=0x61a000013e98, msg=0x7fffee9e6f30) at socket.c:3641
#1  0x00007ffff7218e4a in rpc_transport_submit_request (this=0x61a000013e98, req=req@entry=0x7fffee9e6f30)
    at rpc-transport.c:388
#2  0x00007ffff7221db9 in rpc_clnt_submit (rpc=rpc@entry=0x614000010058, prog=prog@entry=0x7ffff72663c0 <clnt_ping_prog>,
    procnum=procnum@entry=2, cbkfn=cbkfn@entry=0x7ffff722b730 <rpc_clnt_ping_cbk>, proghdr=proghdr@entry=0x0,
    proghdrcount=proghdrcount@entry=0, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1633
#3  0x00007ffff722c4ca in rpc_clnt_ping (rpc=rpc@entry=0x614000010058) at rpc-clnt-ping.c:261
#4  0x00007ffff722c9a8 in rpc_clnt_start_ping (rpc_ptr=0x614000010058) at rpc-clnt-ping.c:336
#5  rpc_clnt_start_ping (rpc_ptr=0x614000010058) at rpc-clnt-ping.c:279
#6  0x00007ffff722cb0d in rpc_clnt_check_and_start_ping (rpc=0x614000010058) at rpc-clnt-ping.c:352
#7  0x00007ffff7221e5c in rpc_clnt_submit (rpc=rpc@entry=0x614000010058, prog=prog@entry=0x7fffef099540 <clnt4_0_fop_prog>,
    procnum=procnum@entry=27, cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, proghdr=proghdr@entry=0x7fffee9e7e00,
    proghdrcount=proghdrcount@entry=1, progpayload=<optimized out>, progpayloadcount=<optimized out>,
    iobref=<optimized out>, frame=<optimized out>, rsphdr=<optimized out>, rsphdr_count=<optimized out>,
    rsp_payload=<optimized out>, rsp_payload_count=<optimized out>, rsp_iobref=<optimized out>) at rpc-clnt.c:1674
#8  0x00007fffeefe3bb7 in client_submit_request (this=this@entry=0x621000024518, req=req@entry=0x7fffee9e8190,
    frame=frame@entry=0x61100018b558, prog=<optimized out>, procnum=procnum@entry=27,
    cbkfn=cbkfn@entry=0x7fffef0091e0 <client4_0_lookup_cbk>, cp=<optimized out>, xdrproc=<optimized out>) at client.c:261
#9  0x00007fffef02b854 in client4_0_lookup (frame=frame@entry=0x61100018b558, this=this@entry=0x621000024518,
    data=data@entry=0x7fffee9e8400) at client-rpc-fops_v2.c:2986
#10 0x00007fffeefca376 in client_lookup (frame=frame@entry=0x61100018b558, this=this@entry=0x621000024518,
    loc=loc@entry=0x61d00031b390, xdata=<optimized out>) at client.c:375
#11 0x00007fffeee779d6 in ec_wind_lookup (ec=0x61600001fe98, fop=0x61d00031b098, idx=<optimized out>) at ec-generic.c:792
#12 0x00007fffeee66fc2 in ec_dispatch_mask (fop=0x61d00031b098, mask=1) at ec-common.c:795
#13 0x00007fffeee676fa in ec_dispatch_all (fop=0x61d00031b098) at ec-common.c:877
#14 0x00007fffeee7f84a in ec_manager_lookup (fop=<optimized out>, state=<optimized out>) at ec-generic.c:842
#15 0x00007fffeee63ed1 in __ec_manager (fop=0x61d00031b098, error=<optimized out>) at ec-common.c:3017
#16 0x00007fffeee73905 in ec_manager (fop=<optimized out>, error=<optimized out>) at ec-common.c:3035
#17 0x00007fffeee80201 in ec_lookup (frame=0x61100018add8, this=<optimized out>, target=<optimized out>,
    fop_flags=<optimized out>, func=0x7ffff749a7e0 <default_lookup_cbk>, data=0x0, loc=0x62300022ad50, xdata=0x60d000019c48)
    at ec-generic.c:944
#18 0x00007fffeee51f74 in ec_gf_lookup (frame=frame@entry=0x61100018add8, this=this@entry=0x621000025918,
    loc=loc@entry=0x62300022ad50, xdata=xdata@entry=0x60d000019c48) at ec.c:1164
#19 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x61100018add8, this=this@entry=0x621000026d18,
    loc=loc@entry=0x62300022ad50, xdata=xdata@entry=0x60d000019c48) at defaults.c:3024
#20 0x00007fffeebd28da in gf_utime_lookup (frame=frame@entry=0x61100018ac98, this=this@entry=0x621000028118,
    loc=loc@entry=0x62300022ad50, xdata=xdata@entry=0x60d000019c48) at ../../../../xlators/features/utime/src/utime.c:260
    #21 0x00007fffeeb98231 in wb_lookup_helper (frame=<optimized out>, this=0x621000029518, loc=0x62300022ad50,
    xdata=0x60d000019c48) at write-behind.c:2379
#22 0x00007ffff731dcfe in call_resume_wind (stub=0x62300022ad18) at call-stub.c:1876
#23 0x00007ffff734df9d in call_resume_keep_stub (stub=0x62300022ad18) at call-stub.c:2455
#24 0x00007fffeeba8fad in wb_do_winds (wb_inode=<optimized out>, tasks=0x7fffee9e89f0) at write-behind.c:1722
#25 0x00007fffeeba9437 in wb_process_queue (wb_inode=wb_inode@entry=0x611000044b18) at write-behind.c:1759
#26 0x00007fffeebb2d45 in wb_lookup (frame=frame@entry=0x61100018ab58, this=this@entry=0x621000029518,
    loc=loc@entry=0x623000235550, xdata=xdata@entry=0x60d000019c48) at write-behind.c:2401
#27 0x00007ffff7484070 in default_lookup (frame=frame@entry=0x61100018ab58, this=this@entry=0x62100002a918,
    loc=loc@entry=0x623000235550, xdata=xdata@entry=0x60d000019c48) at defaults.c:3024
#28 0x00007fffeeb56b2d in qr_lookup (frame=frame@entry=0x61100018aa18, this=this@entry=0x62100002bd18,
    loc=loc@entry=0x623000235550, xdata=xdata@entry=0x60d000019c48) at quick-read.c:647
#29 0x00007fffeeb319e3 in mdc_lookup (frame=frame@entry=0x61100018a798, this=this@entry=0x62100002d118,
    loc=loc@entry=0x623000235550, xdata=xdata@entry=0x60d000019c48) at md-cache.c:1337
#30 0x00007ffff74e6e51 in default_lookup_resume (frame=<optimized out>, this=0x62100002f918, loc=0x623000235550,
    xdata=0x60d000019c48) at defaults.c:2215
#31 0x00007ffff731dcfe in call_resume_wind (stub=0x623000235518) at call-stub.c:1876
#32 0x00007ffff734d8f5 in call_resume (stub=0x623000235518) at call-stub.c:2390
#33 0x00007fffeead68bd in iot_worker (data=0x615000010498) at io-threads.c:227
#34 0x00007ffff71bf609 in start_thread (arg=<optimized out>) at pthread_create.c:477
#35 0x00007ffff70e4103 in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
```