Downloading large static files hangs forever once it hits specific length with Sendfile, AIO and IO_uring #467
Description
Hi there,
I'm reporting what I think is a bug.
I have been testing Internal Redirect via PHP script as well as directly downloading a large file via browser in Alma Linux 10 with Kernel 6.17.5-1.el10.elrepo.x86_64 x86_64, having I failed via streaming due to Max Dynamic Response Body Size limits.
Once downloading reaches 579544816 bytes out of 9169479408 or any other large files size, it hangs, the connections remains open likes so
Also, through wget
eventually throwing:-
Connection closed at byte 579544816. Retrying.
......
HTTP request sent, awaiting response...
And stays like this
This is the last segment of the debug which I think would be useful when you compare it to previous successful io_uring_submit to hint out for the debug:-
025-10-29 22:42:57.175987 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 2097152 return 2097152.
2025-10-29 22:42:57.176006 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 2097152, buffered = 0.
2025-10-29 22:42:57.176035 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.176078 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 2097152 bytes (0x241bcbc0) at 568328192
2025-10-29 22:42:57.176398 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.176435 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 2097152 bytes at 568328192
2025-10-29 22:42:57.176466 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.176498 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8601151216
2025-10-29 22:42:57.176537 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 568328192, 2097152 bytes diff: 0
2025-10-29 22:42:57.176566 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 2097152, remain: 8601151216
2025-10-29 22:42:57.176964 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d85ff000, 2097152) written 981960, total sent: 569310152
2025-10-29 22:42:57.177004 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 2097152 return 981960.
2025-10-29 22:42:57.177038 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Socket busy, len = 981960 (of 2097152), buffered = 0.
2025-10-29 22:42:57.179680 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::doWrite()!
2025-10-29 22:42:57.179727 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::flush()!
2025-10-29 22:42:57.179784 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] SendStaticFile()
2025-10-29 22:42:57.179831 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx entry
2025-10-29 22:42:57.179884 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx Async
2025-10-29 22:42:57.180093 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 568328192, 2097152 bytes diff: 981960
2025-10-29 22:42:57.180217 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 1115192, remain: 8600169256
2025-10-29 22:42:57.180833 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d86eebc8, 1115192) written 1115192, total sent: 570425344
2025-10-29 22:42:57.180950 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 1115192 return 1115192.
2025-10-29 22:42:57.181047 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 1115192, buffered = 0.
2025-10-29 22:42:57.181185 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.181286 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 2097152 bytes (0x241bcbc0) at 570425344
2025-10-29 22:42:57.181700 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() return 1
2025-10-29 22:42:57.181859 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
2025-10-29 22:42:57.182105 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::doWrite()!
2025-10-29 22:42:57.182213 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::flush()!
2025-10-29 22:42:57.182306 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] SendStaticFile()
2025-10-29 22:42:57.182400 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx entry
2025-10-29 22:42:57.182485 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx Async
2025-10-29 22:42:57.182552 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, Read still pending
2025-10-29 22:42:57.182618 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 0, remain: 8599054064
2025-10-29 22:42:57.182753 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() return 1
2025-10-29 22:42:57.182837 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
2025-10-29 22:42:57.182949 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.183027 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 2097152 bytes at 570425344
2025-10-29 22:42:57.183098 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.183171 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8599054064
2025-10-29 22:42:57.183286 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 570425344, 2097152 bytes diff: 0
2025-10-29 22:42:57.183400 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 2097152, remain: 8599054064
2025-10-29 22:42:57.184047 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d85ff000, 2097152) written 2097152, total sent: 572522496
2025-10-29 22:42:57.184153 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 2097152 return 2097152.
2025-10-29 22:42:57.184257 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 2097152, buffered = 0.
2025-10-29 22:42:57.184378 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.184472 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 2097152 bytes (0x241bcbc0) at 572522496
2025-10-29 22:42:57.184882 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.185004 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 2097152 bytes at 572522496
2025-10-29 22:42:57.185097 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.185197 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8596956912
2025-10-29 22:42:57.185291 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 572522496, 2097152 bytes diff: 0
2025-10-29 22:42:57.185388 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 2097152, remain: 8596956912
2025-10-29 22:42:57.186044 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d85ff000, 2097152) written 2097152, total sent: 574619648
2025-10-29 22:42:57.186134 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 2097152 return 2097152.
2025-10-29 22:42:57.186227 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 2097152, buffered = 0.
2025-10-29 22:42:57.186336 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.186430 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 2097152 bytes (0x241bcbc0) at 574619648
2025-10-29 22:42:57.186797 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.186920 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 2097152 bytes at 574619648
2025-10-29 22:42:57.187002 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.187081 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8594859760
2025-10-29 22:42:57.187180 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 574619648, 2097152 bytes diff: 0
2025-10-29 22:42:57.187274 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 2097152, remain: 8594859760
2025-10-29 22:42:57.187612 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d85ff000, 2097152) written 717800, total sent: 575337448
2025-10-29 22:42:57.187693 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 2097152 return 717800.
2025-10-29 22:42:57.187786 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Socket busy, len = 717800 (of 2097152), buffered = 0.
2025-10-29 22:42:57.190306 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::doWrite()!
2025-10-29 22:42:57.190442 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::flush()!
2025-10-29 22:42:57.190511 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] SendStaticFile()
2025-10-29 22:42:57.190532 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx entry
2025-10-29 22:42:57.190537 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx Async
2025-10-29 22:42:57.190541 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 574619648, 2097152 bytes diff: 717800
2025-10-29 22:42:57.190544 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 1379352, remain: 8594141960
2025-10-29 22:42:57.190766 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d86ae3e8, 1379352) written 1379352, total sent: 576716800
2025-10-29 22:42:57.190789 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 1379352 return 1379352.
2025-10-29 22:42:57.190810 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 1379352, buffered = 0.
2025-10-29 22:42:57.190830 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.190838 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 2097152 bytes (0x241bcbc0) at 576716800
2025-10-29 22:42:57.191135 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() return 1
2025-10-29 22:42:57.191144 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
2025-10-29 22:42:57.191154 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.191158 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 2097152 bytes at 576716800
2025-10-29 22:42:57.191161 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.191164 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8592762608
2025-10-29 22:42:57.191168 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 576716800, 2097152 bytes diff: 0
2025-10-29 22:42:57.191171 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 2097152, remain: 8592762608
2025-10-29 22:42:57.191239 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d85ff000, 2097152) written 388176, total sent: 577104976
2025-10-29 22:42:57.191243 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 2097152 return 388176.
2025-10-29 22:42:57.191246 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Socket busy, len = 388176 (of 2097152), buffered = 0.
2025-10-29 22:42:57.193287 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::doWrite()!
2025-10-29 22:42:57.193290 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::flush()!
2025-10-29 22:42:57.193293 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] SendStaticFile()
2025-10-29 22:42:57.193297 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx entry
2025-10-29 22:42:57.193300 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx Async
2025-10-29 22:42:57.193303 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 576716800, 2097152 bytes diff: 388176
2025-10-29 22:42:57.193307 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 1708976, remain: 8592374432
2025-10-29 22:42:57.193609 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d865dc50, 1708976) written 1708976, total sent: 578813952
2025-10-29 22:42:57.193613 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 1708976 return 1708976.
2025-10-29 22:42:57.193616 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 1708976, buffered = 0.
2025-10-29 22:42:57.193620 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.193623 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 730864 bytes (0x241bcbc0) at 578813952
2025-10-29 22:42:57.193736 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() return 1
2025-10-29 22:42:57.193742 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
2025-10-29 22:42:57.193751 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.193754 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 730864 bytes at 578813952
2025-10-29 22:42:57.193758 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.193765 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8590665456
2025-10-29 22:42:57.193769 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 578813952, 730864 bytes diff: 0
2025-10-29 22:42:57.193772 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 730864, remain: 8590665456
2025-10-29 22:42:57.193860 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d85ff000, 730864) written 713192, total sent: 579527144
2025-10-29 22:42:57.193863 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 730864 return 713192.
2025-10-29 22:42:57.193867 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Socket busy, len = 713192 (of 730864), buffered = 0.
2025-10-29 22:42:57.197497 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::doWrite()!
2025-10-29 22:42:57.197500 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::flush()!
2025-10-29 22:42:57.197503 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] SendStaticFile()
2025-10-29 22:42:57.197507 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx entry
2025-10-29 22:42:57.197510 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx Async
2025-10-29 22:42:57.197513 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 578813952, 730864 bytes diff: 713192
2025-10-29 22:42:57.197517 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 17672, remain: 8589952264
2025-10-29 22:42:57.197554 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect(): write(0x7f72d86ad1e8, 17672) written 17672, total sent: 579544816
2025-10-29 22:42:57.197557 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] writeRespBodyDirect() tried 17672 return 17672.
2025-10-29 22:42:57.197560 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] File async: Completed, len = 17672, buffered = 0.
2025-10-29 22:42:57.197563 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring getsqe, fd: 17
2025-10-29 22:42:57.197567 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] Iouring io_uring_submit 0 bytes (0x241bcbc0) at 579544816
2025-10-29 22:42:57.197578 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() return 1
2025-10-29 22:42:57.197581 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
2025-10-29 22:42:57.197595 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::doWrite()!
2025-10-29 22:42:57.197599 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::flush()!
2025-10-29 22:42:57.197602 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] SendStaticFile()
2025-10-29 22:42:57.197605 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx entry
2025-10-29 22:42:57.197608 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] sendStaticFileEx Async
2025-10-29 22:42:57.197611 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, Read still pending
2025-10-29 22:42:57.197614 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 0, remain: 8589934592
2025-10-29 22:42:57.197624 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() return 1
2025-10-29 22:42:57.197627 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
2025-10-29 22:42:57.197650 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq, io_complete event 1
2025-10-29 22:42:57.197669 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq read 0 bytes at 579544816
2025-10-29 22:42:57.197683 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq call session onAioReqEvent
2025-10-29 22:42:57.197687 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] onAioReqEvent, remain: 8589934592
2025-10-29 22:42:57.197690 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 579544816, 0 bytes diff: 0
2025-10-29 22:42:57.197693 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 0, remain: 8589934592
2025-10-29 22:43:00.742752 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] [HTTP_END] run Hook function for [Module:mod_security] session=0x242456a8
2025-10-29 22:43:00.742867 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] [Module:mod_security] EndSession, session=0x242456a8 myData=0x24215000.
2025-10-29 22:43:00.742987 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] [HTTP_END] [Module:mod_security] session=0x242456a8 ret 0
2025-10-29 22:43:00.743043 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] [HTTP_END] run Hook function for [Module:cache] session=0x242456a8
2025-10-29 22:43:00.743052 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] [HTTP_END] [Module:cache] session=0x242456a8 ret 0
2025-10-29 22:43:00.743086 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] calling removeSessionCb on this 0x242456a0 (recycle())
2025-10-29 22:43:00.743096 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] HttpSession::recycle() 0x242456a0
2025-10-29 22:43:00.743099 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] [Tm] failed trylockMtRace.
I think the problem occurs here
2025-10-29 22:42:57.197690 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] LsAioReq::getRead at 579544816, 0 bytes diff: 0
2025-10-29 22:42:57.197693 [DEBUG] [3419939] [2a00:da00:f42d:b400:0:2:2:2:44498#PHPMyAdmin] getRead(): ret: 0, read: 0, remain: 8589934592
The read value shouldn't be 0 length,
Also this is the debug log segment after retry call
./error.log.2025_10_29.09-2025-10-29 23:06:33.201641 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] processContextPath() returned 0.
./error.log.2025_10_29.09-2025-10-29 23:06:33.201644 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] processFileMap(): HSPS_FILE_MAP -> HSPS_CHECK_AUTH_ACCESS
./error.log.2025_10_29.09-2025-10-29 23:06:33.201647 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] processContextAuth(): HSPS_CHECK_AUTH_ACCESS -> HSPS_HKPT_HTTP_AUTH
./error.log.2025_10_29.09-2025-10-29 23:06:33.201649 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] runEventHkpt(): HSPS_HKPT_HTTP_AUTH -> HSPS_AUTH_DONE
./error.log.2025_10_29.09-2025-10-29 23:06:33.201652 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] smProcessReq(): HSPS_AUTH_DONE -> HSPS_BEGIN_HANDLER_PROCESS
./error.log.2025_10_29.09-2025-10-29 23:06:33.201655 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] handlerProcess(): HSPS_BEGIN_HANDLER_PROCESS -> HSPS_HANDLER_PROCESSING
./error.log.2025_10_29.09-2025-10-29 23:06:33.201658 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] HT_STATIC but not getUrlStaticFileData
./error.log.2025_10_29.09-2025-10-29 23:06:33.201663 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200] Range: bytes=579544816-
./error.log.2025_10_29.09-2025-10-29 23:06:33.201680 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] setSendFileBeginEnd 579544816, 9169479408 remain 8589934592
./error.log.2025_10_29.09-2025-10-29 23:06:33.201683 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] Response header finished!
./error.log.2025_10_29.09-2025-10-29 23:06:33.201686 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] [RECV_RESP_HEADER] run Hook function for [Module:mod_security] session=0x2424d8a8
./error.log.2025_10_29.09-2025-10-29 23:06:33.201781 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] [RECV_RESP_HEADER] [Module:mod_security] session=0x2424d8a8 ret 0
./error.log.2025_10_29.09-2025-10-29 23:06:33.201784 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] [RECV_RESP_HEADER] run Hook function for [Module:cache] session=0x2424d8a8
./error.log.2025_10_29.09-2025-10-29 23:06:33.201788 [DEBUG] [3419934] [CACHE] createEntry quit due to internal error.
./error.log.2025_10_29.09-2025-10-29 23:06:33.201791 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] [RECV_RESP_HEADER] [Module:cache] session=0x2424d8a8 ret 0
./error.log.2025_10_29.09-2025-10-29 23:06:33.201799 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] endResponse( 1 )
./error.log.2025_10_29.09-2025-10-29 23:06:33.201801 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] endResponseInternal()
./error.log.2025_10_29.09-2025-10-29 23:06:33.201804 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] [RCVD_RESP_BODY] run Hook function for [Module:mod_security] session=0x2424d8a8
./error.log.2025_10_29.09-2025-10-29 23:06:33.201815 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] [RCVD_RESP_BODY] [Module:mod_security] session=0x2424d8a8 ret 0
./error.log.2025_10_29.09-2025-10-29 23:06:33.201818 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] HttpSession::flush()!
./error.log.2025_10_29.09-2025-10-29 23:06:33.201821 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] sendRespHeaders()
./error.log.2025_10_29.09-2025-10-29 23:06:33.201825 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] finalizeHeader() added Alt-Svc with value .
./error.log.2025_10_29.09-2025-10-29 23:06:33.201828 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] Resp headers, total: 8, removed: 1, unique:7, has hole: 0, buffer size: 264
./error.log.2025_10_29.09-2025-10-29 23:06:33.201831 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] etag: "2228b26f0-690067b7-1c00371b;;;"
./error.log.2025_10_29.09-2025-10-29 23:06:33.201834 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] last-modified: Tue, 28 Oct 2025 06:50:31 GMT
./error.log.2025_10_29.09-2025-10-29 23:06:33.201836 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] content-type: application/octet-stream
./error.log.2025_10_29.09-2025-10-29 23:06:33.201839 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] content-range: bytes 579544816-9169479407/9169479408
./error.log.2025_10_29.09-2025-10-29 23:06:33.201841 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] content-length: 8589934592
./error.log.2025_10_29.09-2025-10-29 23:06:33.201844 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] date: Wed, 29 Oct 2025 23:06:33 GMT
./error.log.2025_10_29.09-2025-10-29 23:06:33.201846 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] server: LiteSpeed
./error.log.2025_10_29.09-2025-10-29 23:06:33.201850 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] SendStaticFile()
./error.log.2025_10_29.09-2025-10-29 23:06:33.201862 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] sendStaticFileEx entry
./error.log.2025_10_29.09-2025-10-29 23:06:33.201864 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] sendStaticFileEx Async
./error.log.2025_10_29.09-2025-10-29 23:06:33.201867 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] sendStaticFileEx usingLsAioReq 17 17
./error.log.2025_10_29.09-2025-10-29 23:06:33.201871 [INFO] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] Using io_uring for transfer of /var/lsws/host/html/28-10-2025_06-00-03.enc
./error.log.2025_10_29.09-2025-10-29 23:06:33.201874 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] LsAioReq::init 17
./error.log.2025_10_29.09-2025-10-29 23:06:33.201876 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] LsAioReq AioBlockSize: 2097152
./error.log.2025_10_29.09-2025-10-29 23:06:33.201881 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] Iouring getsqe, fd: 17
./error.log.2025_10_29.09-2025-10-29 23:06:33.201911 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] Iouring io_uring_submit 0 bytes (0x2424e370) at 579544816
./error.log.2025_10_29.09-2025-10-29 23:06:33.201930 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] flushBody() return 1
./error.log.2025_10_29.09-2025-10-29 23:06:33.201933 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
./error.log.2025_10_29.09-2025-10-29 23:06:33.201936 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] HttpSession::flush()!
./error.log.2025_10_29.09-2025-10-29 23:06:33.201939 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] SendStaticFile()
./error.log.2025_10_29.09-2025-10-29 23:06:33.201941 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] sendStaticFileEx entry
./error.log.2025_10_29.09-2025-10-29 23:06:33.201944 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] sendStaticFileEx Async
./error.log.2025_10_29.09-2025-10-29 23:06:33.201947 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] LsAioReq, Read still pending
./error.log.2025_10_29.09:2025-10-29 23:06:33.201950 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] getRead(): ret: 0, read: 0, remain: 858993459
./error.log.2025_10_29.09-2025-10-29 23:06:33.201957 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200] NtwkIOLink::suspendWrite()...
./error.log.2025_10_29.09-2025-10-29 23:06:33.201960 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200] Write suspended
./error.log.2025_10_29.09-2025-10-29 23:06:33.201963 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] flushBody() return 1
./error.log.2025_10_29.09-2025-10-29 23:06:33.201965 [DEBUG] [3419934] [2a00:da00:f42d:b400:0:2:2:2:36200#PHPMyAdmin] flushBody() has HSF_AIO_READING set, ret now 1
I can download the file when I send download request using HTTP 1.1, while HTTP 2 fails and not sure about HTTP 3 if it works with following settings:-
Use sendfile()[ Yes
Use Asynchronized disk I/O (AIO) No
AIO Block Size 128K
Lastly, I looked at io_uring library issues it may well be a another bug like this:-
axboe/liburing#71
Also, additional point, not sure if its relevant but enclosing as it might help debugging this bug in case it was effectively.
I installed libiouring development headers using:- dnf install libiouring-devel
I think it It did increase the length to a fixed limit almost as much twice as before from 579.5MB to 940MB.
Any recommendation or a patch for this bug?
Looking forward with thanks.
Zak.