Tor randomly fails to bootstrap when running in VirtualBox Linux guest
My setup:
- VirtualBox 6.1.12 r139181 (Qt5.6.1)
- host system: Slackware64-14.2 with kernel 4.4.227
- guest system: same
- Tor: first 0.4.3.5, some time ago upgraded to 0.4.3.6, from http://slackbuilds.org/repository/14.2/network/tor/, the problem randomly happens with both versions
Tor on host always works fine.
Tor on guest randomly fails to bootstrap. Even with default torrc with no additional settings (except enabling verbose logging so I could attach it below) changed. Randomly as it did not work for a couple of days, then it suddenly worked again, now it doesn't work again, hence me opening the issue.
A hack to make Tor work in the VM was to copy the content of /var/lib/tor
from host (where it always worked) to guest, and restart the Tor daemon.
Relevant logs:
tor.log:
[notice] Tor 0.4.3.6 opening new log file.
[notice] Tor 0.4.3.6 running on Linux with Libevent 2.0.22-stable, OpenSSL 1.0.2u, Zlib 1.2.11, Liblzma 5.2.2, and Libzstd N/A.
[notice] Tor can't help you if you use it wrong! Learn how to be safe at https://www.torproject.org/download/download#warning
[notice] Read configuration file "/etc/tor/torrc".
[notice] Opening Socks listener on 127.0.0.1:9050
[notice] Opened Socks listener on 127.0.0.1:9050
[warn] Your log may contain sensitive information - you're logging more than "notice". Don't log unless it serves an important reason. Overwrite the log afterwards.
[notice] Parsing GEOIP IPv4 file /usr/share/tor/geoip.
[notice] Parsing GEOIP IPv6 file /usr/share/tor/geoip6.
[notice] Bootstrapped 0% (starting): Starting
[notice] Starting with guard context "default"
debug.log:
[notice] Tor 0.4.3.6 opening new log file.
[notice] Tor 0.4.3.6 running on Linux with Libevent 2.0.22-stable, OpenSSL 1.0.2u, Zlib 1.2.11, Liblzma 5.2.2, and Libzstd N/A.
[notice] Tor can't help you if you use it wrong! Learn how to be safe at https://www.torproject.org/download/download#warning
[notice] Read configuration file "/etc/tor/torrc".
[notice] Opening Socks listener on 127.0.0.1:9050
[notice] Opened Socks listener on 127.0.0.1:9050
[warn] Your log may contain sensitive information - you're logging more than "notice". Don't log unless it serves an important reason. Overwrite the log afterwards.
[info] options_commit_listener_transaction(): Recomputed OOS thresholds: ConnLimit 1000, ConnLimit_ 32736, ConnLimit_high_thresh 32672, ConnLimit_low_thresh 24552
[info] crypto_openssl_late_init(): NOT using OpenSSL engine support.
[info] evaluate_evp_for_aes(): This version of OpenSSL has a known-good EVP counter-mode implementation. Using it.
[debug] tor_disable_debugger_attach(): Attemping to disable debugger attachment to Tor for unprivileged users.
[info] tor_lockfile_lock(): Locking "/var/lib/tor/lock"
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 128.31.0.39:9131 (9695)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 86.59.21.38:80 (847B)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 45.66.33.45:80 (7EA6)
[debug] parse_dir_authority_line(): Trusted 16 dirserver at 66.111.2.131:9030 (BA44)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 131.188.40.189:80 (F204)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 193.23.244.244:80 (7BE6)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 171.25.193.9:443 (BD6A)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 154.35.175.225:80 (CF6D)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 199.58.81.140:80 (74A9)
[debug] parse_dir_authority_line(): Trusted 100 dirserver at 204.13.164.118:80 (24E2)
[debug] file_status(): stat()ing /var/lib/tor/state
[debug] subsystems_register_state_formats(): Added state format for mainloop with index 0
[info] or_state_load(): Loaded state from "/var/lib/tor/state"
[debug] get_guard_selection_by_name(): Creating a guard selection called default
[info] sampled_guards_update_from_consensus(): Not updating the sample guard set; we have no reasonably live consensus.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Nisos ($7FA3FA525901A91A1310B817943668BC2C16289E): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard nicknametor ($AD1FC4AE877B2FFEB3C84F1A6B76E9B057AAEF4E): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard rofltor07 ($15BE17C99FACE24470D40AF782D6A9C692AB36D6): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard flo ($F8DE8132E599A194E20DDB738AF64A7200CD5949): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Srv3MuchRelay ($614094CAF0701EB568106E60EC12A785E704AE0F): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard kramse3 ($F94A7BAC5D1E3D9372437141E10695A625B8B533): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard courage ($B1080EA61CAF5763139E7B596F37FE9830311C8F): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($E33CB42667DC1FB88F7F02469762D71886DC8DB4): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard BraveTeotihuacan ($723E21FD6F91905E84E583D4619A2D1E282B8180): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard w1d3p33p0 ($963879BB424D9A637A63105D5C26A2453C2C2272): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($B2D03CDF45EB4D74A65FAC4F00EBA785C9663A5F): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard torvic ($30C0B4DE58CFBB350485B326A0D67352E0138CCF): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard butts ($6974910EF995EF8B45D46285FD15FCF84347BB84): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard CCrelay1337 ($131FBC8A3D4B44DE9B258ADC58674082B1E06D2C): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard FalkensteinTor02 ($F5C3DA7642BB037E0D279359AE88CD7FC03A98A0): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($1F4105C688E835A56AF3D66C787677B57240FFA2): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard sandbox ($EE94030D02AF5A788C62EA56D483540C38460E10): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard iriseden ($005079A42356183CEA5A3ADD239303F44F12E7EA): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard 40e62fcce23032 ($3A51E32A303A0E0A3AB8AA1CCBE98FF8791E2341): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($C63110BDD8736D2C2A733FF962F58D58FDE63A2D): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard CheenaTorRelay1 ($8AFFEAE2A917D35C4E98395F5F3BDC3A55472632): filtered=0; reachable_filtered=0.
[info] sample_reachable_filtered_entry_guards(): Trying to sample a reachable guard: We know of 0 in the USABLE_FILTERED set.
[info] sample_reachable_filtered_entry_guards(): (That isn't enough. Trying to expand the sample.)
[info] entry_guards_expand_sample(): Not expanding the sample guard set; we have no reasonably live consensus.
[info] sample_reachable_filtered_entry_guards(): (After filters [b], we have 0 guards to consider.)
[info] circuit_build_times_parse_state(): Adding 0 timeouts.
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 1125
[debug] circuit_build_times_add_time(): Adding circuit build time 9725
[debug] circuit_build_times_add_time(): Adding circuit build time 525
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 225
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 675
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 775
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 2125
[debug] circuit_build_times_add_time(): Adding circuit build time 775
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 675
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 625
[debug] circuit_build_times_add_time(): Adding circuit build time 1375
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 3525
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 775
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 675
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 875
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 525
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 625
[debug] circuit_build_times_add_time(): Adding circuit build time 1025
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 525
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 775
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 675
[debug] circuit_build_times_add_time(): Adding circuit build time 775
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 525
[debug] circuit_build_times_add_time(): Adding circuit build time 725
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 825
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 525
[debug] circuit_build_times_add_time(): Adding circuit build time 775
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 725
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 525
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 1675
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 225
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 575
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 275
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 375
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[debug] circuit_build_times_add_time(): Adding circuit build time 425
[debug] circuit_build_times_add_time(): Adding circuit build time 325
[debug] circuit_build_times_add_time(): Adding circuit build time 475
[info] circuit_build_times_parse_state(): Loaded 136/136 values from 21 lines in circuit time histogram
[info] circuit_build_times_get_xm(): Xm mode #0: 375 27
[info] circuit_build_times_set_timeout(): Set buildtimeout to low value 557.411891ms. Setting to 1500ms
[info] circuit_build_times_set_timeout(): Based on 136 circuit times, it looks like we don't need to wait so long for circuits to finish. We will now assume a circuit is too slow to use after waiting 2 seconds.
[info] circuit_build_times_set_timeout(): Circuit timeout data: 1500.000000ms, 60000.000000ms, Xm: 375, a: 4.060357, r: 0.029412
[info] circuit_build_times_filter_timeouts(): We had 4 timeouts out of 136 build times, and filtered 0 above the max of 60000
[debug] kist_scheduler_run_interval(): KISTSchedRunInterval=0, turning to the consensus.
[debug] scheduler_can_use_kist(): Determined KIST sched_run_interval should be 10. Can use KIST.
[info] scheduler_kist_set_full_mode(): Setting KIST scheduler with kernel support (KIST mode)
[debug] kist_scheduler_run_interval(): KISTSchedRunInterval=0, turning to the consensus.
[info] cmux_ewma_set_options(): Enabled cell_ewma algorithm because of value in CircuitPriorityHalflifeMsec in consensus; scale factor is 0.793701 per 10 seconds
[notice] Parsing GEOIP IPv4 file /usr/share/tor/geoip.
[notice] Parsing GEOIP IPv6 file /usr/share/tor/geoip6.
[info] circpad_machine_client_hide_intro_circuits(): Registered client intro point hiding padding machine (0)
[info] circpad_machine_relay_hide_intro_circuits(): Registered relay intro circuit hiding padding machine (0)
[info] circpad_machine_client_hide_rend_circuits(): Registered client rendezvous circuit hiding padding machine (1)
[info] circpad_machine_relay_hide_rend_circuits(): Registered relay rendezvous circuit hiding padding machine (1)
[info] add_predicted_port(): New port prediction added. Will continue predictive circ building for 1869 more seconds.
[debug] file_status(): stat()ing /var/lib/tor/unparseable-descs
[notice] Bootstrapped 0% (starting): Starting
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for moria1
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority moria1 with signing key ACE88AC828C1641CD528F8681F570FB53A65807E
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for maatuska
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority maatuska with signing key 80563A5D95D7CE8842A6755CA288983178AF16C5
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for tor26
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority tor26 with signing key 4ABD14E600065842C3A3D1079BD39CDB1969A51F
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for bastet
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority bastet with signing key D4256F8F3059FF48585BF9D636808B43D24349C1
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for dizum
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority dizum with signing key 472B3A0BCDC5DBF1EB54A60FA32A2478D92C5D4E
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for gabelmoo
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority gabelmoo with signing key C4F720E2C59F9DDD4867FFF465CA04031E35648F
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for longclaw
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority longclaw with signing key 509EF6508DD58C083D55BECAC7C1B6B8BED4D358
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for Faravahar
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority Faravahar with signing key 757F4244FF0E65AEBF55FFA36E7D2AEBB81A005D
[debug] trusted_dirs_load_certs_from_string(): Parsed certificate for dannenberg
[info] trusted_dirs_load_certs_from_string(): Adding cached certificate for directory authority dannenberg with signing key 0AF48E6865839B2529BFB19DB8F97AFB3AAD2FFD
[info] tor_mmap_file(): Could not open "/var/lib/tor/cached-consensus" for mmap(): No such file or directory
[info] tor_mmap_file(): Could not open "/var/lib/tor/unverified-consensus" for mmap(): No such file or directory
[info] A consensus needs 5 good signatures from recognized authorities for us to accept it. This microdesc one has 9 (dannenberg tor26 longclaw bastet maatuska moria1 dizum gabelmoo Faravahar).
[debug] kist_scheduler_run_interval(): KISTSchedRunInterval=0, turning to the consensus.
[debug] scheduler_can_use_kist(): Determined KIST sched_run_interval should be 10. Can use KIST.
[info] scheduler_kist_set_full_mode(): Setting KIST scheduler with kernel support (KIST mode)
[debug] kist_scheduler_run_interval(): KISTSchedRunInterval=0, turning to the consensus.
[info] Choosing expected valid-after time as 2020-09-15 00:00:00: consensus_set=0, interval=1800
[info] microdesc_cache_reload(): Reloaded microdescriptor cache. Found 7789 descriptors.
[info] node_set_hsdir_index(): Not setting hsdir index with a non-live consensus.
[info] update_consensus_networkstatus_fetch_time_impl(): No live microdesc consensus; we should fetch one immediately.
[info] cmux_ewma_set_options(): Enabled cell_ewma algorithm because of value in CircuitPriorityHalflifeMsec in consensus; scale factor is 0.793701 per 10 seconds
[info] tor_mmap_file(): Could not open "/var/lib/tor/unverified-microdesc-consensus" for mmap(): No such file or directory
[info] tor_mmap_file(): Could not open "/var/lib/tor/cached-descriptors" for mmap(): No such file or directory
[debug] file_status(): stat()ing /var/lib/tor/cached-descriptors.new
[info] routerlist_remove_old_routers(): We have 0 live routers and 0 old router descriptors.
[info] tor_mmap_file(): Could not open "/var/lib/tor/cached-extrainfo" for mmap(): No such file or directory
[debug] file_status(): stat()ing /var/lib/tor/cached-extrainfo.new
[notice] Starting with guard context "default"
[info] sampled_guards_update_from_consensus(): Not updating the sample guard set; we have no reasonably live consensus.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Nisos ($7FA3FA525901A91A1310B817943668BC2C16289E): filtered=0; reachable_filtered=0.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard nicknametor ($AD1FC4AE877B2FFEB3C84F1A6B76E9B057AAEF4E): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard rofltor07 ($15BE17C99FACE24470D40AF782D6A9C692AB36D6): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard flo ($F8DE8132E599A194E20DDB738AF64A7200CD5949): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Srv3MuchRelay ($614094CAF0701EB568106E60EC12A785E704AE0F): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard kramse3 ($F94A7BAC5D1E3D9372437141E10695A625B8B533): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard courage ($B1080EA61CAF5763139E7B596F37FE9830311C8F): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($E33CB42667DC1FB88F7F02469762D71886DC8DB4): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard BraveTeotihuacan ($723E21FD6F91905E84E583D4619A2D1E282B8180): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard w1d3p33p0 ($963879BB424D9A637A63105D5C26A2453C2C2272): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($B2D03CDF45EB4D74A65FAC4F00EBA785C9663A5F): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard torvic ($30C0B4DE58CFBB350485B326A0D67352E0138CCF): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard butts ($6974910EF995EF8B45D46285FD15FCF84347BB84): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard CCrelay1337 ($131FBC8A3D4B44DE9B258ADC58674082B1E06D2C): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard FalkensteinTor02 ($F5C3DA7642BB037E0D279359AE88CD7FC03A98A0): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($1F4105C688E835A56AF3D66C787677B57240FFA2): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard sandbox ($EE94030D02AF5A788C62EA56D483540C38460E10): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard iriseden ($005079A42356183CEA5A3ADD239303F44F12E7EA): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard 40e62fcce23032 ($3A51E32A303A0E0A3AB8AA1CCBE98FF8791E2341): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard Unnamed ($C63110BDD8736D2C2A733FF962F58D58FDE63A2D): filtered=1; reachable_filtered=1.
[debug] entry_guard_set_filtered_flags(): Updated sampled guard CheenaTorRelay1 ($8AFFEAE2A917D35C4E98395F5F3BDC3A55472632): filtered=1; reachable_filtered=1.
[info] sample_reachable_filtered_entry_guards(): Trying to sample a reachable guard: We know of 20 in the USABLE_FILTERED set.
[info] sample_reachable_filtered_entry_guards(): (After filters [b], we have 18 guards to consider.)
[info] sample_reachable_filtered_entry_guards(): (Selected CCrelay1337 ($131FBC8A3D4B44DE9B258ADC58674082B1E06D2C).)
[info] entry_guards_update_primary(): Primary entry guards have changed. New primary guard list is:
[info] entry_guards_update_primary(): 1/3: Srv3MuchRelay ($614094CAF0701EB568106E60EC12A785E704AE0F) (confirmed)
[info] entry_guards_update_primary(): 2/3: rofltor07 ($15BE17C99FACE24470D40AF782D6A9C692AB36D6) (confirmed)
[info] entry_guards_update_primary(): 3/3: CCrelay1337 ($131FBC8A3D4B44DE9B258ADC58674082B1E06D2C)
[info] I learned some more directory information, but not enough to build a circuit: We have no recent usable consensus.
[debug] periodic_events_rescan_by_roles(): Launching periodic event add_entropy
[debug] periodic_events_rescan_by_roles(): Launching periodic event heartbeat
[debug] periodic_events_rescan_by_roles(): Launching periodic event reset_padding_counts
[debug] periodic_events_rescan_by_roles(): Disabling periodic event second_elapsed
[debug] periodic_events_rescan_by_roles(): Disabling periodic event retry_listeners
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_expired_networkstatus
[debug] periodic_events_rescan_by_roles(): Disabling periodic event fetch_networkstatus
[debug] periodic_events_rescan_by_roles(): Disabling periodic event launch_descriptor_fetches
[debug] periodic_events_rescan_by_roles(): Disabling periodic event rotate_x509_certificate
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_network_participation
[debug] periodic_events_rescan_by_roles(): Disabling periodic event clean_caches
[debug] periodic_events_rescan_by_roles(): Disabling periodic event save_state
[debug] periodic_events_rescan_by_roles(): Disabling periodic event write_stats_file
[debug] periodic_events_rescan_by_roles(): Disabling periodic event prune_old_routers
[debug] periodic_events_rescan_by_roles(): Disabling periodic event hs_service
[debug] periodic_events_rescan_by_roles(): Disabling periodic event record_bridge_stats
[debug] periodic_events_rescan_by_roles(): Disabling periodic event rend_cache_failure_clean
[debug] periodic_events_rescan_by_roles(): Disabling periodic event clean_consdiffmgr
[debug] periodic_events_rescan_by_roles(): Disabling periodic event control_per_second_events
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_canonical_channels
[debug] periodic_events_rescan_by_roles(): Disabling periodic event expire_old_circuits_serverside
[debug] periodic_events_rescan_by_roles(): Disabling periodic event retry_dns
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_dns_honesty
[debug] periodic_events_rescan_by_roles(): Disabling periodic event rotate_onion_key
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_descriptor
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_for_reachability_bw
[debug] periodic_events_rescan_by_roles(): Disabling periodic event reachability_warnings
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_ed_keys
[debug] periodic_events_rescan_by_roles(): Disabling periodic event check_onion_keys_expiry_time