Skip to content

Instantly share code, notes, and snippets.

@iyear

iyear/debug.log

Last active October 7, 2023 16:54
Show Gist options
  • Save iyear/ea718b9e90c09da301028b5865c730ad to your computer and use it in GitHub Desktop.
Save iyear/ea718b9e90c09da301028b5865c730ad to your computer and use it in GitHub Desktop.
Download ZIP
Raw
debug.log
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "reason": "read: read: read intermediate: read length: EOF", "reasonVerbose": "read:\n github.com/gotd/td/internal/mtproto.(*Conn).readLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/read.go:193\n - read:\n github.com/gotd/td/transport.(*connection).Recv\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/transport/connection.go:70\n - read intermediate:\n github.com/gotd/td/internal/proto/codec.Intermediate.Read\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/intermediate.go:73\n - read length:\n github.com/gotd/td/internal/proto/codec.readLen\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/codec.go:35\n - EOF"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "task": "readLoop", "error": "read: read: read intermediate: read length: EOF", "errorVerbose": "read:\n github.com/gotd/td/internal/mtproto.(*Conn).readLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/read.go:193\n - read:\n github.com/gotd/td/transport.(*connection).Recv\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/transport/connection.go:70\n - read intermediate:\n github.com/gotd/td/internal/proto/codec.Intermediate.Read\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/intermediate.go:73\n - read length:\n github.com/gotd/td/internal/proto/codec.readLen\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/codec.go:35\n - EOF", "elapsed": "8.7287387s"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "8.7287387s"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "8.7287056s"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "task": "saltsLoop", "error": "context canceled", "elapsed": "8.7292608s"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 3, "dc_id": 5}
2023-10-07 20:47:22 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 3, "dc_id": 5}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "msg_id": 7287197306025888344}
2023-10-07 20:47:22 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "duration": "3.1011946s", "ongoing": 0}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:146 Transfer connection to requester {"v": "v0.88.0", "dc_id": 5, "conn_id": 3}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:202 Got connection for request {"v": "v0.88.0", "dc_id": 5, "conn_id": 3, "request_id": 35}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: retryUntilAck: engine forcibly closed: context canceled", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - retryUntilAck:\n github.com/gotd/td/internal/rpc.(*Engine).Do\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/engine.go:141\n - engine forcibly closed:\n github.com/gotd/td/internal/rpc.(*Engine).retryUntilAck.func1\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/engine.go:204\n - context canceled"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "msg_id": 7287197319011984832}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197319011984840}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197319011984852}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "task": "handleClose", "elapsed": "8.7293172s"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "msg_id": 7287197319011984832}
2023-10-07 20:47:22 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "duration": "0s", "ongoing": 0}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 5, "conn_id": 3}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197319011984840}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 3, "dc_id": 5}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197319011984852}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: engine was closed", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - engine was closed:\n github.com/gotd/td/internal/rpc.init\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/errors.go:26"}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "ack_id": 7287197319011984852}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "ack_id": 7287197319011984840}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0x58e4a740", "type_name": "rpc_drop_answer#58e4a740", "msg_id": 7287197319011984852}
2023-10-07 20:47:22 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "type_id": "0x58e4a740", "type_name": "rpc_drop_answer#58e4a740", "msg_id": 7287197319011984840}
2023-10-07 20:47:22 DEBUG dl.td.conn manager/conn.go:125 Connection dead {"v": "v0.88.0", "conn_id": 3, "dc_id": 5, "error": "group: task readLoop: read: read: read intermediate: read length: EOF", "errorVerbose": "group:\n github.com/gotd/td/internal/mtproto.(*Conn).Run\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/conn.go:214\n - task readLoop:\n github.com/gotd/td/internal/tdsync.(*LogGroup).Go.func1\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/tdsync/log_group.go:48\n - read:\n github.com/gotd/td/internal/mtproto.(*Conn).readLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/read.go:193\n - read:\n github.com/gotd/td/transport.(*connection).Recv\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/transport/connection.go:70\n - read intermediate:\n github.com/gotd/td/internal/proto/codec.Intermediate.Read\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/intermediate.go:73\n - read length:\n github.com/gotd/td/internal/proto/codec.readLen\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/codec.go:35\n - EOF"}
2023-10-07 20:47:22 DEBUG dl.td.pool pool/pool.go:119 Connection died {"v": "v0.88.0", "dc_id": 5, "remaining": 2, "conn_id": 3}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 32, "msg_id": 7287197317110550529}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xa43ad8b7", "type_name": "rpc_answer_dropped#a43ad8b7", "size_bytes": 20, "msg_id": 7287197319011984852}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197319011984852}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197319011984852}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:165 Request dropped {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197318868203432}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197318868203432}
2023-10-07 20:47:23 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "duration": "452.7269ms", "ongoing": 0}
2023-10-07 20:47:23 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 5, "conn_id": 2}
2023-10-07 20:47:23 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: context canceled", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - context canceled"}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197316737463297}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197318868203432}
2023-10-07 20:47:23 WARN dl.td.conn.mtproto.rpc rpc/engine.go:241 rpc callback not set {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197318868203432}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197311885656065}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197314330631936}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197314330631936}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197314330631936}
2023-10-07 20:47:23 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "duration": "1.729697s", "ongoing": 0}
2023-10-07 20:47:23 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:23 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:23 DEBUG dl.td.pool pool/pool.go:167 Re-using free connection {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:23 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197322650790328}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197322650790328}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "ack_id": 7287197322650790328}
2023-10-07 20:47:23 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197322650790328}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197314793279489}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197318254729140}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197318254729140}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197318254729140}
2023-10-07 20:47:24 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "duration": "1.8873966s", "ongoing": 0}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 2}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:167 Re-using free connection {"v": "v0.88.0", "dc_id": 4, "conn_id": 2}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "ack_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197319033499649}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197322650790328}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197322650790328}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197322650790328}
2023-10-07 20:47:24 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "duration": "813.4391ms", "ongoing": 0}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:167 Re-using free connection {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "ack_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197322723108865}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197326759196724}
2023-10-07 20:47:24 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "duration": "676.9905ms", "ongoing": 0}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:167 Re-using free connection {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197327436187224}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197327436187224}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "ack_id": 7287197327436187224}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197327436187224}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197322624747521}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197326732060324}
2023-10-07 20:47:24 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "duration": "726.8578ms", "ongoing": 0}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 2}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:167 Re-using free connection {"v": "v0.88.0", "dc_id": 4, "conn_id": 2}
2023-10-07 20:47:24 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197327458918124}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197327458918124}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "ack_id": 7287197327458918124}
2023-10-07 20:47:24 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197327458918124}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 320292, "msg_id": 7287197325963173889}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 320280, "msg_id": 7287197327458918124}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197327458918124}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "msg_id": 7287197327458918124}
2023-10-07 20:47:25 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "duration": "624.3797ms", "ongoing": 0}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 2}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197325825787905}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197327436187224}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197327436187224}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "msg_id": 7287197327436187224}
2023-10-07 20:47:25 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "duration": "754.8522ms", "ongoing": 0}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 4, "conn_id": 1}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:25 DEBUG dl downloader/downloader.go:106 Start download item {"item": {"ID":4,"InputFileLoc":{"ID":6199555094413838828,"AccessHash":4192809438500397287,"FileReference":"AQABE3VlIVNUCzk0MAS5aUpZmOj0eKCZJA==","ThumbSize":""},"Name":"5612666796_70517_QQ9.9.0.14569_x64.exe","Size":175563272,"DC":5}}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:178 Creating new connection {"v": "v0.88.0", "dc_id": 5, "conn_id": 4}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:167 Re-using free connection {"v": "v0.88.0", "dc_id": 5, "conn_id": 2}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:198 Waiting for free connect {"v": "v0.88.0", "dc_id": 5, "request_id": 37}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:198 Waiting for free connect {"v": "v0.88.0", "dc_id": 5, "request_id": 36}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197331597182120}
2023-10-07 20:47:25 INFO dl.td.conn.mtproto.rpc rpc/engine.go:42 Initialized {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "retry_interval": "5s", "max_retries": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197331597182120}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "ack_id": 7287197331597182120}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197331597182120}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/conn.go:198 Run: start {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:25 INFO dl.td.conn.mtproto mtproto/connect.go:44 Key already exists {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/connect.go:49 Generating new session id {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "readLoop"}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "ackLoop"}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.read mtproto/read.go:135 Read loop started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "saltsLoop"}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "handleClose"}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "userCallback"}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task started {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "pingLoop"}
2023-10-07 20:47:25 DEBUG dl.td.conn manager/conn.go:191 Initializing {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "ack_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0x73f1f8dc", "type_name": "message_container", "size_bytes": 88, "msg_id": 7287197329207608325}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0x9ec20908", "type_name": "new_session_created#9ec20908", "size_bytes": 28, "msg_id": 7287197329207608325}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_session_created.go:21 Session created {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "unique_id": 263116001955864598, "first_msg_id": 7287197331598264520, "first_msg_time": "2023-10-07 20:47:25"}
2023-10-07 20:47:25 INFO dl.td.conn manager/conn.go:79 SessionInit {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331839182928}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xb921bd04", "type_name": "get_future_salts#b921bd04", "msg_id": 7287197331839182920}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331839182928}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "ack_id": 7287197331839182928}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197331839182928}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 544, "msg_id": 7287197329248625665}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0x3072cfa1", "type_name": "gzip", "size_bytes": 532, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:39 Decompressed {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xcc1a241e", "type_name": "config#cc1a241e", "size_bytes": 1116, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "userCallback", "elapsed": "249.4464ms"}
2023-10-07 20:47:25 DEBUG dl.td telegram/connect.go:84 Ready {"v": "v0.88.0"}
2023-10-07 20:47:25 DEBUG dl.td telegram/session.go:89 Data saved {"v": "v0.88.0", "key_id": "be84c0df3d3ff824"}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0x62d6b459", "type_name": "msgs_ack#62d6b459", "size_bytes": 20, "msg_id": 7287197329207608325}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_ack.go:17 Received ack {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_ids": [7287197331598264520]}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:15 Acknowledge callback not set {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331598264520}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xae500895", "type_name": "future_salts#ae500895", "size_bytes": 84, "msg_id": 7287197329718348801}
2023-10-07 20:47:25 DEBUG dl.td.conn.mtproto mtproto/handle_future_salts.go:23 Got future salts {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "server_time": "2023-10-07 20:47:24"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197328831768577}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197331597182120}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197331597182120}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197331597182120}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "duration": "372.4583ms", "ongoing": 0}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:146 Transfer connection to requester {"v": "v0.88.0", "dc_id": 5, "conn_id": 2}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:262 DC Invoke complete {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:198 Waiting for free connect {"v": "v0.88.0", "dc_id": 5, "request_id": 38}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:202 Got connection for request {"v": "v0.88.0", "dc_id": 5, "conn_id": 2, "request_id": 36}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "ack_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xbf9459b7", "type_name": "invokeWithoutUpdates#bf9459b7", "msg_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "reason": "read: read: read intermediate: read length: EOF", "reasonVerbose": "read:\n github.com/gotd/td/internal/mtproto.(*Conn).readLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/read.go:193\n - read:\n github.com/gotd/td/transport.(*connection).Recv\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/transport/connection.go:70\n - read intermediate:\n github.com/gotd/td/internal/proto/codec.Intermediate.Read\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/intermediate.go:73\n - read length:\n github.com/gotd/td/internal/proto/codec.readLen\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/codec.go:35\n - EOF"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "task": "readLoop", "error": "read: read: read intermediate: read length: EOF", "errorVerbose": "read:\n github.com/gotd/td/internal/mtproto.(*Conn).readLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/read.go:193\n - read:\n github.com/gotd/td/transport.(*connection).Recv\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/transport/connection.go:70\n - read intermediate:\n github.com/gotd/td/internal/proto/codec.Intermediate.Read\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/intermediate.go:73\n - read length:\n github.com/gotd/td/internal/proto/codec.readLen\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/codec.go:35\n - EOF", "elapsed": "14.2110786s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 1, "dc_id": 5}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 1, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "task": "saltsLoop", "error": "context canceled", "elapsed": "14.2115931s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197319011984840}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "14.2115931s"}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:161 Failed to drop request {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197305384770844, "error": "rpcDoRequest: retryUntilAck: engine forcibly closed: context canceled", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - retryUntilAck:\n github.com/gotd/td/internal/rpc.(*Engine).Do\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/engine.go:141\n - engine forcibly closed:\n github.com/gotd/td/internal/rpc.(*Engine).retryUntilAck.func1\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/engine.go:204\n - context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "14.2115931s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197305384770844}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "duration": "6.9318854s", "ongoing": 0}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:146 Transfer connection to requester {"v": "v0.88.0", "dc_id": 5, "conn_id": 1}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:202 Got connection for request {"v": "v0.88.0", "dc_id": 5, "conn_id": 1, "request_id": 38}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "task": "handleClose", "elapsed": "14.2115931s"}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: context canceled", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197335381870816}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 1, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197335381870816}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "duration": "0s", "ongoing": 0}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:146 Transfer connection to requester {"v": "v0.88.0", "dc_id": 5, "conn_id": 1}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:125 Connection dead {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "error": "group: task readLoop: read: read: read intermediate: read length: EOF", "errorVerbose": "group:\n github.com/gotd/td/internal/mtproto.(*Conn).Run\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/conn.go:214\n - task readLoop:\n github.com/gotd/td/internal/tdsync.(*LogGroup).Go.func1\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/tdsync/log_group.go:48\n - read:\n github.com/gotd/td/internal/mtproto.(*Conn).readLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/read.go:193\n - read:\n github.com/gotd/td/transport.(*connection).Recv\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/transport/connection.go:70\n - read intermediate:\n github.com/gotd/td/internal/proto/codec.Intermediate.Read\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/intermediate.go:73\n - read length:\n github.com/gotd/td/internal/proto/codec.readLen\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/proto/codec/codec.go:35\n - EOF"}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:202 Got connection for request {"v": "v0.88.0", "dc_id": 5, "conn_id": 1, "request_id": 37}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:119 Connection died {"v": "v0.88.0", "dc_id": 5, "remaining": 2, "conn_id": 1}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:254 DC Invoke {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197335381870824}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "msg_id": 7287197335381870824}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 1, "dc_id": 5, "duration": "0s", "ongoing": 0}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 5, "conn_id": 1}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: engine was closed", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - engine was closed:\n github.com/gotd/td/internal/rpc.init\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/errors.go:26"}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: engine was closed", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - engine was closed:\n github.com/gotd/td/internal/rpc.init\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/rpc/errors.go:26"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "ack_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:29 Invoke start {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:87 Do called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0x58e4a740", "type_name": "rpc_drop_answer#58e4a740", "msg_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/ack.go:34 Waiting for acknowledge {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "ack_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0x58e4a740", "type_name": "rpc_drop_answer#58e4a740", "msg_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/new_encrypted_msg.go:62 Request {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "type_id": "0x62d6b459", "type_name": "msgs_ack#62d6b459", "msg_id": 7287197335600368616}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.ack mtproto/ack.go:24 Ack {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "msg_ids": [7287197267913293825, 7287197269114504193, 7287197295257036801]}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197330344864769}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 32, "msg_id": 7287197332076140545}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "type_id": "0xa43ad8b7", "type_name": "rpc_answer_dropped#a43ad8b7", "size_bytes": 20, "msg_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335381870844}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:165 Request dropped {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "msg_id": 7287197335265147416}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "duration": "539.4836ms", "ongoing": 0}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 5, "conn_id": 2}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: context canceled", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 32, "msg_id": 7287197330788854785}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xa43ad8b7", "type_name": "rpc_answer_dropped#a43ad8b7", "size_bytes": 20, "msg_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:99 Handler called {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:47 Invoke end {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197335381870836}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.rpc rpc/engine.go:165 Request dropped {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331839182928}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/rpc.go:44 Invoke end {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331839182928}
2023-10-07 20:47:26 DEBUG dl.td.conn manager/conn.go:113 Invoke {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "duration": "721.6306ms", "ongoing": 0}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:149 Connection released {"v": "v0.88.0", "dc_id": 5, "conn_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:258 DC Invoke failed {"v": "v0.88.0", "dc_id": 5, "error": "rpcDoRequest: context canceled", "errorVerbose": "rpcDoRequest:\n github.com/gotd/td/internal/mtproto.(*Conn).Invoke\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/rpc.go:44\n - context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_message.go:19 Handle message {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0xf35c6d01", "type_name": "rpc_result", "size_bytes": 524316, "msg_id": 7287197329777916929}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/handle_result.go:24 Handle result {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "type_id": "0x96a18d5", "type_name": "upload.file#96a18d5", "size_bytes": 524304, "msg_id": 7287197331839182928}
2023-10-07 20:47:26 WARN dl.td.conn.mtproto.rpc rpc/engine.go:241 rpc callback not set {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "msg_id": 7287197331839182928}
2023-10-07 20:47:26 DEBUG dl dl/dl.go:191 Save progress {"finished": 3}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:273 Closing DC {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 2, "dc_id": 5}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 2, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "992.8409ms"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "12.4235359s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "saltsLoop", "error": "context canceled", "elapsed": "992.8409ms"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "992.8409ms"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "task": "saltsLoop", "error": "context canceled", "elapsed": "12.4235359s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "12.4235359s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "reason": "context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "task": "readLoop", "error": "context canceled", "elapsed": "12.4240459s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "reason": "context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "handleClose", "elapsed": "992.8409ms"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 6, "dc_id": 5, "task": "readLoop", "error": "context canceled", "elapsed": "992.8409ms"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 2, "dc_id": 5, "task": "handleClose", "elapsed": "12.4235359s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 2, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 6, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:119 Connection died {"v": "v0.88.0", "dc_id": 5, "remaining": 1, "conn_id": 2}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:119 Connection died {"v": "v0.88.0", "dc_id": 5, "remaining": 0, "conn_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:277 DC closed {"v": "v0.88.0", "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:273 Closing DC {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 5, "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "task": "saltsLoop", "error": "context canceled", "elapsed": "5.7594821s"}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 5, "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "task": "saltsLoop", "error": "context canceled", "elapsed": "5.079484s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "5.079484s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 4, "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "5.7594821s"}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 4, "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "5.079484s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "5.7594821s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "reason": "context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "task": "handleClose", "elapsed": "5.079484s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 5, "dc_id": 4, "task": "readLoop", "error": "context canceled", "elapsed": "5.079484s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "reason": "context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "task": "handleClose", "elapsed": "5.7594821s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 5, "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 4, "dc_id": 4, "task": "readLoop", "error": "context canceled", "elapsed": "5.7594821s"}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:119 Connection died {"v": "v0.88.0", "dc_id": 4, "remaining": 1, "conn_id": 2}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 4, "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:119 Connection died {"v": "v0.88.0", "dc_id": 4, "remaining": 0, "conn_id": 1}
2023-10-07 20:47:26 DEBUG dl.td.pool pool/pool.go:277 DC closed {"v": "v0.88.0", "dc_id": 4}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:172 Closing {"v": "v0.88.0", "conn_id": 0, "dc_id": 5}
2023-10-07 20:47:26 INFO dl.td.conn.mtproto.rpc rpc/engine.go:271 Close called {"v": "v0.88.0", "conn_id": 0, "dc_id": 5}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "task": "ackLoop", "error": "acl: context canceled", "errorVerbose": "acl:\n github.com/gotd/td/internal/mtproto.(*Conn).ackLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ack.go:33\n - context canceled", "elapsed": "15.2877751s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "task": "saltsLoop", "error": "context canceled", "elapsed": "15.2877751s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "task": "pingLoop", "error": "ping loop: context canceled", "errorVerbose": "ping loop:\n github.com/gotd/td/internal/mtproto.(*Conn).pingLoop\n C:/Users/iyear/go/pkg/mod/github.com/gotd/td@v0.88.0/internal/mtproto/ping.go:107\n - context canceled", "elapsed": "15.2877751s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.read mtproto/read.go:141 Read loop done {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "reason": "context canceled"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task complete {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "task": "handleClose", "elapsed": "15.2877751s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto.group tdsync/cancel_group.go:48 Task stopped {"v": "v0.88.0", "conn_id": 0, "dc_id": 5, "task": "readLoop", "error": "context canceled", "elapsed": "15.2877751s"}
2023-10-07 20:47:26 DEBUG dl.td.conn.mtproto mtproto/conn.go:214 Run: end {"v": "v0.88.0", "conn_id": 0, "dc_id": 5}
2023-10-07 20:47:26 INFO dl.td telegram/connect.go:164 Closed {"v": "v0.88.0"}
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment