diff --git a/log b/log deleted file mode 100644 index 503d094..0000000 --- a/log +++ /dev/null @@ -1,1705 +0,0 @@ -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 - -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 diff --git a/src/api/handlers.rs b/src/api/handlers.rs index 494caf6..7b7d66e 100644 --- a/src/api/handlers.rs +++ b/src/api/handlers.rs @@ -1,10 +1,11 @@ use super::response::User; use super::response::{AppStatus, ResponseType}; use crate::api::request::Request; +use crate::api::types::Message as FrontMessage; use crate::chat::init_config; use crate::chat::types::PackedMessage; use crate::chat::types::SP; -use crate::db::{self, messages, types, users}; +use crate::db::{self, messages, types::Message as DbMessage, users}; use async_std::io::Result; use fcpv2::client::fcp_types::{ClientHello, ClientPut}; use fcpv2::types::{ @@ -85,13 +86,6 @@ pub fn send_message( conn: &Connection, server_sender: SP, ) -> Result<()> { - log::debug!("CUM CUM"); - log::debug!("CUM CUM"); - log::debug!("CUM CUM"); - log::debug!("CUM CUM"); - log::debug!("CUM CUM"); - log::debug!("CUM CUM"); - log::debug!("CUM CUM"); if let Ok(user_data) = db::users::get_user_by_id(user_id, conn) { // Add message to DB let key = user_data.insert_key; @@ -106,9 +100,9 @@ pub fn send_message( from_me: true, }; let _ = db::messages::add_my_message(db_message, conn).unwrap(); - log::debug!("sending new message to freent"); + log::debug!("Sending new message to freent..."); let fcp_req: String = - ClientPut::new_default_direct(fcpv2::types::USK{ ssk: key, path: format!("{}/{}", &identifier, message_id)}, &format!("{}/{}", &identifier, &message_id )[..], &message[..]).convert(); + ClientPut::new_default_direct(fcpv2::types::USK{ ssk: key, path: format!("{}/{}", &identifier, message_id)}, &format!("new-messge-{}/{}", &identifier, &message_id )[..], &message[..]).convert(); server_sender .send(PackedMessage::ToFreenet(fcp_req)) .unwrap(); @@ -136,8 +130,18 @@ pub fn load_messages( conn: &Connection, server_sender: SP, ) -> Result<()> { - let messages = db::messages::select_n_last_messages(user_id, start_index, count, conn).unwrap(); - let jsoned = json!(messages); + let messages: Vec = db::messages::select_n_last_messages(user_id.clone(), start_index, count, conn).unwrap(); + let jsoned = json!( + ResponseType::MessageList{ + messages: messages.into_iter().map(|msg| -> FrontMessage {return FrontMessage{ + message: msg.message, + date: msg.date, + id: user_id.0, + from_me: msg.from_me, + }}).collect(), + id: user_id.0 + } + ); let _ = server_sender.send(PackedMessage::ToClient(jsoned.to_string())).unwrap(); Ok(()) diff --git a/src/api/response.rs b/src/api/response.rs index db55549..8de48eb 100644 --- a/src/api/response.rs +++ b/src/api/response.rs @@ -3,6 +3,9 @@ use crate::db::types::User as SqliteUser; use serde_derive::{Deserialize, Serialize}; use tungstenite::http::Response; pub type InsertKey = String; + +use crate::db::types::Id; +use crate::api::types::Message; #[derive(Serialize, Deserialize, Debug)] #[serde(rename_all = "camelCase")] #[serde(tag = "type")] @@ -23,6 +26,11 @@ pub enum ResponseType { }, UserList { users: Vec, + }, +#[serde(rename_all = "camelCase")] + MessageList{ + id: uuid::Uuid, + messages: Vec } } #[derive(Serialize, Deserialize)] diff --git a/src/api/types.rs b/src/api/types.rs index 8a557cd..4a0d56c 100644 --- a/src/api/types.rs +++ b/src/api/types.rs @@ -6,6 +6,6 @@ use serde::{Serialize, Deserialize}; pub struct Message { pub message: String, pub date: Time, - pub id: Id, + pub id: uuid::Uuid, pub from_me: bool, } diff --git a/src/chat/front_conn.rs b/src/chat/front_conn.rs index 2c04132..6da8a11 100644 --- a/src/chat/front_conn.rs +++ b/src/chat/front_conn.rs @@ -1,5 +1,6 @@ use crate::chat::types::PackedMessage; use crate::db; +use crate::db::types::Id; use async_std::{ io, net::{TcpListener, TcpStream}, @@ -132,7 +133,7 @@ async fn connection_for_receiving( crate::db::types::Message { id: id, date: jsoned.date, - user_id: jsoned.id, + user_id: Id(jsoned.id), message: jsoned.message, from_me: jsoned.from_me, }, @@ -148,11 +149,14 @@ async fn connection_for_receiving( */ } None => {} - _ => {} + _ => { + log::debug!("Got message from Freenet:\n {:?}", &r ) + } } // .expect("Couldn't send messge"); } - _ => {} + _ => { + } } // } } diff --git a/src/chat/stay_awake.rs b/src/chat/stay_awake.rs index 28f613d..a74566d 100644 --- a/src/chat/stay_awake.rs +++ b/src/chat/stay_awake.rs @@ -20,6 +20,7 @@ pub async fn request_repeater(ss: SP, conn: Pool) -> io let parsed: crate::chat::Config = toml::from_str(&config[..]).unwrap(); log::debug!("Config gotted: {:?}", &config); +// let identifier_fil = File::open( ) ; loop { let users: Vec = crate::db::users::load_all_users(&db).unwrap();