core/log

1706 lines
204 KiB
Plaintext
Raw Blame History

This file contains invisible Unicode characters

This file contains invisible Unicode characters that are indistinguishable to humans but may be processed differently by a computer. If you think that this is intentional, you can safely ignore this warning. Use the Escape button to reveal them.

2021-04-23 10:05:13,226 TRACE [async_io::driver] block_on()
2021-04-23 10:05:13,226 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:13,226 TRACE [async_io::driver] block_on()
2021-04-23 10:05:13,226 TRACE [polling::epoll] add: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: false, writable: false }
2021-04-23 10:05:13,226 TRACE [async_io::driver] block_on()
2021-04-23 10:05:13,226 TRACE [mio::poll] registering event source with poller: token=Token(0), interests=READABLE | WRITABLE
2021-04-23 10:05:13,229 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:13,229 TRACE [async_io::driver] block_on()
2021-04-23 10:05:13,230 TRACE [mio::poll] registering event source with poller: token=Token(1), interests=READABLE | WRITABLE
2021-04-23 10:05:13,231 TRACE [async_io::driver] block_on: notified
2021-04-23 10:05:13,226 TRACE [async_io::driver] block_on()
2021-04-23 10:05:13,231 TRACE [async_io::driver] block_on()
2021-04-23 10:05:13,226 TRACE [polling::epoll] add: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:13,231 TRACE [polling::epoll] new: epoll_fd=10, event_fd=11, timer_fd=Some(12)
2021-04-23 10:05:13,231 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:13,231 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:13,231 INFO [hole::chat::serv_conn] Connected to FCP
2021-04-23 10:05:13,231 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:13,231 TRACE [async_std::task::builder] spawn
2021-04-23 10:05:13,231 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:13,232 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:13,232 TRACE [polling::epoll] add: epoll_fd=10, fd=13, ev=Event { key: 0, readable: false, writable: false }
2021-04-23 10:05:13,232 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:13,232 TRACE [polling::epoll] modify: epoll_fd=10, fd=13, ev=Event { key: 0, readable: true, writable: false }
2021-04-23 10:05:13,232 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:13,232 TRACE [polling] Poller::notify()
2021-04-23 10:05:13,232 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:13,232 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:13,231 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:13,232 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:13,232 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:13,232 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:13,232 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:13,232 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:13,232 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:13,233 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:13,233 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:13,233 TRACE [async_io::driver] block_on: notified
2021-04-23 10:05:13,233 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:13,233 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:13,233 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:13,233 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:13,233 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:13,233 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:13,233 TRACE [polling] Poller::notify()
2021-04-23 10:05:13,233 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:13,234 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:13,234 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:13,234 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:13,234 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:13,234 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:13,234 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:13,234 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:13,234 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:13,234 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:13,234 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:13,235 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:13,235 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:13,235 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:13,235 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:13,235 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:13,236 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:13,237 TRACE [async_io::driver] main_loop: sleeping for 750 us
2021-04-23 10:05:13,238 TRACE [async_io::driver] main_loop: sleeping for 1000 us
2021-04-23 10:05:13,240 TRACE [async_io::driver] main_loop: sleeping for 2500 us
2021-04-23 10:05:13,242 TRACE [async_io::driver] main_loop: sleeping for 5000 us
2021-04-23 10:05:13,248 TRACE [async_io::driver] main_loop: sleeping for 10000 us
2021-04-23 10:05:16,370 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:16,370 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,370 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:16,370 TRACE [polling::epoll] add: epoll_fd=10, fd=15, ev=Event { key: 1, readable: false, writable: false }
2021-04-23 10:05:16,370 INFO [hole::chat::front_conn] Peer address: 127.0.0.1:48924
2021-04-23 10:05:16,370 TRACE [async_tungstenite::handshake] Setting ctx when starting handshake
2021-04-23 10:05:16,370 TRACE [tungstenite::handshake::server] Server handshake initiated.
2021-04-23 10:05:16,371 TRACE [tungstenite::handshake::machine] Doing handshake round.
2021-04-23 10:05:16,371 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:16,371 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,371 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:16,371 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:16,371 TRACE [async_tungstenite::handshake] Setting context in handshake
2021-04-23 10:05:16,371 TRACE [tungstenite::handshake::machine] Doing handshake round.
2021-04-23 10:05:16,371 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:16,371 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,371 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:16,371 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:16,372 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:16,372 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:16,372 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,372 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:16,372 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:16,372 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,372 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:16,372 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,372 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:16,372 TRACE [async_tungstenite::handshake] Setting context in handshake
2021-04-23 10:05:16,372 TRACE [tungstenite::handshake::machine] Doing handshake round.
2021-04-23 10:05:16,373 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:16,373 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,373 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:16,373 TRACE [tungstenite::handshake::machine] Doing handshake round.
2021-04-23 10:05:16,373 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:16,373 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,373 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:16,373 DEBUG [tungstenite::handshake::server] Server handshake done.
2021-04-23 10:05:16,373 INFO [hole::chat::front_conn] connected to: 127.0.0.1:48924
2021-04-23 10:05:16,373 DEBUG [hole::chat::front_conn] launching repeater...
2021-04-23 10:05:16,374 TRACE [async_std::task::builder] spawn
2021-04-23 10:05:16,374 TRACE [polling] Poller::notify()
2021-04-23 10:05:16,374 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:16,374 TRACE [async_std::task::builder] spawn
2021-04-23 10:05:16,374 TRACE [async_std::task::builder] spawn
2021-04-23 10:05:16,374 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:16,374 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:16,374 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:16,374 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:16,374 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,374 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:16,374 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:16,374 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,374 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:16,374 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,374 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,374 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:16,374 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:16,375 DEBUG [hole::chat::stay_awake] Request Repeater Started!
tset
2021-04-23 10:05:16,378 INFO [hole::chat::front_conn] Connection for sending launched
2021-04-23 10:05:16,378 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:16,378 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,378 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:16,378 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,378 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,379 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,379 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,379 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:16,379 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,379 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:16,379 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:16,379 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:16,380 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:16,380 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,380 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:16,380 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:16,380 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,380 TRACE [async_io::driver] block_on: sleep until notification
tset
2021-04-23 10:05:16,386 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:16,386 INFO [hole::chat::front_conn] Connection for receiving launched
2021-04-23 10:05:16,386 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:16,386 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:16,386 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:16,387 TRACE [async_io::driver] main_loop: sleeping for 750 us
2021-04-23 10:05:16,387 DEBUG [hole::chat::stay_awake] Config gotted: "id = \"d63f0037-3daf-49fe-8728-34086da0a27f\"\npublicKey = \"SSK@dp0sjzo69X1kPNpedzY1c~oQEZUWO45D~JQ96mJCnVw,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQECAAE\"\nprivateKey = \"SSK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE\"\n"
2021-04-23 10:05:16,388 TRACE [async_io::driver] main_loop: sleeping for 1000 us
2021-04-23 10:05:16,389 TRACE [async_io::driver] main_loop: sleeping for 2500 us
2021-04-23 10:05:16,391 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:16,392 TRACE [async_io::driver] main_loop: sleeping for 5000 us
2021-04-23 10:05:16,399 TRACE [async_io::driver] main_loop: sleeping for 10000 us
2021-04-23 10:05:16,406 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:16,406 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:16,406 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:16,406 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,407 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:16,407 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:16,407 TRACE [polling] Poller::notify()
2021-04-23 10:05:16,407 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:16,407 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:16,407 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,407 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:16,407 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,407 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,407 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,407 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,407 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:16,407 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,407 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 147]
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol::frame::frame] Second: 10010011
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 25
payload length: 19
payload: 0xd7675570dc203338e365568de316079dc675c
2021-04-23 10:05:16,408 TRACE [tungstenite::protocol] Received message {"type":"startApp"}
2021-04-23 10:05:16,408 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:16,408 INFO [hole::api::selector] matching request...
2021-04-23 10:05:16,408 DEBUG [hole::chat::serv_handler] SENDED ClientHello
Name=start_app_request
ExpectedVersion=2.0
EndMessage
2021-04-23 10:05:16,408 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:16,408 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:16,408 DEBUG [hole::api::handlers] Responsing to start_app: id = "d63f0037-3daf-49fe-8728-34086da0a27f"
publicKey = "SSK@dp0sjzo69X1kPNpedzY1c~oQEZUWO45D~JQ96mJCnVw,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQECAAE"
privateKey = "SSK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE"
2021-04-23 10:05:16,408 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:16,408 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,408 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:16,408 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:16,409 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
tset
2021-04-23 10:05:16,409 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:16,409 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,409 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,409 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:16,409 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:16,409 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:16,409 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,409 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:16,409 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:16,409 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,411 DEBUG [hole::chat::serv_conn] unhandled: NodeHello
2021-04-23 10:05:16,413 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:16,413 TRACE [polling] Poller::notify()
2021-04-23 10:05:16,413 DEBUG [hole::chat::front_conn] Got:
NodeHello
CompressionCodecs=4 - GZIP(0), BZIP2(1), LZMA(2), LZMA_NEW(3)
Revision=build01490
Testnet=false
Version=Fred,0.7,1.0,1490
Build=1490
ConnectionIdentifier=13541269fb1b0c248f14314a0027b8f9
Node=Fred
ExtBuild=29
FCPVersion=2.0
NodeLanguage=ENGLISH
ExtRevision=v29
EndMessage
2021-04-23 10:05:16,413 TRACE [async_io::driver] block_on: sleep until notification
tset
2021-04-23 10:05:16,413 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:16,416 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:16,416 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,416 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,416 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:16,416 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:16,416 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:16,416 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:16,416 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:16,416 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:16,416 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:16,416 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:16,419 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:16,419 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:16,419 TRACE [async_io::driver] block_on()
2021-04-23 10:05:16,419 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,419 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,419 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,419 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,419 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,419 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,419 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,419 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,419 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,419 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:16,420 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [123, 34, 116, 121, 112, 101, 34, 58, 34, 105, 110, 105, 116, 105, 97, 108, 67, 111, 110, 102, 105, 103, 34, 44, 34, 105, 100, 34, 58, 34, 100, 54, 51, 102, 48, 48, 51, 55, 45, 51, 100, 97, 102, 45, 52, 57, 102, 101, 45, 56, 55, 50, 56, 45, 51, 52, 48, 56, 54, 100, 97, 48, 97, 50, 55, 102, 34, 44, 34, 112, 117, 98, 108, 105, 99, 75, 101, 121, 34, 58, 34, 83, 83, 75, 64, 100, 112, 48, 115, 106, 122, 111, 54, 57, 88, 49, 107, 80, 78, 112, 101, 100, 122, 89, 49, 99, 126, 111, 81, 69, 90, 85, 87, 79, 52, 53, 68, 126, 74, 81, 57, 54, 109, 74, 67, 110, 86, 119, 44, 54, 104, 86, 100, 116, 68, 67, 100, 57, 78, 107, 45, 78, 89, 112, 65, 114, 118, 120, 97, 52, 89, 116, 99, 74, 49, 87, 104, 112, 85, 52, 68, 85, 117, 81, 51, 54, 84, 98, 106, 49, 71, 56, 44, 65, 81, 69, 67, 65, 65, 69, 34, 44, 34, 112, 114, 105, 118, 97, 116, 101, 75, 101, 121, 34, 58, 34, 83, 83, 75, 64, 72, 57, 70, 65, 72, 73, 99, 66, 65, 102, 86, 69, 108, 80, 107, 81, 110, 78, 70, 78, 53, 111, 99, 49, 85, 105, 49, 104, 97, 86, 84, 84, 108, 52, 76, 70, 106, 104, 50, 74, 48, 83, 77, 44, 54, 104, 86, 100, 116, 68, 67, 100, 57, 78, 107, 45, 78, 89, 112, 65, 114, 118, 120, 97, 52, 89, 116, 99, 74, 49, 87, 104, 112, 85, 52, 68, 85, 117, 81, 51, 54, 84, 98, 106, 49, 71, 56, 44, 65, 81, 65, 67, 65, 65, 69, 34, 125] }
2021-04-23 10:05:16,420 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 301
payload length: 297
payload: 0x7b2274797065223a22696e697469616c436f6e666967222c226964223a2264363366303033372d336461662d343966652d383732382d333430383664613061323766222c227075626c69634b6579223a2253534b40647030736a7a6f363958316b504e7065647a5931637e6f51455a55574f3435447e4a5139366d4a436e56772c3668566474444364394e6b2d4e59704172767861345974634a31576870553444557551333654626a3147382c41514543414145222c22707269766174654b6579223a2253534b404839464148496342416656456c506b516e4e464e356f633155693168615654546c344c466a68324a30534d2c3668566474444364394e6b2d4e59704172767861345974634a31576870553444557551333654626a3147382c41514143414145227d
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,420 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,420 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,420 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,420 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:16,421 DEBUG [hole::chat::front_conn] Message sended to frontend: {"type":"initialConfig","id":"d63f0037-3daf-49fe-8728-34086da0a27f","publicKey":"SSK@dp0sjzo69X1kPNpedzY1c~oQEZUWO45D~JQ96mJCnVw,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQECAAE","privateKey":"SSK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE"}
2021-04-23 10:05:16,421 DEBUG [hole::api::handlers] Responsing to start_app
2021-04-23 10:05:16,421 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:16,421 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,421 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:16,421 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,421 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,421 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 148]
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol::frame::frame] Second: 10010100
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 26
payload length: 20
payload: 0xef3f9c2e4785f81b67112daf048edee66e5fc6
2021-04-23 10:05:16,421 TRACE [tungstenite::protocol] Received message {"type":"loadUsers"}
2021-04-23 10:05:16,421 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:16,421 INFO [hole::api::selector] matching request...
2021-04-23 10:05:16,426 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:16,429 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:16,430 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:16,430 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:16,430 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:16,430 TRACE [async_io::driver] block_on()
2021-04-23 10:05:16,430 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,430 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,430 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,430 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,431 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,431 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,431 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:16,431 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [123, 34, 116, 121, 112, 101, 34, 58, 34, 117, 115, 101, 114, 76, 105, 115, 116, 34, 44, 34, 117, 115, 101, 114, 115, 34, 58, 91, 123, 34, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 44, 34, 110, 97, 109, 101, 34, 58, 34, 74, 97, 99, 107, 34, 44, 34, 115, 105, 103, 110, 75, 101, 121, 34, 58, 34, 102, 111, 111, 34, 44, 34, 105, 110, 115, 101, 114, 116, 75, 101, 121, 34, 58, 34, 83, 83, 75, 64, 82, 74, 126, 117, 65, 67, 77, 72, 80, 102, 105, 97, 79, 85, 109, 119, 71, 100, 104, 53, 103, 66, 78, 110, 68, 98, 71, 101, 76, 106, 114, 49, 84, 86, 99, 118, 66, 77, 70, 99, 100, 87, 77, 44, 107, 53, 86, 100, 55, 82, 119, 113, 79, 85, 99, 88, 103, 75, 71, 84, 108, 126, 99, 82, 52, 99, 85, 111, 114, 75, 118, 50, 52, 77, 52, 82, 126, 116, 67, 66, 65, 85, 112, 50, 121, 90, 111, 44, 65, 81, 69, 67, 65, 65, 69, 34, 44, 34, 109, 101, 115, 115, 97, 103, 101, 115, 67, 111, 117, 110, 116, 34, 58, 48, 44, 34, 109, 121, 77, 101, 115, 115, 97, 103, 101, 115, 67, 111, 117, 110, 116, 34, 58, 50, 52, 125, 44, 123, 34, 105, 100, 34, 58, 34, 50, 100, 49, 50, 99, 51, 48, 53, 45, 101, 98, 55, 57, 45, 52, 56, 57, 99, 45, 98, 54, 52, 51, 45, 102, 50, 55, 102, 49, 101, 55, 56, 97, 55, 99, 48, 34, 44, 34, 110, 97, 109, 101, 34, 58, 34, 70, 111, 114, 84, 101, 115, 116, 105, 110, 103, 34, 44, 34, 115, 105, 103, 110, 75, 101, 121, 34, 58, 34, 116, 101, 115, 116, 105, 110, 103, 34, 44, 34, 105, 110, 115, 101, 114, 116, 75, 101, 121, 34, 58, 34, 83, 83, 75, 64, 65, 73, 51, 107, 66, 111, 120, 65, 77, 84, 90, 66, 119, 65, 108, 74, 109, 70, 97, 116, 120, 49, 114, 112, 56, 50, 49, 85, 74, 45, 113, 118, 82, 66, 66, 72, 103, 77, 120, 105, 109, 55, 97, 99, 44, 75, 49, 113, 49, 49, 98, 70, 48, 72, 81, 49, 79, 108, 52, 88, 104, 77, 86, 119, 73, 51, 57, 53, 88, 110, 97, 112, 49, 98, 51, 74, 85, 107, 99, 75, 71, 115, 104, 80, 100, 103, 70, 119, 44, 65, 81, 69, 67, 65, 65, 69, 34, 44, 34, 109, 101, 115, 115, 97, 103, 101, 115, 67, 111, 117, 110, 116, 34, 58, 48, 44, 34, 109, 121, 77, 101, 115, 115, 97, 103, 101, 115, 67, 111, 117, 110, 116, 34, 58, 48, 125, 93, 125] }
2021-04-23 10:05:16,431 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 501
payload length: 497
payload: 0x7b2274797065223a22757365724c697374222c227573657273223a5b7b226964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164222c226e616d65223a224a61636b222c227369676e4b6579223a22666f6f222c22696e736572744b6579223a2253534b40524a7e7541434d48506669614f556d774764683567424e6e446247654c6a723154566376424d466364574d2c6b355664375277714f556358674b47546c7e63523463556f724b7632344d34527e74434241557032795a6f2c41514543414145222c226d65737361676573436f756e74223a302c226d794d65737361676573436f756e74223a32347d2c7b226964223a2232643132633330352d656237392d343839632d623634332d663237663165373861376330222c226e616d65223a22466f7254657374696e67222c227369676e4b6579223a2274657374696e67222c22696e736572744b6579223a2253534b404149336b426f78414d545a4277416c4a6d46617478317270383231554a2d717652424248674d78696d3761632c4b317131316246304851314f6c3458684d567749333935586e61703162334a556b634b47736850646746772c41514543414145222c226d65737361676573436f756e74223a302c226d794d65737361676573436f756e74223a307d5d7d
2021-04-23 10:05:16,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:16,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,432 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,432 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,432 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:16,432 DEBUG [hole::chat::front_conn] Message sended to frontend: {"type":"userList","users":[{"id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d","name":"Jack","signKey":"foo","insertKey":"SSK@RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM,k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo,AQECAAE","messagesCount":0,"myMessagesCount":24},{"id":"2d12c305-eb79-489c-b643-f27f1e78a7c0","name":"ForTesting","signKey":"testing","insertKey":"SSK@AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac,K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw,AQECAAE","messagesCount":0,"myMessagesCount":0}]}
2021-04-23 10:05:16,432 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:16,432 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:16,432 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,432 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:16,432 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:16,433 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:16,433 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:16,433 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:16,433 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:16,433 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:16,433 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:17,706 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:17,706 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:17,706 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:17,706 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:17,707 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:17,707 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:17,707 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:17,709 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:17,712 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:17,712 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:18,265 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:18,265 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:18,265 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:18,265 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:18,265 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:18,265 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:18,265 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:18,266 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:18,266 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:18,266 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:18,266 TRACE [polling] Poller::notify()
2021-04-23 10:05:18,266 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:18,266 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:18,266 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:18,266 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:18,266 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:18,266 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:18,265 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:18,267 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:18,267 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:18,267 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:18,267 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:18,267 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:18,267 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:18,267 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:18,267 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:18,267 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:18,267 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:18,268 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,268 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:18,268 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:18,268 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:18,268 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,268 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:18,268 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 225]
2021-04-23 10:05:18,269 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:18,269 TRACE [tungstenite::protocol::frame::frame] Second: 11100001
2021-04-23 10:05:18,269 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:18,269 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:18,269 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 103
payload length: 97
payload: 0x93137b698542d45ca5d601e8c7c6ac9b50681a9b13235d9d426ada1552d45ca36b4eda523c4fdd1c6a1ddf8224bd086c528a73b4cc5573d488e06a48d050381cd813235d8b5e7a119c13354ed81d2dc9c507dba15f6b1a9013354f95
2021-04-23 10:05:18,269 TRACE [tungstenite::protocol] Received message {"type":"loadMessages","userId":"2d12c305-eb79-489c-b643-f27f1e78a7c0","count":10,"startIndex":0}
2021-04-23 10:05:18,269 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:18,269 INFO [hole::api::selector] matching request...
2021-04-23 10:05:18,270 INFO [hole::db::messages] All messages loaded to memory
2021-04-23 10:05:18,271 DEBUG [hole::db::messages] []
2021-04-23 10:05:18,271 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:18,271 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:18,271 TRACE [async_io::driver] block_on()
2021-04-23 10:05:18,271 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:18,271 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:18,271 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,271 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:18,271 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:18,271 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:18,271 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:18,272 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:18,272 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [91, 93] }
2021-04-23 10:05:18,272 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 4
payload length: 2
payload: 0x5b5d
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,272 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:18,273 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:18,273 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:18,273 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,273 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:18,273 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:18,273 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:18,273 DEBUG [hole::chat::front_conn] Message sended to frontend: []
2021-04-23 10:05:18,273 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:18,273 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:18,273 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:18,273 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:18,273 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,273 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:18,273 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:18,274 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:18,274 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:18,274 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:18,274 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:18,274 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:18,274 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:19,013 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:19,013 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:19,014 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:19,014 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:19,015 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:19,015 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:19,016 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:19,020 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:19,020 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:19,021 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
Global=false
EndMessage
IdentifierCollision
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
Global=false
EndMessage
2021-04-23 10:05:19,022 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:19,022 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:19,032 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:19,049 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:19,049 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:20,207 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:20,208 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:20,214 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:20,216 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:20,217 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:20,350 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:20,350 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:20,350 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:20,350 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:20,350 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:20,350 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:20,350 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:20,352 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:20,352 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:20,352 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:20,352 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
Global=false
EndMessage
2021-04-23 10:05:20,352 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:20,354 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:20,357 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:20,358 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:21,639 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:21,640 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:21,640 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:21,640 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:21,641 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:21,658 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:21,659 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:21,659 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:21,659 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:21,660 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:21,660 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:21,661 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:21,664 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:21,665 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
Global=false
EndMessage
2021-04-23 10:05:21,666 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:21,667 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:21,667 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:21,687 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:21,726 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:21,726 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:22,681 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:22,682 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:22,682 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:22,682 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:22,683 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:22,683 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:22,683 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:22,683 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:22,684 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:22,684 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:22,689 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:22,690 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:22,690 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:22,690 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:22,690 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,690 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:22,691 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:22,692 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:22,692 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,697 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:22,697 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:22,698 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 225]
2021-04-23 10:05:22,698 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:22,698 TRACE [tungstenite::protocol::frame::frame] Second: 11100001
2021-04-23 10:05:22,698 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:22,698 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:22,699 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 103
payload length: 97
payload: 0x7bdba729709cf16a2295bc3164b4b6237398b43573dbff72758ab622499df16a22c1eb3661cce76962d4e73564cbfe6439cbe47d62ceb0352d98b16262cab13663cce66164dbff726396a63e74dbe96130d5f1237498a1244997b73578dbe9607d
2021-04-23 10:05:22,699 TRACE [tungstenite::protocol] Received message {"type":"loadMessages","userId":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d","count":10,"startIndex":0}
2021-04-23 10:05:22,700 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:22,700 INFO [hole::api::selector] matching request...
2021-04-23 10:05:22,701 INFO [hole::db::messages] Message: Ok(Message { id: 22, date: 2021-04-22T18:40:37.989775900+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,702 INFO [hole::db::messages] Message: Ok(Message { id: 20, date: 2021-04-22T18:40:37.844596459+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,702 INFO [hole::db::messages] Message: Ok(Message { id: 18, date: 2021-04-22T18:40:37.714170922+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,703 INFO [hole::db::messages] Message: Ok(Message { id: 16, date: 2021-04-22T18:40:37.581089113+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,703 INFO [hole::db::messages] Message: Ok(Message { id: 14, date: 2021-04-22T18:40:37.432847143+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,704 INFO [hole::db::messages] Message: Ok(Message { id: 12, date: 2021-04-22T18:40:37.396670040+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,704 INFO [hole::db::messages] Message: Ok(Message { id: 10, date: 2021-04-22T18:40:37.239896598+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,705 INFO [hole::db::messages] Message: Ok(Message { id: 8, date: 2021-04-22T18:40:37.049990869+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,705 INFO [hole::db::messages] Message: Ok(Message { id: 6, date: 2021-04-22T18:40:36.912055048+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,713 INFO [hole::db::messages] Message: Ok(Message { id: 4, date: 2021-04-22T18:40:36.789330452+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:22,713 INFO [hole::db::messages] All messages loaded to memory
2021-04-23 10:05:22,713 DEBUG [hole::db::messages] [Message { id: 22, date: 2021-04-22T18:40:37.989775900+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 20, date: 2021-04-22T18:40:37.844596459+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 18, date: 2021-04-22T18:40:37.714170922+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 16, date: 2021-04-22T18:40:37.581089113+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 14, date: 2021-04-22T18:40:37.432847143+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 12, date: 2021-04-22T18:40:37.396670040+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 10, date: 2021-04-22T18:40:37.239896598+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 8, date: 2021-04-22T18:40:37.049990869+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 6, date: 2021-04-22T18:40:36.912055048+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 4, date: 2021-04-22T18:40:36.789330452+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }]
2021-04-23 10:05:22,715 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:22,715 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:22,715 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:22,716 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:22,716 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,716 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:22,716 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:22,716 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:22,721 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:22,722 TRACE [async_io::driver] block_on()
2021-04-23 10:05:22,723 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:22,723 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:22,723 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,723 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:22,724 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:22,724 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:22,724 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:22,724 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:22,724 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,724 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:22,724 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:22,724 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:22,724 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:22,724 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,725 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:22,725 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:22,725 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [91, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 57, 56, 57, 55, 55, 53, 57, 48, 48, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 50, 50, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 56, 52, 52, 53, 57, 54, 52, 53, 57, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 50, 48, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 55, 49, 52, 49, 55, 48, 57, 50, 50, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 56, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 53, 56, 49, 48, 56, 57, 49, 49, 51, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 54, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 52, 51, 50, 56, 52, 55, 49, 52, 51, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 52, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 51, 57, 54, 54, 55, 48, 48, 52, 48, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 50, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 50, 51, 57, 56, 57, 54, 53, 57, 56, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 48, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 48, 52, 57, 57, 57, 48, 56, 54, 57, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 56, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 54, 46, 57, 49, 50, 48, 53, 53, 48, 52, 56, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 54, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 54, 46, 55, 56, 57, 51, 51, 48, 52, 53, 50, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 52, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 93] }
2021-04-23 10:05:22,729 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 1372
payload length: 1368
payload: 0x5b7b2264617465223a22323032312d30342d32325431383a34303a33372e3938393737353930302b30333a3030222c2266726f6d5f6d65223a747275652c226964223a32322c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3834343539363435392b30333a3030222c2266726f6d5f6d65223a747275652c226964223a32302c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3731343137303932322b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31382c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3538313038393131332b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31362c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3433323834373134332b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31342c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3339363637303034302b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31322c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3233393839363539382b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31302c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3034393939303836392b30333a3030222c2266726f6d5f6d65223a747275652c226964223a382c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33362e3931323035353034382b30333a3030222c2266726f6d5f6d65223a747275652c226964223a362c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33362e3738393333303435322b30333a3030222c2266726f6d5f6d65223a747275652c226964223a342c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d5d
2021-04-23 10:05:22,733 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:22,733 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,733 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:22,733 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:22,733 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,734 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:22,734 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:22,734 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:22,734 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:22,734 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:22,734 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:22,734 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:22,734 DEBUG [hole::chat::front_conn] Message sended to frontend: [{"date":"2021-04-22T18:40:37.989775900+03:00","from_me":true,"id":22,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.844596459+03:00","from_me":true,"id":20,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.714170922+03:00","from_me":true,"id":18,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.581089113+03:00","from_me":true,"id":16,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.432847143+03:00","from_me":true,"id":14,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.396670040+03:00","from_me":true,"id":12,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.239896598+03:00","from_me":true,"id":10,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.049990869+03:00","from_me":true,"id":8,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:36.912055048+03:00","from_me":true,"id":6,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:36.789330452+03:00","from_me":true,"id":4,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"}]
2021-04-23 10:05:22,737 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:23,027 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:23,027 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:23,028 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:23,028 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:23,029 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:23,029 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:23,030 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:23,035 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:23,035 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:23,036 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
Global=false
EndMessage
IdentifierCollision
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
Global=false
EndMessage
2021-04-23 10:05:23,036 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:23,037 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:23,054 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:23,073 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:23,073 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:24,092 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:24,092 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:24,093 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:24,093 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:24,093 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:24,094 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:24,094 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:24,094 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:24,094 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:24,095 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:24,095 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:24,095 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,096 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:24,096 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:24,097 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:24,097 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,097 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:24,097 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:24,099 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:24,099 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,099 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:24,099 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 225]
2021-04-23 10:05:24,100 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:24,100 TRACE [tungstenite::protocol::frame::frame] Second: 11100001
2021-04-23 10:05:24,100 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:24,100 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:24,101 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 103
payload length: 97
payload: 0x35670613e4126226c486b792a69616b3d45637d3d6283a3b57616a74026226c1660297c4737287b9617a791d292c761d67352c12302b6342362f2815612f7645337b7e6283a2d4b71763a63e297e8266b3a45766c74a607d3663e2833
2021-04-23 10:05:24,102 TRACE [tungstenite::protocol] Received message {"type":"loadMessages","userId":"2d12c305-eb79-489c-b643-f27f1e78a7c0","count":10,"startIndex":0}
2021-04-23 10:05:24,102 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:24,104 INFO [hole::api::selector] matching request...
2021-04-23 10:05:24,105 INFO [hole::db::messages] All messages loaded to memory
2021-04-23 10:05:24,105 DEBUG [hole::db::messages] []
2021-04-23 10:05:24,106 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:24,107 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:24,107 TRACE [async_io::driver] block_on()
2021-04-23 10:05:24,108 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:24,108 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:24,108 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,109 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,109 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:24,110 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:24,110 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:24,110 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:24,110 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,111 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:24,111 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:24,112 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [91, 93] }
2021-04-23 10:05:24,112 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 4
payload length: 2
payload: 0x5b5d
2021-04-23 10:05:24,112 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:24,119 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,120 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:24,120 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:24,120 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,120 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:24,121 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:24,121 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:24,121 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,121 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:24,121 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:24,122 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:24,122 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,122 DEBUG [hole::chat::front_conn] Message sended to frontend: []
2021-04-23 10:05:24,122 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:24,123 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:24,123 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:24,123 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:24,123 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,124 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:24,124 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:24,125 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:24,125 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:24,126 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:24,126 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:24,126 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:24,126 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,129 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,130 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:24,130 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:24,130 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:24,130 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,374 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:24,374 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:24,374 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:24,374 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:24,374 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:24,374 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:24,374 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:24,374 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,375 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:24,375 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
Global=false
EndMessage
2021-04-23 10:05:24,375 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:24,375 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:24,379 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:24,383 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:24,383 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:25,683 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:25,684 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:25,684 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:25,684 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:25,685 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:25,685 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:25,686 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:25,688 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:25,688 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:25,689 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
Global=false
EndMessage
IdentifierCollision
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
Global=false
EndMessage
2021-04-23 10:05:25,689 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:25,690 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:25,704 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:25,726 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:25,726 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:26,172 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:26,172 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:26,173 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:26,173 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:26,173 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:26,788 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:26,788 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:26,788 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:26,789 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:26,789 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:27,026 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:27,027 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:27,027 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:27,028 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:27,028 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:27,029 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:27,029 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:27,064 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:27,077 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:27,080 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:28,154 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,154 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:28,154 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:28,154 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:28,155 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,381 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:28,381 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:28,382 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:28,382 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:28,383 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:28,383 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:28,384 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:28,402 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:28,421 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:28,422 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:28,466 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,466 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:28,466 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:28,467 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,467 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
Global=false
EndMessage
2021-04-23 10:05:28,805 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,805 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:28,805 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:28,805 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:28,806 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,809 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:28,809 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:28,809 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:28,809 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:28,809 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:29,422 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:29,422 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:29,422 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:29,422 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:29,422 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:29,422 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:29,422 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:29,422 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:29,422 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:29,422 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,422 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:29,423 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:29,423 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,423 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 225]
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol::frame::frame] Second: 11100001
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 103
payload length: 97
payload: 0x3a687d49312f2ba632666512576c43322b6e553268251234396c4282e2ba63723156207f3d923673d55257824478783e1d237d6a556c2b6b223796b56227f3c12568251222257c5e356833171662b43352b7b448246d5539683303c
2021-04-23 10:05:29,423 TRACE [tungstenite::protocol] Received message {"type":"loadMessages","userId":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d","count":10,"startIndex":0}
2021-04-23 10:05:29,423 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:29,423 INFO [hole::api::selector] matching request...
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 22, date: 2021-04-22T18:40:37.989775900+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 20, date: 2021-04-22T18:40:37.844596459+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 18, date: 2021-04-22T18:40:37.714170922+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 16, date: 2021-04-22T18:40:37.581089113+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 14, date: 2021-04-22T18:40:37.432847143+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 12, date: 2021-04-22T18:40:37.396670040+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,424 INFO [hole::db::messages] Message: Ok(Message { id: 10, date: 2021-04-22T18:40:37.239896598+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,425 INFO [hole::db::messages] Message: Ok(Message { id: 8, date: 2021-04-22T18:40:37.049990869+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,425 INFO [hole::db::messages] Message: Ok(Message { id: 6, date: 2021-04-22T18:40:36.912055048+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,425 INFO [hole::db::messages] Message: Ok(Message { id: 4, date: 2021-04-22T18:40:36.789330452+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true })
2021-04-23 10:05:29,425 INFO [hole::db::messages] All messages loaded to memory
2021-04-23 10:05:29,425 DEBUG [hole::db::messages] [Message { id: 22, date: 2021-04-22T18:40:37.989775900+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 20, date: 2021-04-22T18:40:37.844596459+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 18, date: 2021-04-22T18:40:37.714170922+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 16, date: 2021-04-22T18:40:37.581089113+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 14, date: 2021-04-22T18:40:37.432847143+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 12, date: 2021-04-22T18:40:37.396670040+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 10, date: 2021-04-22T18:40:37.239896598+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 8, date: 2021-04-22T18:40:37.049990869+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 6, date: 2021-04-22T18:40:36.912055048+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }, Message { id: 4, date: 2021-04-22T18:40:36.789330452+03:00, user_id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), message: "Weeew", from_me: true }]
2021-04-23 10:05:29,422 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:29,426 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:29,422 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:29,426 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:29,426 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:29,426 TRACE [async_io::driver] block_on()
2021-04-23 10:05:29,426 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:29,426 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:29,426 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:29,426 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,426 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:29,426 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:29,426 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:29,426 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:29,426 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:29,426 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:29,426 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:29,426 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:29,426 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:29,427 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,427 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:29,427 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:29,427 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [91, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 57, 56, 57, 55, 55, 53, 57, 48, 48, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 50, 50, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 56, 52, 52, 53, 57, 54, 52, 53, 57, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 50, 48, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 55, 49, 52, 49, 55, 48, 57, 50, 50, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 56, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 53, 56, 49, 48, 56, 57, 49, 49, 51, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 54, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 52, 51, 50, 56, 52, 55, 49, 52, 51, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 52, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 51, 57, 54, 54, 55, 48, 48, 52, 48, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 50, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 50, 51, 57, 56, 57, 54, 53, 57, 56, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 49, 48, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 55, 46, 48, 52, 57, 57, 57, 48, 56, 54, 57, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 56, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 54, 46, 57, 49, 50, 48, 53, 53, 48, 52, 56, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 54, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 44, 123, 34, 100, 97, 116, 101, 34, 58, 34, 50, 48, 50, 49, 45, 48, 52, 45, 50, 50, 84, 49, 56, 58, 52, 48, 58, 51, 54, 46, 55, 56, 57, 51, 51, 48, 52, 53, 50, 43, 48, 51, 58, 48, 48, 34, 44, 34, 102, 114, 111, 109, 95, 109, 101, 34, 58, 116, 114, 117, 101, 44, 34, 105, 100, 34, 58, 52, 44, 34, 109, 101, 115, 115, 97, 103, 101, 34, 58, 34, 87, 101, 101, 101, 119, 34, 44, 34, 117, 115, 101, 114, 95, 105, 100, 34, 58, 34, 56, 56, 102, 97, 53, 52, 57, 98, 45, 52, 101, 100, 50, 45, 52, 57, 50, 55, 45, 98, 55, 99, 101, 45, 97, 98, 50, 98, 51, 98, 102, 99, 53, 53, 49, 100, 34, 125, 93] }
2021-04-23 10:05:29,427 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:29,427 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 1372
payload length: 1368
payload: 0x5b7b2264617465223a22323032312d30342d32325431383a34303a33372e3938393737353930302b30333a3030222c2266726f6d5f6d65223a747275652c226964223a32322c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3834343539363435392b30333a3030222c2266726f6d5f6d65223a747275652c226964223a32302c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3731343137303932322b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31382c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3538313038393131332b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31362c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3433323834373134332b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31342c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3339363637303034302b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31322c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3233393839363539382b30333a3030222c2266726f6d5f6d65223a747275652c226964223a31302c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33372e3034393939303836392b30333a3030222c2266726f6d5f6d65223a747275652c226964223a382c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33362e3931323035353034382b30333a3030222c2266726f6d5f6d65223a747275652c226964223a362c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d2c7b2264617465223a22323032312d30342d32325431383a34303a33362e3738393333303435322b30333a3030222c2266726f6d5f6d65223a747275652c226964223a342c226d657373616765223a225765656577222c22757365725f6964223a2238386661353439622d346564322d343932372d623763652d616232623362666335353164227d5d
2021-04-23 10:05:29,428 TRACE [async_io::driver] main_loop: sleeping for 750 us
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:29,428 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,428 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:29,429 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:29,429 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:29,429 DEBUG [hole::chat::front_conn] Message sended to frontend: [{"date":"2021-04-22T18:40:37.989775900+03:00","from_me":true,"id":22,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.844596459+03:00","from_me":true,"id":20,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.714170922+03:00","from_me":true,"id":18,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.581089113+03:00","from_me":true,"id":16,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.432847143+03:00","from_me":true,"id":14,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.396670040+03:00","from_me":true,"id":12,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.239896598+03:00","from_me":true,"id":10,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:37.049990869+03:00","from_me":true,"id":8,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:36.912055048+03:00","from_me":true,"id":6,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"},{"date":"2021-04-22T18:40:36.789330452+03:00","from_me":true,"id":4,"message":"Weeew","user_id":"88fa549b-4ed2-4927-b7ce-ab2b3bfc551d"}]
2021-04-23 10:05:29,429 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:29,429 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:29,428 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:29,429 TRACE [polling] Poller::notify()
2021-04-23 10:05:29,429 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:29,429 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:29,429 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:29,429 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:29,429 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:29,429 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:29,429 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:29,429 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:29,430 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:29,430 TRACE [async_io::driver] block_on: notified
2021-04-23 10:05:29,430 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:29,430 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:29,430 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:29,430 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:29,430 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:29,430 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:29,429 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:29,430 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:29,430 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:29,430 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,430 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:29,430 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:29,430 TRACE [async_io::driver] main_loop: sleeping for 750 us
2021-04-23 10:05:29,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:29,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:29,431 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:29,431 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:29,431 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:29,431 TRACE [async_io::driver] main_loop: sleeping for 1000 us
2021-04-23 10:05:29,431 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:29,433 TRACE [async_io::driver] main_loop: sleeping for 2500 us
2021-04-23 10:05:29,435 TRACE [async_io::driver] main_loop: sleeping for 5000 us
2021-04-23 10:05:29,441 TRACE [async_io::driver] main_loop: sleeping for 10000 us
2021-04-23 10:05:29,722 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:29,722 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:29,722 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:29,722 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:29,723 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:29,723 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:29,723 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:29,726 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:29,729 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:29,729 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:30,209 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:30,209 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:30,209 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:30,210 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:30,210 TRACE [polling] Poller::notify()
2021-04-23 10:05:30,210 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:30,210 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:30,210 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:30,210 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:30,211 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:30,211 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:30,211 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:30,211 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:30,211 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:30,212 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:30,212 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:30,212 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:30,212 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:30,212 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:30,213 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:30,211 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:30,213 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:30,213 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:30,213 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:30,213 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:30,214 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,214 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:30,214 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:30,215 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:30,215 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,215 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:30,215 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 225]
2021-04-23 10:05:30,216 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:30,216 TRACE [tungstenite::protocol::frame::frame] Second: 11100001
2021-04-23 10:05:30,216 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:30,216 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:30,216 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 103
payload length: 97
payload: 0xc5586c8dce1f3ace9c167795da377d87cd1b7f91cd5834d6cb97d86f71e3ace9c487cc58c192bc48b577d96894335c086437bd9dc4c2cc7931c2ac3d84b7dc3861b2f978e5834d6dd156d9aca5822c58e563a87ca1b6a80f7147c91c65822c4c3
2021-04-23 10:05:30,217 TRACE [tungstenite::protocol] Received message {"type":"loadMessages","userId":"2d12c305-eb79-489c-b643-f27f1e78a7c0","count":10,"startIndex":0}
2021-04-23 10:05:30,217 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:30,217 INFO [hole::api::selector] matching request...
2021-04-23 10:05:30,218 INFO [hole::db::messages] All messages loaded to memory
2021-04-23 10:05:30,218 DEBUG [hole::db::messages] []
2021-04-23 10:05:30,219 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:30,219 INFO [hole::chat::serv_handler] Message sended to client thread
2021-04-23 10:05:30,219 TRACE [async_std::task::builder] block_on
2021-04-23 10:05:30,219 TRACE [async_io::driver] block_on()
2021-04-23 10:05:30,220 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:30,220 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:30,220 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:30,221 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:30,221 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,221 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:30,221 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:30,222 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:30,222 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,222 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:30,223 TRACE [polling::epoll] modify: epoll_fd=10, fd=15, ev=Event { key: 1, readable: true, writable: false }
2021-04-23 10:05:30,223 TRACE [async_tungstenite::compat] WouldBlock
2021-04-23 10:05:30,224 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:30,224 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:30,224 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,224 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:30,224 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:30,225 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:30,225 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:30,225 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,225 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:30,225 TRACE [tungstenite::protocol] Frames still in queue: 1
2021-04-23 10:05:30,226 TRACE [tungstenite::protocol] Sending frame: Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Data(Text), mask: None }, payload: [91, 93] }
2021-04-23 10:05:30,226 TRACE [tungstenite::protocol::frame] writing frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 4
payload length: 2
payload: 0x5b5d
2021-04-23 10:05:30,226 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:167 Write.write
2021-04-23 10:05:30,226 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,226 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:172 Write.with_context write -> poll_write
2021-04-23 10:05:30,228 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:30,228 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,228 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:30,228 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:30,228 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:30,228 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:30,229 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:30,229 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:30,229 TRACE [async_io::driver] block_on: completed
2021-04-23 10:05:30,229 DEBUG [hole::chat::front_conn] Message sended to frontend: []
2021-04-23 10:05:30,230 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:30,779 TRACE [polling] Poller::notify()
2021-04-23 10:05:30,779 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:30,779 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:30,780 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:30,780 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:30,780 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:30,780 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:30,781 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:30,781 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:30,781 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:30,781 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:30,781 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:30,781 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:30,782 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:30,782 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:30,782 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:30,782 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:30,782 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:30,782 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:30,783 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:30,783 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:30,784 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:30,785 TRACE [async_io::driver] main_loop: sleeping for 750 us
2021-04-23 10:05:30,786 TRACE [async_io::driver] main_loop: sleeping for 1000 us
2021-04-23 10:05:30,787 TRACE [async_io::driver] main_loop: sleeping for 2500 us
2021-04-23 10:05:30,790 TRACE [async_io::driver] main_loop: sleeping for 5000 us
2021-04-23 10:05:30,797 TRACE [async_io::driver] main_loop: sleeping for 10000 us
2021-04-23 10:05:31,029 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:31,029 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:31,030 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:31,035 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:31,036 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:31,036 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:31,036 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:31,044 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:31,087 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:31,087 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:31,149 TRACE [polling] Poller::notify()
2021-04-23 10:05:31,149 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:31,150 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:31,151 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:31,151 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:31,151 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:31,151 TRACE [async_io::driver] block_on: notified
2021-04-23 10:05:31,151 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:31,151 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:31,151 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:31,151 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:31,151 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:31,151 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:31,151 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:31,151 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
Global=false
EndMessage
2021-04-23 10:05:31,152 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:31,152 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,183 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,184 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:32,184 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:32,185 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:32,185 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,388 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:32,388 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:32,389 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:32,389 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:32,390 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:32,390 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:32,391 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:32,427 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:32,429 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,430 DEBUG [hole::chat::serv_conn] unhandled: IdentifierCollision
2021-04-23 10:05:32,430 DEBUG [hole::chat::front_conn] Got:
IdentifierCollision
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
Global=false
EndMessage
2021-04-23 10:05:32,430 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:32,430 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,434 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:32,434 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:32,790 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:32,790 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,791 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:32,791 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:32,792 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,803 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:32,803 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:32,803 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:32,804 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:32,804 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:33,734 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:33,735 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:33,735 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:33,735 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:33,736 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:33,736 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:33,737 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:33,754 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:33,770 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:33,770 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:33,906 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:33,906 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:33,907 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:33,907 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:33,907 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:34,484 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:34,484 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:34,485 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:34,486 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:34,486 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:35,070 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:35,071 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:35,071 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:35,071 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:35,071 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:35,071 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:35,071 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:35,075 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:35,079 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:35,079 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:35,530 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:35,530 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:35,531 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:35,531 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:35,531 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:35,533 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:35,534 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:35,534 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:35,534 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:35,534 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:36,379 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:36,380 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:36,380 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:36,380 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:36,380 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:36,380 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:36,380 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:36,384 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:36,387 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:36,387 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:36,578 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:36,578 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:36,578 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:36,578 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:36,578 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:36,581 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:36,581 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:36,581 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:36,581 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:36,581 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:37,242 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:37,242 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:37,242 TRACE [async_io::reactor] react: 1 ready wakers
2021-04-23 10:05:37,242 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:37,242 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:37,242 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:37,243 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:37,243 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:37,243 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:37,243 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:37,243 TRACE [polling] Poller::notify()
2021-04-23 10:05:37,243 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:37,243 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:37,243 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:37,243 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:37,243 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:37,243 TRACE [async_io::driver] block_on: stops hogging the reactor
2021-04-23 10:05:37,243 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:37,243 TRACE [async_io::driver] main_loop: waiting on I/O
2021-04-23 10:05:37,243 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:37,243 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:37,243 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:37,243 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:37,243 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:301 Stream.poll_next
2021-04-23 10:05:37,243 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:256 WebSocketStream.with_context
2021-04-23 10:05:37,243 TRACE [async_tungstenite] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/lib.rs:306 Stream.with_context poll_next -> read_message()
2021-04-23 10:05:37,244 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:182 Write.flush
2021-04-23 10:05:37,244 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:37,244 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:187 Write.with_context flush -> poll_flush
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol] Frames still in queue: 0
2021-04-23 10:05:37,244 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:147 Read.read
2021-04-23 10:05:37,244 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:124 AllowStd.with_context
2021-04-23 10:05:37,244 TRACE [async_tungstenite::compat] /home/horhik/.cargo/registry/src/github.com-1ecc6299db9ec823/async-tungstenite-0.11.0/src/compat.rs:152 Read.with_context read -> poll_read
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol::frame::frame] Parsed headers [129, 218]
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol::frame::frame] First: 10000001
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol::frame::frame] Second: 11011010
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol::frame::frame] Opcode: Data(Text)
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol::frame::frame] Masked: true
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol::frame] received frame
<FRAME>
final: true
reserved: false false false
opcode: TEXT
length: 96
payload length: 90
payload: 0x4f4244d9445129a161355ce502d55d347157c5164c12cd511343c1535129a162917cd143351c4164c12d547542e95042a826419257530951955297d4d498d31dc225438d52527c65579855575390161d
2021-04-23 10:05:37,244 TRACE [tungstenite::protocol] Received message {"type":"sendMessage","message":"I'm Sad","userId":"2d12c305-eb79-489c-b643-f27f1e78a7c0"}
2021-04-23 10:05:37,244 INFO [hole::chat::front_conn] new request
2021-04-23 10:05:37,245 INFO [hole::api::selector] matching request...
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,245 DEBUG [hole::api::handlers] CUM CUM
2021-04-23 10:05:37,250 INFO [hole::db::users] User 2d12c305-eb79-489c-b643-f27f1e78a7c0 founded
2021-04-23 10:05:37,251 ERROR [hole::db::messages] failed to insert message SqliteFailure(Error { code: ConstraintViolation, extended_code: 1555 }, Some("UNIQUE constraint failed: messages.id"))
2021-04-23 10:05:37,256 TRACE [async_io::driver] block_on()
2021-04-23 10:05:37,257 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:37,687 DEBUG [hole::chat::stay_awake] enough sleep
2021-04-23 10:05:37,687 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1"
2021-04-23 10:05:37,687 DEBUG [hole::chat::stay_awake] sending "USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1"
2021-04-23 10:05:37,689 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/88fa549b-4ed2-4927-b7ce-ab2b3bfc551d/1
Identifier=rec;88fa549b-4ed2-4927-b7ce-ab2b3bfc551d;1
ReturnType=direct
EndMessage
2021-04-23 10:05:37,689 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:37,689 DEBUG [hole::chat::serv_handler] SENDED ClientGet
URI=USK@H9FAHIcBAfVElPkQnNFN5oc1Ui1haVTTl4LFjh2J0SM,6hVdtDCd9Nk-NYpArvxa4YtcJ1WhpU4DUuQ36Tbj1G8,AQACAAE/2d12c305-eb79-489c-b643-f27f1e78a7c0/1
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
ReturnType=direct
EndMessage
2021-04-23 10:05:37,689 INFO [hole::chat::serv_handler] Message sended to freenet
2021-04-23 10:05:37,692 INFO [hole::db::users] User: Ok(User { id: Id(88fa549b-4ed2-4927-b7ce-ab2b3bfc551d), name: "Jack", sign_key: "foo", insert_key: SSK { sign_key: "RJ~uACMHPfiaOUmwGdh5gBNnDbGeLjr1TVcvBMFcdWM", decrypt_key: "k5Vd7RwqOUcXgKGTl~cR4cUorKv24M4R~tCBAUp2yZo", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 24 })
2021-04-23 10:05:37,697 INFO [hole::db::users] User: Ok(User { id: Id(2d12c305-eb79-489c-b643-f27f1e78a7c0), name: "ForTesting", sign_key: "testing", insert_key: SSK { sign_key: "AI3kBoxAMTZBwAlJmFatx1rp821UJ-qvRBBHgMxim7ac", decrypt_key: "K1q11bF0HQ1Ol4XhMVwI395Xnap1b3JUkcKGshPdgFw", settings: Some("AQECAAE") }, messages_count: 0, my_messages_count: 0 })
2021-04-23 10:05:37,697 INFO [hole::db::users] All users loaded to memory
2021-04-23 10:05:38,610 TRACE [polling] Poller::notify()
2021-04-23 10:05:38,610 TRACE [async_io::driver] block_on: sleep until notification
2021-04-23 10:05:38,610 TRACE [polling::epoll] notify: epoll_fd=10, event_fd=11
2021-04-23 10:05:38,610 TRACE [polling::epoll] new events: epoll_fd=10, res=1
2021-04-23 10:05:38,610 DEBUG [hole::chat::serv_conn] unhandled: GetFailed
2021-04-23 10:05:38,611 TRACE [polling::epoll] modify: epoll_fd=10, fd=11, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:38,611 DEBUG [hole::chat::front_conn] Got:
GetFailed
Identifier=rec;2d12c305-eb79-489c-b643-f27f1e78a7c0;1
CodeDescription=The request was terminated by a node because it had recently received a request for the same key and that request had failed
ShortCodeDescription=Data not found (recently failed)
Fatal=false
Code=30
EndMessage
2021-04-23 10:05:38,611 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:38,611 INFO [hole::chat::serv_conn] Sended to client! 1024
2021-04-23 10:05:38,611 TRACE [async_io::reactor] react: 0 ready wakers
2021-04-23 10:05:38,611 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:38,612 TRACE [async_io::driver] main_loop: notified
2021-04-23 10:05:38,612 TRACE [async_io::driver] main_loop: sleeping for 50 us
2021-04-23 10:05:38,611 TRACE [async_io::driver] block_on: waiting on I/O
2021-04-23 10:05:38,612 TRACE [async_io::driver] main_loop: sleeping for 75 us
2021-04-23 10:05:38,612 TRACE [async_io::reactor] process_timers: 0 ready wakers
2021-04-23 10:05:38,612 TRACE [polling] Poller::wait(_, None)
2021-04-23 10:05:38,612 TRACE [async_io::driver] main_loop: sleeping for 100 us
2021-04-23 10:05:38,613 TRACE [polling::epoll] wait: epoll_fd=10, timeout=None
2021-04-23 10:05:38,613 TRACE [polling::epoll] modify: epoll_fd=10, fd=12, ev=Event { key: 18446744073709551615, readable: true, writable: false }
2021-04-23 10:05:38,613 TRACE [async_io::driver] main_loop: sleeping for 250 us
2021-04-23 10:05:38,613 TRACE [async_io::driver] main_loop: sleeping for 500 us
2021-04-23 10:05:38,614 TRACE [async_io::driver] main_loop: sleeping for 750 us
2021-04-23 10:05:38,615 TRACE [async_io::driver] main_loop: sleeping for 1000 us
2021-04-23 10:05:38,616 TRACE [async_io::driver] main_loop: sleeping for 2500 us
2021-04-23 10:05:38,619 TRACE [async_io::driver] main_loop: sleeping for 5000 us
2021-04-23 10:05:38,625 TRACE [async_io::driver] main_loop: sleeping for 10000 us