[tor-bugs] #29042 [Core Tor/Tor]: Error loading private key after 0.3.5.7 upgrade

Mon Jan 14 13:56:36 UTC 2019

#29042: Error loading private key after 0.3.5.7 upgrade
--------------------------+------------------------------------
 Reporter:  anong         |          Owner:  (none)
     Type:  defect        |         Status:  new
 Priority:  Very High     |      Milestone:  Tor: 0.3.5.x-final
Component:  Core Tor/Tor  |        Version:  Tor: 0.3.5.7
 Severity:  Critical      |     Resolution:
 Keywords:  tor-relay     |  Actual Points:
Parent ID:                |         Points:
 Reviewer:                |        Sponsor:
--------------------------+------------------------------------

Comment (by anong):

 Debug log:

 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_add_time(): Adding
 circuit build time 4725
 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_add_time(): Adding
 circuit build time 2147483646
 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_add_time(): Adding
 circuit build time 375
 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_parse_state():
 Loaded 273/273 values from 74 lines in circuit time histogram
 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_get_xm(): Xm mode
 #0: 525 19
 Jan 14 14:52:43 DietPi Tor[1561]:
 circuit_build_times_set_timeout_worker(): Circuit build measurement period
 of 38477ms is more than twice the maximum build time we have ever
 observed. Capping it to 34650ms.
 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_set_timeout(): Based
 on 273 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 5 seconds
 Jan 14 14:52:43 DietPi Tor[1561]: circuit_build_times_set_timeout():
 Circuit timeout data: 5274.074997ms, 60000.000000ms, Xm: 525, a: 0.697584,
 r: 0.271062
 Jan 14 14:52:43 DietPi Tor[1561]: read_file_to_str(): Could not open
 "/var/lib/tor/router-stability": No such file or directory
 Jan 14 14:52:43 DietPi Tor[1561]: check_private_dir(): stat()ing
 /var/lib/tor/diff-cache
 Jan 14 14:53:13 DietPi Tor[1561]: kist_scheduler_run_interval():
 KISTSchedRunInterval=0, turning to the consensus.
 Jan 14 14:53:13 DietPi Tor[1561]: scheduler_can_use_kist(): Determined
 KIST sched_run_interval should be 10. Can use KIST.
 Jan 14 14:53:13 DietPi Tor[1561]: scheduler_kist_set_full_mode(): Setting
 KIST scheduler with kernel support (KIST mode)
 Jan 14 14:53:13 DietPi Tor[1561]: kist_scheduler_run_interval():
 KISTSchedRunInterval=0, turning to the consensus.
 Jan 14 14:53:13 DietPi Tor[1561]: cmux_ewma_set_options(): Enabled
 cell_ewma algorithm because of value in CircuitPriorityHalflifeMsec in
 consensus; scale factor is 0.793701 per 10 seconds
 Jan 14 14:53:13 DietPi Tor[1561]: Parsing GEOIP IPv4 file
 /usr/share/tor/geoip.
 Jan 14 14:53:14 DietPi Tor[1561]: Parsing GEOIP IPv6 file
 /usr/share/tor/geoip6.
 Jan 14 14:53:15 DietPi Tor[1561]: options_act(): Configured to measure
 hidden service statistics.
 Jan 14 14:53:15 DietPi Tor[1561]: Configured to measure statistics. Look
 for the *-stats files that will first be written to the data directory in
 24 hours from now.
 Jan 14 14:53:15 DietPi Tor[1561]: mark_my_descriptor_dirty(): Decided to
 publish new relay descriptor: config change
 Jan 14 14:53:15 DietPi Tor[1561]: add_predicted_port(): New port
 prediction added. Will continue predictive circ building for 2909 more
 seconds.
 Jan 14 14:53:15 DietPi Tor[1561]: crypto_openssl_late_init(): NOT using
 OpenSSL engine support.
 Jan 14 14:53:15 DietPi Tor[1561]: evaluate_evp_for_aes(): This version of
 OpenSSL has a known-good EVP counter-mode implementation. Using it.
 Jan 14 14:53:15 DietPi Tor[1561]: file_status(): stat()ing /var/lib/tor
 /unparseable-descs
 Jan 14 14:53:15 DietPi Tor[1561]: check_private_dir(): stat()ing
 /var/lib/tor
 Jan 14 14:53:15 DietPi Tor[1561]: tor_getpwnam(): Caching new entry tor
 for tor
 Jan 14 14:53:15 DietPi Tor[1561]: check_private_dir(): stat()ing
 /var/lib/tor/keys
 Jan 14 14:53:15 DietPi Tor[1561]: tor_getpwnam(): Caching new entry tor
 for tor
 Jan 14 14:53:15 DietPi Tor[1561]: init_keys(): Reading/making identity key
 "/var/lib/tor/keys/secret_id_key"...
 Jan 14 14:53:15 DietPi Tor[1561]: file_status(): stat()ing
 /var/lib/tor/keys/secret_id_key
 Jan 14 14:53:15 DietPi Tor[1561]: Error loading private key.
 Jan 14 14:53:15 DietPi Tor[1561]: Error initializing keys; exiting
 Jan 14 14:53:15 DietPi Tor[1561]: tor_rename(): Renaming
 /var/lib/tor/state.tmp to /var/lib/tor/state
 Jan 14 14:53:15 DietPi Tor[1561]: or_state_save(): Saved state to
 "/var/lib/tor/state"
 Jan 14 14:53:15 DietPi Tor[1561]: channel_tls_free_all(): Shutting down
 TLS channels...
 Jan 14 14:53:15 DietPi Tor[1561]: channel_tls_free_all(): Done shutting
 down TLS channels
 Jan 14 14:53:15 DietPi Tor[1561]: channel_free_all(): Shutting down
 channels...
 Jan 14 14:53:15 DietPi Tor[1561]: channel_free_all(): Freeing
 channel_identity_map
 Jan 14 14:53:15 DietPi Tor[1561]: channel_free_all(): Freeing
 channel_gid_map
 Jan 14 14:53:15 DietPi Tor[1561]: channel_free_all(): Done cleaning up
 after channels
 Jan 14 14:53:15 DietPi Tor[1561]: connection_free_minimal(): closing fd 6.
 Jan 14 14:53:15 DietPi Tor[1561]: connection_free_minimal(): closing fd 7.
 Jan 14 14:53:15 DietPi Tor[1561]: connection_free_minimal(): closing fd 8.
 Jan 14 14:53:15 DietPi Tor[1561]: connection_free_minimal(): closing fd 9.
 Jan 14 14:53:15 DietPi Tor[1561]: connection_free_minimal(): closing fd
 10.
 Jan 14 14:53:15 DietPi Tor[1561]: scheduler_free_all(): Shutting down
 scheduler
 Jan 14 14:53:15 DietPi Tor[1561]: tor_lockfile_unlock(): Unlocking
 "/var/lib/tor/lock"

--
Ticket URL: <https://trac.torproject.org/projects/tor/ticket/29042#comment:5>
Tor Bug Tracker & Wiki <https://trac.torproject.org/>
The Tor Project: anonymity online