[tor-relays] duplicated log output after SIGHUP

[Scott Bennett]

     I made a minor change to my torrc file and sent tor a SIGHUP.  The only
logging I'm doing is "notice" level to a file.  Here's what has been going on
since the SIGHUP.

Feb 06 22:32:01.201 [notice] Heartbeat: Tor's uptime is 10 days 3:00 hours, with 206 circuits open. I've sent 8.22 GB and received 7.72 GB.
Feb 06 22:32:01.201 [notice] Average packaged cell fullness: 77.286%
Feb 06 22:32:01.201 [notice] TLS write overhead: 12%
Feb 06 23:10:33.967 [notice] Received reload signal (hup). Reloading config and resetting internal state.
Feb 06 23:10:33.967 [notice] Read configuration file "/usr/local/etc/tor/torrc".
Feb 06 23:10:33.976 [warn] Skipping obsolete configuration option 'MaxOnionsPending'
Feb 06 23:10:33.978 [notice] Tor 0.2.4.20 (git-3cb5c70beec5bf46) opening log file.
Feb 06 23:10:33.978 [notice] Tor 0.2.4.20 (git-3cb5c70beec5bf46) opening log file.
Feb 06 23:10:34.293 [notice] Your Tor server's identity key fingerprint is 'MYCROFTsOtherChild 023220505A550D6FDF0C20FF7C48E66BA06A49A6'
Feb 06 23:10:34.293 [notice] Your Tor server's identity key fingerprint is 'MYCROFTsOtherChild 023220505A550D6FDF0C20FF7C48E66BA06A49A6'
Feb 06 23:31:59.200 [notice] Circuit handshake stats since last time: 2129/2129 TAP, 364/364 NTor.
Feb 06 23:31:59.200 [notice] Circuit handshake stats since last time: 2129/2129 TAP, 364/364 NTor.
Feb 06 23:32:01.222 [notice] Heartbeat: Tor's uptime is 10 days 4:00 hours, with 296 circuits open. I've sent 8.55 GB and received 8.05 GB.
Feb 06 23:32:01.222 [notice] Heartbeat: Tor's uptime is 10 days 4:00 hours, with 296 circuits open. I've sent 8.55 GB and received 8.05 GB.
Feb 06 23:32:01.222 [notice] Average packaged cell fullness: 77.276%
Feb 06 23:32:01.222 [notice] Average packaged cell fullness: 77.276%
Feb 06 23:32:01.222 [notice] TLS write overhead: 11%
Feb 06 23:32:01.222 [notice] TLS write overhead: 11%
Feb 07 00:31:59.201 [notice] Circuit handshake stats since last time: 2781/2781 TAP, 348/348 NTor.
Feb 07 00:31:59.201 [notice] Circuit handshake stats since last time: 2781/2781 TAP, 348/348 NTor.
Feb 07 00:32:01.201 [notice] Heartbeat: Tor's uptime is 10 days 5:00 hours, with 384 circuits open. I've sent 8.62 GB and received 8.11 GB.
Feb 07 00:32:01.201 [notice] Heartbeat: Tor's uptime is 10 days 5:00 hours, with 384 circuits open. I've sent 8.62 GB and received 8.11 GB.
Feb 07 00:32:01.201 [notice] Average packaged cell fullness: 77.264%
Feb 07 00:32:01.201 [notice] Average packaged cell fullness: 77.264%
Feb 07 00:32:01.201 [notice] TLS write overhead: 11%
Feb 07 00:32:01.201 [notice] TLS write overhead: 11%
Feb 07 01:31:59.219 [notice] Circuit handshake stats since last time: 3469/3469 TAP, 278/278 NTor.
Feb 07 01:31:59.219 [notice] Circuit handshake stats since last time: 3469/3469 TAP, 278/278 NTor.
Feb 07 01:32:01.202 [notice] Heartbeat: Tor's uptime is 10 days 6:00 hours, with 559 circuits open. I've sent 8.63 GB and received 8.13 GB.
Feb 07 01:32:01.202 [notice] Heartbeat: Tor's uptime is 10 days 6:00 hours, with 559 circuits open. I've sent 8.63 GB and received 8.13 GB.
Feb 07 01:32:01.202 [notice] Average packaged cell fullness: 77.151%
Feb 07 01:32:01.202 [notice] Average packaged cell fullness: 77.151%
Feb 07 01:32:01.202 [notice] TLS write overhead: 11%
Feb 07 01:32:01.202 [notice] TLS write overhead: 11%
Feb 07 02:31:59.200 [notice] Circuit handshake stats since last time: 4090/4090 TAP, 345/345 NTor.
Feb 07 02:31:59.200 [notice] Circuit handshake stats since last time: 4090/4090 TAP, 345/345 NTor.
Feb 07 02:32:01.201 [notice] Heartbeat: Tor's uptime is 10 days 7:00 hours, with 540 circuits open. I've sent 8.82 GB and received 8.31 GB.
Feb 07 02:32:01.201 [notice] Heartbeat: Tor's uptime is 10 days 7:00 hours, with 540 circuits open. I've sent 8.82 GB and received 8.31 GB.
Feb 07 02:32:01.201 [notice] Average packaged cell fullness: 77.116%
Feb 07 02:32:01.201 [notice] Average packaged cell fullness: 77.116%
Feb 07 02:32:01.201 [notice] TLS write overhead: 11%
Feb 07 02:32:01.201 [notice] TLS write overhead: 11%

And so on.  Is the duplication of messages after a reload a known bug?  
Please note that the double logging I reported earlier has been corrected by
commenting out the --Log stuff in /usr/local/etc/rc.d/tor, so the new double
logging appears to be a different problem.


                                  Scott Bennett, Comm. ASMELG, CFIAG
**********************************************************************
* Internet:   bennett at sdf.org   *or*   bennett at freeshell.org   *
*--------------------------------------------------------------------*
* "A well regulated and disciplined militia, is at all times a good  *
* objection to the introduction of that bane of all free governments *
* -- a standing army."                                               *
*    -- Gov. John Hancock, New York Journal, 28 January 1790         *
**********************************************************************