[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl] Starting the websocket server
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl::handlers] Setting up the handler
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl] Starting the Ubuntu Touch client
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl::ut] Starting the ubuntu touch client
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  warp::server] Server::run; addr=127.0.0.1:9081
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  warp::server] Server::run; addr=127.0.0.1:9080
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  warp::server] listening on http://127.0.0.1:9081
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  warp::server] listening on http://127.0.0.1:9080
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287445
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] hit max_lsn 287445 in iterator, stopping
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287446 and 524287
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::snapshot] removing old snapshot file "/home/phablet/.config/axolotl.nanuc/sled/snap.00000000000462AD"
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287446
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287446, recovered lsn 287446
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287446 next_lid: 287446
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::meta] allocated pid 8 for root of new_tree [115, 116, 97, 116, 101]
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287446 to 287480
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG presage_store_sled] migrating from v0, nothing to do
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287480 to 287541
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] wrote lsns 287446-287479 to disk at offsets 287446-287479, maxed false complete_len 34
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] mark_interval(287446, 34)
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] wrote lsns 287480-287540 to disk at offsets 287480-287540, maxed false complete_len 61
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] mark_interval(287480, 61)
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] new highest interval: 287446 - 287479
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] new highest interval: 287480 - 287540
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] make_stable(287540) returning
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG presage_store_sled] migrating from schema v1 to v2: encrypting state if cipher is enabled
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287541 to 287570
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] wrote lsns 287541-287569 to disk at offsets 287541-287569, maxed false complete_len 29
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] mark_interval(287541, 29)
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] new highest interval: 287541 - 287569
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] make_stable(287569) returning
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG presage_store_sled] migrating from schema v2 to v3: dropping encrypted group cache
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287570 to 287599
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] wrote lsns 287570-287598 to disk at offsets 287570-287598, maxed false complete_len 29
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] mark_interval(287570, 29)
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] new highest interval: 287570 - 287598
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] make_stable(287598) returning
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287598
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iterator] hit max_lsn 287598 in iterator, stopping
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287599 and 524287
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::snapshot] removing old snapshot file "/home/phablet/.config/axolotl.nanuc/sled/snap.00000000000462D6"
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287599
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287599, recovered lsn 287599
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287599 next_lid: 287599
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl::handlers] Setting up the manager2
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl::handlers] Not yet registered.
[01.11.24 18:43] [2024-11-01T17:43:50Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:43] [2024-11-01T17:43:50Z INFO  axolotl::handlers] Axolotl backend started
[01.11.24 18:43] library "eglSubDriverAndroid.so" not found
[01.11.24 18:43] Creating a QMirClientScreen now
[01.11.24 18:43] lomiri.deprecations: At least 1 Ubuntu.* QML type is in use. Ubuntu.* type is deprecated and is provided for compatibility. To print what types are being in use, set "QT_LOGGING_RULES=lomiri.deprecations.debug=true" environment variable.
[01.11.24 18:43] [44969:44989:1101/184351.236493:ERROR:file_path_watcher_linux.cc(71)] Failed to read /proc/sys/fs/inotify/max_user_watches
[01.11.24 18:43] qml: MainUt_QMLTYPE_30(0x557524d970)"Axolotl": In Lomiri.Components 1.3, the use of Page.title, Page.flickable and Page.head is deprecated. Use Page.header and the PageHeader component instead.
[01.11.24 18:43] file:///opt/click.ubuntu.com/axolotl.nanuc/2.0.4/ut/MainUt.qml:231:3: Unable to assign [undefined] to QObject*
[01.11.24 18:43] file:///opt/click.ubuntu.com/axolotl.nanuc/2.0.4/ut/MainUt.qml:289: TypeError: Cannot read property 'message' of null
[01.11.24 18:43] [44969:45001:1101/184351.505620:ERROR:zygote_host_impl_linux.cc(263)] Failed to adjust OOM score of renderer with pid 45002: Permission denied (13)
[01.11.24 18:43] qml: [Axolotl Web View] [JS] url changed http://localhost:9081/
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] parsed 10 headers
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG warp::filters::fs] dir: appending index.html to directory path
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 171 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 1561 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] parsed 10 headers
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 187 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] parsed 10 headers
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 170 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 6025 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 16384 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 16384 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:51Z DEBUG hyper::proto::h1::io] flushed 2975 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 172 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 16384 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 6645 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 11 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 166 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG axolotl] New websocket connection
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG axolotl::handlers] Starting the manager loop: 0
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG axolotl::handlers] Is registered: false
[01.11.24 18:43] [2024-11-01T17:43:52Z INFO  axolotl::handlers] Starting registration process
[01.11.24 18:43] [2024-11-01T17:43:52Z INFO  axolotl::handlers] Starting registration
[01.11.24 18:43] [2024-11-01T17:43:52Z INFO  axolotl::handlers] Sent registration start message to client
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 184 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 6167 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 183 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 125 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 182 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 169 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 65 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 868 bytes
[01.11.24 18:43] qml: [Axolotl Web View] [JS] url changed http://localhost:9081/
[01.11.24 18:43] qml: [Axolotl Web View] [JS] (http://localhost:9081/assets/index-uR8DTgvK.js: 20207) Untranslated de key found: Please be aware:
[01.11.24 18:43] qml: [Axolotl Web View] [JS] (http://localhost:9081/assets/index-uR8DTgvK.js: 20207) Untranslated de key found: Registering your phone number with Axolotl will
[01.11.24 18:43] qml: [Axolotl Web View] [JS] (http://localhost:9081/assets/index-uR8DTgvK.js: 20207) Untranslated de key found: de-register your existing Signal account and also
[01.11.24 18:43] qml: [Axolotl Web View] [JS] (http://localhost:9081/assets/index-uR8DTgvK.js: 20207) Untranslated de key found: de-link your Signal Desktop.
[01.11.24 18:43] qml: [Axolotl Web View] [JS] (http://localhost:9081/assets/index-uR8DTgvK.js: 20207) Untranslated de key found: Register as secondary device (like signal desktop)
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 171 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 5250 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 10 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 173 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 176 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 4448 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 8192 bytes
[01.11.24 18:43] [2024-11-01T17:43:52Z DEBUG hyper::proto::h1::io] flushed 6276 bytes
[01.11.24 18:43] [2024-11-01T17:43:54Z INFO  axolotl::handlers] Axolotl registration request: registerSecondaryDevice
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::handlers] Registering secondary device
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::handlers] Creating provisioning link
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287598
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] hit max_lsn 287598 in iterator, stopping
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287599 and 524287
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::io] parsed 9 headers
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::conn] incoming body is empty
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::io] flushed 184 bytes
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::io] flushed 168 bytes
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::io] flushed 82 bytes
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG hyper::proto::h1::io] flushed 1952 bytes
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287599
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287599, recovered lsn 287599
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287599 next_lid: 287599
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287598
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iterator] hit max_lsn 287598 in iterator, stopping
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287599 and 524287
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287599
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287599, recovered lsn 287599
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287599 next_lid: 287599
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::handlers] Creating runtime
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::handlers] provision linking: runtime created
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::handlers] Provisioning link created successfully
[01.11.24 18:43] [2024-11-01T17:43:54Z INFO  axolotl::handlers] Awaiting for provisioning link
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::handlers] Spawning manager thread
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::manager_thread] manager thread started, get contact
[01.11.24 18:43] [2024-11-01T17:43:54Z INFO  axolotl::manager_thread] Loading the configuration store
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG axolotl::manager_thread] The config store reports not registered, registering the manage
[01.11.24 18:43] [2024-11-01T17:43:54Z INFO  axolotl::manager_thread] The config store is not valid yet, not registered yet
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287599 to 287640
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] wrote lsns 287599-287639 to disk at offsets 287599-287639, maxed false complete_len 41
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] mark_interval(287599, 41)
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] new highest interval: 287599 - 287639
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG sled::pagecache::iobuf] make_stable(287639) returning
[01.11.24 18:43] qml: [Axolotl Web View] [JS] (http://localhost:9081/assets/index-uR8DTgvK.js: 20207) Untranslated de key found: Please scan the QR code with your primary signal device.
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG rustls::webpki::anchors] add_parsable_certificates processed 1 valid and 0 invalid certs
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG tracing::span] websocket;
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG rustls::webpki::anchors] add_parsable_certificates processed 1 valid and 0 invalid certs
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG rustls::client::hs] No cached session for DnsName("chat.signal.org")
[01.11.24 18:43] [2024-11-01T17:43:54Z DEBUG rustls::client::hs] Not resuming any session
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG rustls::client::hs] Using ciphersuite TLS13_AES_256_GCM_SHA384
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG rustls::client::tls13] Not resuming
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG rustls::client::tls13] TLS1.3 encrypted extensions: [ServerNameAck, Unknown(UnknownExtension { typ: EllipticCurves, payload: 0014001d001700180019001e01000101010201030104 })]
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG rustls::client::hs] ALPN protocol is None
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG tungstenite::handshake::client] Client handshake done.
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG libsignal_service_hyper::websocket] WebSocket connected: Response { status: 101, version: HTTP/1.1, headers: {"date": "Fri, 01 Nov 2024 17:43:55 GMT", "x-signal-timestamp": "1730483035237", "vary": "Origin", "upgrade": "websocket", "connection": "Upgrade", "sec-websocket-accept": "kgvQXvgDPuxz7xEwQC0rQpNBd+w="}, body: None }
[01.11.24 18:43] [2024-11-01T17:43:55Z INFO  presage::manager::linking] generating qrcode from provisioning link: sgnl://linkdevice?uuid=_Fgxxxxxxxxxxx-0Nxxxxxxxxx&pub_key=BTXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXsCrUpkU0g
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG axolotl::handlers] Got provisioning link: Url { scheme: "sgnl", cannot_be_a_base: false, username: "", password: None, host: Some(Domain("linkdevice")), port: None, path: "", query: Some("uuid=_Fgxxxxxxxxxxxxxxx-0NjxxxxxxxxD&pub_key=BTtjxxxxxxxxxxxxxxxxxxxxxxxxxxxU0g"), fragment: None }
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG axolotl::handlers] Provisioning link handled successfully
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG axolotl::handlers] Sending provisioning link
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG axolotl::handlers] Provisioning link sent successfully to client
[01.11.24 18:43] [2024-11-01T17:43:55Z DEBUG libsignal_service::websocket] Sending keep alive upon request
[01.11.24 18:44] [2024-11-01T17:44:24Z DEBUG hyper::proto::h1::conn] read eof
[01.11.24 18:44] [2024-11-01T17:44:24Z DEBUG hyper::proto::h1::conn] read eof
[01.11.24 18:44] [2024-11-01T17:44:24Z DEBUG hyper::proto::h1::conn] read eof
[01.11.24 18:44] QObject::startTimer: Timers cannot be started from another thread
[01.11.24 18:44] QObject::killTimer: Timers cannot be stopped from another thread
[01.11.24 18:44] QObject::startTimer: Timers cannot be started from another thread
[01.11.24 18:45] [2024-11-01T17:45:06Z DEBUG libsignal_service::websocket] Sending keep alive upon request
[01.11.24 18:45] [2024-11-01T17:45:06Z DEBUG tungstenite::protocol] Received close frame: Some(CloseFrame { code: Away, reason: "Connection Idle Timeout" })
[01.11.24 18:45] [2024-11-01T17:45:06Z DEBUG tungstenite::protocol] Replying to close with Frame { header: FrameHeader { is_final: true, rsv1: false, rsv2: false, rsv3: false, opcode: Control(Close), mask: None }, payload: [3, 233, 67, 111, 110, 110, 101, 99, 116, 105, 111, 110, 32, 73, 100, 108, 101, 32, 84, 105, 109, 101, 111, 117, 116] }
[01.11.24 18:45] [2024-11-01T17:45:06Z WARN  libsignal_service_hyper::websocket] Websocket closing: Some(CloseFrame { code: Away, reason: "Connection Idle Timeout" })
[01.11.24 18:45] [2024-11-01T17:45:06Z INFO  libsignal_service::provisioning::pipe] Sink closed, provisioning is done!
[01.11.24 18:45] [2024-11-01T17:45:06Z ERROR axolotl::manager_thread] Failed to register: failed to provision device: Invalid provisioning data: no provisioning message received
[01.11.24 18:45] [2024-11-01T17:45:06Z ERROR libsignal_service::websocket] SignalWebSocket: Websocket error: end of web request stream; socket closing
[01.11.24 18:45] [2024-11-01T17:45:06Z DEBUG axolotl::manager_thread] manager thread started, setup finished
[01.11.24 18:45] [2024-11-01T17:45:06Z INFO  axolotl::manager_thread] Got error: this client is not yet registered, please register or link as a secondary device
[01.11.24 18:45] [2024-11-01T17:45:06Z INFO  axolotl::manager_thread] Could not load contacts
[01.11.24 18:45] [2024-11-01T17:45:06Z INFO  axolotl::handlers] provision linking: ManagerThread started, ready to receive messages from the client.
[01.11.24 18:45] [2024-11-01T17:45:06Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:45] [2024-11-01T17:45:07Z ERROR axolotl::handlers] Error registering secondary device: this client is not yet registered, please register or link as a secondary device
[01.11.24 18:45] [2024-11-01T17:45:07Z DEBUG axolotl::handlers] Break out of loop, because error channel is closed
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287639
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] hit max_lsn 287639 in iterator, stopping
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287640 and 524287
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::snapshot] removing old snapshot file "/home/phablet/.config/axolotl.nanuc/sled/snap.000000000004636F"
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287640
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287640, recovered lsn 287640
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287640 next_lid: 287640
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::meta] allocated pid 8 for root of new_tree [115, 116, 97, 116, 101]
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287640 to 287674
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG presage_store_sled] migrating from v0, nothing to do
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287674 to 287735
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] wrote lsns 287640-287673 to disk at offsets 287640-287673, maxed false complete_len 34
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] mark_interval(287640, 34)
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] wrote lsns 287674-287734 to disk at offsets 287674-287734, maxed false complete_len 61
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] mark_interval(287674, 61)
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] new highest interval: 287640 - 287673
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] new highest interval: 287674 - 287734
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] make_stable(287734) returning
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG presage_store_sled] migrating from schema v1 to v2: encrypting state if cipher is enabled
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287735 to 287764
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] wrote lsns 287735-287763 to disk at offsets 287735-287763, maxed false complete_len 29
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] mark_interval(287735, 29)
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] new highest interval: 287735 - 287763
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] make_stable(287763) returning
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG presage_store_sled] migrating from schema v2 to v3: dropping encrypted group cache
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287764 to 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] wrote lsns 287764-287792 to disk at offsets 287764-287792, maxed false complete_len 29
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] mark_interval(287764, 29)
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] new highest interval: 287764 - 287792
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287792
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] hit max_lsn 287792 in iterator, stopping
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287793 and 524287
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::snapshot] removing old snapshot file "/home/phablet/.config/axolotl.nanuc/sled/snap.0000000000046398"
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287793, recovered lsn 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287793 next_lid: 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:45] [2024-11-01T17:45:09Z INFO  axolotl::handlers] Not registered, lets start the registration
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Error checking registration: Registration failed.: Not yet registered
[01.11.24 18:45]     
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Registering secondary device
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Creating provisioning link
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287792
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] hit max_lsn 287792 in iterator, stopping
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287793 and 524287
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287793, recovered lsn 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287793 next_lid: 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::logger] IoBufs dropped
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] filtering out segments after detected tear at (lsn, lid) 287792
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iterator] hit max_lsn 287792 in iterator, stopping
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::snapshot] zeroing the end of the recovered segment at lsn 0 between lids 287793 and 524287
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::blob_io] gc_blobs removing any blob with an lsn above 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::segment] SA starting with tip 524288 stable -1 free {}
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting log at recovered active offset 287793, recovered lsn 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] starting IoBufs with next_lsn: 287793 next_lid: 287793
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache] load_snapshot loading pages from 0..10
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Creating runtime
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] provision linking: runtime created
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Provisioning link created successfully
[01.11.24 18:45] [2024-11-01T17:45:09Z INFO  axolotl::handlers] Awaiting for provisioning link
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Spawning manager thread
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::manager_thread] manager thread started, get contact
[01.11.24 18:45] [2024-11-01T17:45:09Z INFO  axolotl::manager_thread] Loading the configuration store
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::manager_thread] The config store reports not registered, registering the manage
[01.11.24 18:45] [2024-11-01T17:45:09Z INFO  axolotl::manager_thread] The config store is not valid yet, not registered yet
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] advancing offset within the current segment from 287793 to 287834
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] wrote lsns 287793-287833 to disk at offsets 287793-287833, maxed false complete_len 41
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] mark_interval(287793, 41)
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] new highest interval: 287793 - 287833
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG sled::pagecache::iobuf] make_stable(287833) returning
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::webpki::anchors] add_parsable_certificates processed 1 valid and 0 invalid certs
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG tracing::span] websocket;
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::webpki::anchors] add_parsable_certificates processed 1 valid and 0 invalid certs
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::client::hs] No cached session for DnsName("chat.signal.org")
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::client::hs] Not resuming any session
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::client::hs] Using ciphersuite TLS13_AES_256_GCM_SHA384
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::client::tls13] Not resuming
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::client::tls13] TLS1.3 encrypted extensions: [ServerNameAck, Unknown(UnknownExtension { typ: EllipticCurves, payload: 00xxxxxxxxxxxxxxxxxxxxx4 })]
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG rustls::client::hs] ALPN protocol is None
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG tungstenite::handshake::client] Client handshake done.
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG libsignal_service_hyper::websocket] WebSocket connected: Response { status: 101, version: HTTP/1.1, headers: {"date": "Fri, 01 Nov 2024 17:45:09 GMT", "x-signal-timestamp": "1730483109567", "vary": "Origin", "upgrade": "websocket", "connection": "Upgrade", "sec-websocket-accept": "EV67EowurPTeDkW68FZZwBM3DWo="}, body: None }
[01.11.24 18:45] [2024-11-01T17:45:09Z INFO  presage::manager::linking] generating qrcode from provisioning link: sgnl://linkdevice?uuid=wpXXXXXXXXXXXXXXXXXXXX%3D%3D&pub_key=Bfq%2BUyy9eOsj9u1fVzGuDlE3XXXXXXXXXXXXXXXXXXXX
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Got provisioning link: Url { scheme: "sgnl", cannot_be_a_base: false, username: "", password: None, host: Some(Domain("linkdevice")), port: None, path: "", query: Some("uuid=wpBAXXXXXXXXXXXXXXXXXD%3D&pub_key=Bfq%2BUyy9eOsj9uXXXXXXXXXXXXXXXXXXXXXXX05Ff"), fragment: None }
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Provisioning link handled successfully
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Sending provisioning link
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG axolotl::handlers] Provisioning link sent successfully to client
[01.11.24 18:45] [2024-11-01T17:45:09Z DEBUG libsignal_service::websocket] Sending keep alive upon request
[01.11.24 18:45] QObject::killTimer: Timers cannot be stopped from another thread
[01.11.24 18:45] QObject::startTimer: Timers cannot be started from another thread