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