hibernate.c 33 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560561562563564565566567568569570571572573574575576577578579580581582583584585586587588589590591592593594595596597598599600601602603604605606607608609610611612613614615616617618619620621622623624625626627628629630631632633634635636637638639640641642643644645646647648649650651652653654655656657658659660661662663664665666667668669670671672673674675676677678679680681682683684685686687688689690691692693694695696697698699700701702703704705706707708709710711712713714715716717718719720721722723724725726727728729730731732733734735736737738739740741742743744745746747748749750751752753754755756757758759760761762763764765766767768769770771772773774775776777778779780781782783784785786787788789790791792793794795796797798799800801802803804805806807808809810811812813814815816817818819820821822823824825826827828829830831832833834835836837838839840841842843844845846847848849850851852853854855856857858859860861862863864865866867868869870871872873874875876877878879880881882883884885886887888889890891892893894895896897898899900901902903904905906907908909910911912913914915916917918919920921922923924925926927928929930931932933934935936937938939940941942943944945946947948949950951952953954955956957958959960961962963964965966967968969970971972973974975976977978979980981982983984985986
  1. /* Copyright (c) 2004-2006, Roger Dingledine, Nick Mathewson.
  2. * Copyright (c) 2007-2010, The Tor Project, Inc. */
  3. /* See LICENSE for licensing information */
  4. /**
  5. * \file hibernate.c
  6. * \brief Functions to close listeners, stop allowing new circuits,
  7. * etc in preparation for closing down or going dormant; and to track
  8. * bandwidth and time intervals to know when to hibernate and when to
  9. * stop hibernating.
  10. **/
  11. /*
  12. hibernating, phase 1:
  13. - send destroy in response to create cells
  14. - send end (policy failed) in response to begin cells
  15. - close an OR conn when it has no circuits
  16. hibernating, phase 2:
  17. (entered when bandwidth hard limit reached)
  18. - close all OR/AP/exit conns)
  19. */
  20. #include "or.h"
  21. #include "config.h"
  22. #include "connection.h"
  23. #include "connection_edge.h"
  24. #include "hibernate.h"
  25. #include "main.h"
  26. #include "router.h"
  27. /** Possible values of hibernate_state */
  28. typedef enum {
  29. /** We are running normally. */
  30. HIBERNATE_STATE_LIVE=1,
  31. /** We're trying to shut down cleanly, and we'll kill all active connections
  32. * at shutdown_time. */
  33. HIBERNATE_STATE_EXITING=2,
  34. /** We're running low on allocated bandwidth for this period, so we won't
  35. * accept any new connections. */
  36. HIBERNATE_STATE_LOWBANDWIDTH=3,
  37. /** We are hibernating, and we won't wake up till there's more bandwidth to
  38. * use. */
  39. HIBERNATE_STATE_DORMANT=4
  40. } hibernate_state_t;
  41. extern long stats_n_seconds_working; /* published uptime */
  42. /** Are we currently awake, asleep, running out of bandwidth, or shutting
  43. * down? */
  44. static hibernate_state_t hibernate_state = HIBERNATE_STATE_LIVE;
  45. /** If are hibernating, when do we plan to wake up? Set to 0 if we
  46. * aren't hibernating. */
  47. static time_t hibernate_end_time = 0;
  48. /** If we are shutting down, when do we plan finally exit? Set to 0 if
  49. * we aren't shutting down. */
  50. static time_t shutdown_time = 0;
  51. /** Possible accounting periods. */
  52. typedef enum {
  53. UNIT_MONTH=1, UNIT_WEEK=2, UNIT_DAY=3,
  54. } time_unit_t;
  55. /* Fields for accounting logic. Accounting overview:
  56. *
  57. * Accounting is designed to ensure that no more than N bytes are sent in
  58. * either direction over a given interval (currently, one month, one week, or
  59. * one day) We could
  60. * try to do this by choking our bandwidth to a trickle, but that
  61. * would make our streams useless. Instead, we estimate what our
  62. * bandwidth usage will be, and guess how long we'll be able to
  63. * provide that much bandwidth before hitting our limit. We then
  64. * choose a random time within the accounting interval to come up (so
  65. * that we don't get 50 Tors running on the 1st of the month and none
  66. * on the 30th).
  67. *
  68. * Each interval runs as follows:
  69. *
  70. * 1. We guess our bandwidth usage, based on how much we used
  71. * last time. We choose a "wakeup time" within the interval to come up.
  72. * 2. Until the chosen wakeup time, we hibernate.
  73. * 3. We come up at the wakeup time, and provide bandwidth until we are
  74. * "very close" to running out.
  75. * 4. Then we go into low-bandwidth mode, and stop accepting new
  76. * connections, but provide bandwidth until we run out.
  77. * 5. Then we hibernate until the end of the interval.
  78. *
  79. * If the interval ends before we run out of bandwidth, we go back to
  80. * step one.
  81. */
  82. /** How many bytes have we read in this accounting interval? */
  83. static uint64_t n_bytes_read_in_interval = 0;
  84. /** How many bytes have we written in this accounting interval? */
  85. static uint64_t n_bytes_written_in_interval = 0;
  86. /** How many seconds have we been running this interval? */
  87. static uint32_t n_seconds_active_in_interval = 0;
  88. /** How many seconds were we active in this interval before we hit our soft
  89. * limit? */
  90. static int n_seconds_to_hit_soft_limit = 0;
  91. /** When in this interval was the soft limit hit. */
  92. static time_t soft_limit_hit_at = 0;
  93. /** How many bytes had we read/written when we hit the soft limit? */
  94. static uint64_t n_bytes_at_soft_limit = 0;
  95. /** When did this accounting interval start? */
  96. static time_t interval_start_time = 0;
  97. /** When will this accounting interval end? */
  98. static time_t interval_end_time = 0;
  99. /** How far into the accounting interval should we hibernate? */
  100. static time_t interval_wakeup_time = 0;
  101. /** How much bandwidth do we 'expect' to use per minute? (0 if we have no
  102. * info from the last period.) */
  103. static uint64_t expected_bandwidth_usage = 0;
  104. /** What unit are we using for our accounting? */
  105. static time_unit_t cfg_unit = UNIT_MONTH;
  106. /** How many days,hours,minutes into each unit does our accounting interval
  107. * start? */
  108. static int cfg_start_day = 0,
  109. cfg_start_hour = 0,
  110. cfg_start_min = 0;
  111. static void reset_accounting(time_t now);
  112. static int read_bandwidth_usage(void);
  113. static time_t start_of_accounting_period_after(time_t now);
  114. static time_t start_of_accounting_period_containing(time_t now);
  115. static void accounting_set_wakeup_time(void);
  116. /* ************
  117. * Functions for bandwidth accounting.
  118. * ************/
  119. /** Configure accounting start/end time settings based on
  120. * options->AccountingStart. Return 0 on success, -1 on failure. If
  121. * <b>validate_only</b> is true, do not change the current settings. */
  122. int
  123. accounting_parse_options(or_options_t *options, int validate_only)
  124. {
  125. time_unit_t unit;
  126. int ok, idx;
  127. long d,h,m;
  128. smartlist_t *items;
  129. const char *v = options->AccountingStart;
  130. const char *s;
  131. char *cp;
  132. if (!v) {
  133. if (!validate_only) {
  134. cfg_unit = UNIT_MONTH;
  135. cfg_start_day = 1;
  136. cfg_start_hour = 0;
  137. cfg_start_min = 0;
  138. }
  139. return 0;
  140. }
  141. items = smartlist_create();
  142. smartlist_split_string(items, v, NULL,
  143. SPLIT_SKIP_SPACE|SPLIT_IGNORE_BLANK,0);
  144. if (smartlist_len(items)<2) {
  145. log_warn(LD_CONFIG, "Too few arguments to AccountingStart");
  146. goto err;
  147. }
  148. s = smartlist_get(items,0);
  149. if (0==strcasecmp(s, "month")) {
  150. unit = UNIT_MONTH;
  151. } else if (0==strcasecmp(s, "week")) {
  152. unit = UNIT_WEEK;
  153. } else if (0==strcasecmp(s, "day")) {
  154. unit = UNIT_DAY;
  155. } else {
  156. log_warn(LD_CONFIG,
  157. "Unrecognized accounting unit '%s': only 'month', 'week',"
  158. " and 'day' are supported.", s);
  159. goto err;
  160. }
  161. switch (unit) {
  162. case UNIT_WEEK:
  163. d = tor_parse_long(smartlist_get(items,1), 10, 1, 7, &ok, NULL);
  164. if (!ok) {
  165. log_warn(LD_CONFIG, "Weekly accounting must begin on a day between "
  166. "1 (Monday) and 7 (Sunday)");
  167. goto err;
  168. }
  169. break;
  170. case UNIT_MONTH:
  171. d = tor_parse_long(smartlist_get(items,1), 10, 1, 28, &ok, NULL);
  172. if (!ok) {
  173. log_warn(LD_CONFIG, "Monthly accounting must begin on a day between "
  174. "1 and 28");
  175. goto err;
  176. }
  177. break;
  178. case UNIT_DAY:
  179. d = 0;
  180. break;
  181. /* Coverity dislikes unreachable default cases; some compilers warn on
  182. * switch statements missing a case. Tell Coverity not to worry. */
  183. /* coverity[dead_error_begin] */
  184. default:
  185. tor_assert(0);
  186. }
  187. idx = unit==UNIT_DAY?1:2;
  188. if (smartlist_len(items) != (idx+1)) {
  189. log_warn(LD_CONFIG,"Accounting unit '%s' requires %d argument%s.",
  190. s, idx, (idx>1)?"s":"");
  191. goto err;
  192. }
  193. s = smartlist_get(items, idx);
  194. h = tor_parse_long(s, 10, 0, 23, &ok, &cp);
  195. if (!ok) {
  196. log_warn(LD_CONFIG,"Accounting start time not parseable: bad hour.");
  197. goto err;
  198. }
  199. if (!cp || *cp!=':') {
  200. log_warn(LD_CONFIG,
  201. "Accounting start time not parseable: not in HH:MM format");
  202. goto err;
  203. }
  204. m = tor_parse_long(cp+1, 10, 0, 59, &ok, &cp);
  205. if (!ok) {
  206. log_warn(LD_CONFIG, "Accounting start time not parseable: bad minute");
  207. goto err;
  208. }
  209. if (!cp || *cp!='\0') {
  210. log_warn(LD_CONFIG,
  211. "Accounting start time not parseable: not in HH:MM format");
  212. goto err;
  213. }
  214. if (!validate_only) {
  215. cfg_unit = unit;
  216. cfg_start_day = (int)d;
  217. cfg_start_hour = (int)h;
  218. cfg_start_min = (int)m;
  219. }
  220. SMARTLIST_FOREACH(items, char *, item, tor_free(item));
  221. smartlist_free(items);
  222. return 0;
  223. err:
  224. SMARTLIST_FOREACH(items, char *, item, tor_free(item));
  225. smartlist_free(items);
  226. return -1;
  227. }
  228. /** If we want to manage the accounting system and potentially
  229. * hibernate, return 1, else return 0.
  230. */
  231. int
  232. accounting_is_enabled(or_options_t *options)
  233. {
  234. if (options->AccountingMax)
  235. return 1;
  236. return 0;
  237. }
  238. /** Called from main.c to tell us that <b>seconds</b> seconds have
  239. * passed, <b>n_read</b> bytes have been read, and <b>n_written</b>
  240. * bytes have been written. */
  241. void
  242. accounting_add_bytes(size_t n_read, size_t n_written, int seconds)
  243. {
  244. n_bytes_read_in_interval += n_read;
  245. n_bytes_written_in_interval += n_written;
  246. /* If we haven't been called in 10 seconds, we're probably jumping
  247. * around in time. */
  248. n_seconds_active_in_interval += (seconds < 10) ? seconds : 0;
  249. }
  250. /** If get_end, return the end of the accounting period that contains
  251. * the time <b>now</b>. Else, return the start of the accounting
  252. * period that contains the time <b>now</b> */
  253. static time_t
  254. edge_of_accounting_period_containing(time_t now, int get_end)
  255. {
  256. int before;
  257. struct tm tm;
  258. tor_localtime_r(&now, &tm);
  259. /* Set 'before' to true iff the current time is before the hh:mm
  260. * changeover time for today. */
  261. before = tm.tm_hour < cfg_start_hour ||
  262. (tm.tm_hour == cfg_start_hour && tm.tm_min < cfg_start_min);
  263. /* Dispatch by unit. First, find the start day of the given period;
  264. * then, if get_end is true, increment to the end day. */
  265. switch (cfg_unit)
  266. {
  267. case UNIT_MONTH: {
  268. /* If this is before the Nth, we want the Nth of last month. */
  269. if (tm.tm_mday < cfg_start_day ||
  270. (tm.tm_mday < cfg_start_day && before)) {
  271. --tm.tm_mon;
  272. }
  273. /* Otherwise, the month is correct. */
  274. tm.tm_mday = cfg_start_day;
  275. if (get_end)
  276. ++tm.tm_mon;
  277. break;
  278. }
  279. case UNIT_WEEK: {
  280. /* What is the 'target' day of the week in struct tm format? (We
  281. say Sunday==7; struct tm says Sunday==0.) */
  282. int wday = cfg_start_day % 7;
  283. /* How many days do we subtract from today to get to the right day? */
  284. int delta = (7+tm.tm_wday-wday)%7;
  285. /* If we are on the right day, but the changeover hasn't happened yet,
  286. * then subtract a whole week. */
  287. if (delta == 0 && before)
  288. delta = 7;
  289. tm.tm_mday -= delta;
  290. if (get_end)
  291. tm.tm_mday += 7;
  292. break;
  293. }
  294. case UNIT_DAY:
  295. if (before)
  296. --tm.tm_mday;
  297. if (get_end)
  298. ++tm.tm_mday;
  299. break;
  300. default:
  301. tor_assert(0);
  302. }
  303. tm.tm_hour = cfg_start_hour;
  304. tm.tm_min = cfg_start_min;
  305. tm.tm_sec = 0;
  306. tm.tm_isdst = -1; /* Autodetect DST */
  307. return mktime(&tm);
  308. }
  309. /** Return the start of the accounting period containing the time
  310. * <b>now</b>. */
  311. static time_t
  312. start_of_accounting_period_containing(time_t now)
  313. {
  314. return edge_of_accounting_period_containing(now, 0);
  315. }
  316. /** Return the start of the accounting period that comes after the one
  317. * containing the time <b>now</b>. */
  318. static time_t
  319. start_of_accounting_period_after(time_t now)
  320. {
  321. return edge_of_accounting_period_containing(now, 1);
  322. }
  323. /** Initialize the accounting subsystem. */
  324. void
  325. configure_accounting(time_t now)
  326. {
  327. /* Try to remember our recorded usage. */
  328. if (!interval_start_time)
  329. read_bandwidth_usage(); /* If we fail, we'll leave values at zero, and
  330. * reset below.*/
  331. if (!interval_start_time ||
  332. start_of_accounting_period_after(interval_start_time) <= now) {
  333. /* We didn't have recorded usage, or we don't have recorded usage
  334. * for this interval. Start a new interval. */
  335. log_info(LD_ACCT, "Starting new accounting interval.");
  336. reset_accounting(now);
  337. } else if (interval_start_time ==
  338. start_of_accounting_period_containing(interval_start_time)) {
  339. log_info(LD_ACCT, "Continuing accounting interval.");
  340. /* We are in the interval we thought we were in. Do nothing.*/
  341. interval_end_time = start_of_accounting_period_after(interval_start_time);
  342. } else {
  343. log_warn(LD_ACCT,
  344. "Mismatched accounting interval; starting a fresh one.");
  345. reset_accounting(now);
  346. }
  347. accounting_set_wakeup_time();
  348. }
  349. /** Set expected_bandwidth_usage based on how much we sent/received
  350. * per minute last interval (if we were up for at least 30 minutes),
  351. * or based on our declared bandwidth otherwise. */
  352. static void
  353. update_expected_bandwidth(void)
  354. {
  355. uint64_t expected;
  356. or_options_t *options= get_options();
  357. uint64_t max_configured = (options->RelayBandwidthRate > 0 ?
  358. options->RelayBandwidthRate :
  359. options->BandwidthRate) * 60;
  360. #define MIN_TIME_FOR_MEASUREMENT (1800)
  361. if (soft_limit_hit_at > interval_start_time && n_bytes_at_soft_limit &&
  362. (soft_limit_hit_at - interval_start_time) > MIN_TIME_FOR_MEASUREMENT) {
  363. /* If we hit our soft limit last time, only count the bytes up to that
  364. * time. This is a better predictor of our actual bandwidth than
  365. * considering the entirety of the last interval, since we likely started
  366. * using bytes very slowly once we hit our soft limit. */
  367. expected = n_bytes_at_soft_limit /
  368. (soft_limit_hit_at - interval_start_time);
  369. expected /= 60;
  370. } else if (n_seconds_active_in_interval >= MIN_TIME_FOR_MEASUREMENT) {
  371. /* Otherwise, we either measured enough time in the last interval but
  372. * never hit our soft limit, or we're using a state file from a Tor that
  373. * doesn't know to store soft-limit info. Just take rate at which
  374. * we were reading/writing in the last interval as our expected rate.
  375. */
  376. uint64_t used = MAX(n_bytes_written_in_interval,
  377. n_bytes_read_in_interval);
  378. expected = used / (n_seconds_active_in_interval / 60);
  379. } else {
  380. /* If we haven't gotten enough data last interval, set 'expected'
  381. * to 0. This will set our wakeup to the start of the interval.
  382. * Next interval, we'll choose our starting time based on how much
  383. * we sent this interval.
  384. */
  385. expected = 0;
  386. }
  387. if (expected > max_configured)
  388. expected = max_configured;
  389. expected_bandwidth_usage = expected;
  390. }
  391. /** Called at the start of a new accounting interval: reset our
  392. * expected bandwidth usage based on what happened last time, set up
  393. * the start and end of the interval, and clear byte/time totals.
  394. */
  395. static void
  396. reset_accounting(time_t now)
  397. {
  398. log_info(LD_ACCT, "Starting new accounting interval.");
  399. update_expected_bandwidth();
  400. interval_start_time = start_of_accounting_period_containing(now);
  401. interval_end_time = start_of_accounting_period_after(interval_start_time);
  402. n_bytes_read_in_interval = 0;
  403. n_bytes_written_in_interval = 0;
  404. n_seconds_active_in_interval = 0;
  405. n_bytes_at_soft_limit = 0;
  406. soft_limit_hit_at = 0;
  407. n_seconds_to_hit_soft_limit = 0;
  408. }
  409. /** Return true iff we should save our bandwidth usage to disk. */
  410. static INLINE int
  411. time_to_record_bandwidth_usage(time_t now)
  412. {
  413. /* Note every 600 sec */
  414. #define NOTE_INTERVAL (600)
  415. /* Or every 20 megabytes */
  416. #define NOTE_BYTES 20*(1024*1024)
  417. static uint64_t last_read_bytes_noted = 0;
  418. static uint64_t last_written_bytes_noted = 0;
  419. static time_t last_time_noted = 0;
  420. if (last_time_noted + NOTE_INTERVAL <= now ||
  421. last_read_bytes_noted + NOTE_BYTES <= n_bytes_read_in_interval ||
  422. last_written_bytes_noted + NOTE_BYTES <= n_bytes_written_in_interval ||
  423. (interval_end_time && interval_end_time <= now)) {
  424. last_time_noted = now;
  425. last_read_bytes_noted = n_bytes_read_in_interval;
  426. last_written_bytes_noted = n_bytes_written_in_interval;
  427. return 1;
  428. }
  429. return 0;
  430. }
  431. /** Invoked once per second. Checks whether it is time to hibernate,
  432. * record bandwidth used, etc. */
  433. void
  434. accounting_run_housekeeping(time_t now)
  435. {
  436. if (now >= interval_end_time) {
  437. configure_accounting(now);
  438. }
  439. if (time_to_record_bandwidth_usage(now)) {
  440. if (accounting_record_bandwidth_usage(now, get_or_state())) {
  441. log_warn(LD_FS, "Couldn't record bandwidth usage to disk.");
  442. }
  443. }
  444. }
  445. /** When we have no idea how fast we are, how long do we assume it will take
  446. * us to exhaust our bandwidth? */
  447. #define GUESS_TIME_TO_USE_BANDWIDTH (24*60*60)
  448. /** Based on our interval and our estimated bandwidth, choose a
  449. * deterministic (but random-ish) time to wake up. */
  450. static void
  451. accounting_set_wakeup_time(void)
  452. {
  453. char buf[ISO_TIME_LEN+1];
  454. char digest[DIGEST_LEN];
  455. crypto_digest_env_t *d_env;
  456. int time_in_interval;
  457. uint64_t time_to_exhaust_bw;
  458. int time_to_consider;
  459. if (! identity_key_is_set()) {
  460. if (init_keys() < 0) {
  461. log_err(LD_BUG, "Error initializing keys");
  462. tor_assert(0);
  463. }
  464. }
  465. format_iso_time(buf, interval_start_time);
  466. crypto_pk_get_digest(get_identity_key(), digest);
  467. d_env = crypto_new_digest_env();
  468. crypto_digest_add_bytes(d_env, buf, ISO_TIME_LEN);
  469. crypto_digest_add_bytes(d_env, digest, DIGEST_LEN);
  470. crypto_digest_get_digest(d_env, digest, DIGEST_LEN);
  471. crypto_free_digest_env(d_env);
  472. if (!expected_bandwidth_usage) {
  473. char buf1[ISO_TIME_LEN+1];
  474. char buf2[ISO_TIME_LEN+1];
  475. format_local_iso_time(buf1, interval_start_time);
  476. format_local_iso_time(buf2, interval_end_time);
  477. time_to_exhaust_bw = GUESS_TIME_TO_USE_BANDWIDTH;
  478. interval_wakeup_time = interval_start_time;
  479. log_notice(LD_ACCT,
  480. "Configured hibernation. This interval begins at %s "
  481. "and ends at %s. We have no prior estimate for bandwidth, so "
  482. "we will start out awake and hibernate when we exhaust our quota.",
  483. buf1, buf2);
  484. return;
  485. }
  486. time_in_interval = (int)(interval_end_time - interval_start_time);
  487. time_to_exhaust_bw =
  488. (get_options()->AccountingMax/expected_bandwidth_usage)*60;
  489. if (time_to_exhaust_bw > TIME_MAX) {
  490. time_to_exhaust_bw = TIME_MAX;
  491. time_to_consider = 0;
  492. } else {
  493. time_to_consider = time_in_interval - (int)time_to_exhaust_bw;
  494. }
  495. if (time_to_consider<=0) {
  496. interval_wakeup_time = interval_start_time;
  497. } else {
  498. /* XXX can we simplify this just by picking a random (non-deterministic)
  499. * time to be up? If we go down and come up, then we pick a new one. Is
  500. * that good enough? -RD */
  501. /* This is not a perfectly unbiased conversion, but it is good enough:
  502. * in the worst case, the first half of the day is 0.06 percent likelier
  503. * to be chosen than the last half. */
  504. interval_wakeup_time = interval_start_time +
  505. (get_uint32(digest) % time_to_consider);
  506. format_iso_time(buf, interval_wakeup_time);
  507. }
  508. {
  509. char buf1[ISO_TIME_LEN+1];
  510. char buf2[ISO_TIME_LEN+1];
  511. char buf3[ISO_TIME_LEN+1];
  512. char buf4[ISO_TIME_LEN+1];
  513. time_t down_time;
  514. if (interval_wakeup_time+time_to_exhaust_bw > TIME_MAX)
  515. down_time = TIME_MAX;
  516. else
  517. down_time = (time_t)(interval_wakeup_time+time_to_exhaust_bw);
  518. if (down_time>interval_end_time)
  519. down_time = interval_end_time;
  520. format_local_iso_time(buf1, interval_start_time);
  521. format_local_iso_time(buf2, interval_wakeup_time);
  522. format_local_iso_time(buf3, down_time);
  523. format_local_iso_time(buf4, interval_end_time);
  524. log_notice(LD_ACCT,
  525. "Configured hibernation. This interval began at %s; "
  526. "the scheduled wake-up time %s %s; "
  527. "we expect%s to exhaust our quota for this interval around %s; "
  528. "the next interval begins at %s (all times local)",
  529. buf1,
  530. time(NULL)<interval_wakeup_time?"is":"was", buf2,
  531. time(NULL)<down_time?"":"ed", buf3,
  532. buf4);
  533. }
  534. }
  535. /* This rounds 0 up to 1000, but that's actually a feature. */
  536. #define ROUND_UP(x) (((x) + 0x3ff) & ~0x3ff)
  537. /** Save all our bandwidth tracking information to disk. Return 0 on
  538. * success, -1 on failure. */
  539. int
  540. accounting_record_bandwidth_usage(time_t now, or_state_t *state)
  541. {
  542. /* Just update the state */
  543. state->AccountingIntervalStart = interval_start_time;
  544. state->AccountingBytesReadInInterval = ROUND_UP(n_bytes_read_in_interval);
  545. state->AccountingBytesWrittenInInterval =
  546. ROUND_UP(n_bytes_written_in_interval);
  547. state->AccountingSecondsActive = n_seconds_active_in_interval;
  548. state->AccountingExpectedUsage = expected_bandwidth_usage;
  549. state->AccountingSecondsToReachSoftLimit = n_seconds_to_hit_soft_limit;
  550. state->AccountingSoftLimitHitAt = soft_limit_hit_at;
  551. state->AccountingBytesAtSoftLimit = n_bytes_at_soft_limit;
  552. or_state_mark_dirty(state,
  553. now+(get_options()->AvoidDiskWrites ? 7200 : 60));
  554. return 0;
  555. }
  556. #undef ROUND_UP
  557. /** Read stored accounting information from disk. Return 0 on success;
  558. * return -1 and change nothing on failure. */
  559. static int
  560. read_bandwidth_usage(void)
  561. {
  562. or_state_t *state = get_or_state();
  563. {
  564. char *fname = get_datadir_fname("bw_accounting");
  565. unlink(fname);
  566. tor_free(fname);
  567. }
  568. if (!state)
  569. return -1;
  570. log_info(LD_ACCT, "Reading bandwidth accounting data from state file");
  571. n_bytes_read_in_interval = state->AccountingBytesReadInInterval;
  572. n_bytes_written_in_interval = state->AccountingBytesWrittenInInterval;
  573. n_seconds_active_in_interval = state->AccountingSecondsActive;
  574. interval_start_time = state->AccountingIntervalStart;
  575. expected_bandwidth_usage = state->AccountingExpectedUsage;
  576. /* Older versions of Tor (before 0.2.2.17-alpha or so) didn't generate these
  577. * fields. If you switch back and forth, you might get an
  578. * AccountingSoftLimitHitAt value from long before the most recent
  579. * interval_start_time. If that's so, then ignore the softlimit-related
  580. * values. */
  581. if (state->AccountingSoftLimitHitAt > interval_start_time) {
  582. soft_limit_hit_at = state->AccountingSoftLimitHitAt;
  583. n_bytes_at_soft_limit = state->AccountingBytesAtSoftLimit;
  584. n_seconds_to_hit_soft_limit = state->AccountingSecondsToReachSoftLimit;
  585. } else {
  586. soft_limit_hit_at = 0;
  587. n_bytes_at_soft_limit = 0;
  588. n_seconds_to_hit_soft_limit = 0;
  589. }
  590. {
  591. char tbuf1[ISO_TIME_LEN+1];
  592. char tbuf2[ISO_TIME_LEN+1];
  593. format_iso_time(tbuf1, state->LastWritten);
  594. format_iso_time(tbuf2, state->AccountingIntervalStart);
  595. log_info(LD_ACCT,
  596. "Successfully read bandwidth accounting info from state written at %s "
  597. "for interval starting at %s. We have been active for %lu seconds in "
  598. "this interval. At the start of the interval, we expected to use "
  599. "about %lu KB per second. ("U64_FORMAT" bytes read so far, "
  600. U64_FORMAT" bytes written so far)",
  601. tbuf1, tbuf2,
  602. (unsigned long)n_seconds_active_in_interval,
  603. (unsigned long)(expected_bandwidth_usage*1024/60),
  604. U64_PRINTF_ARG(n_bytes_read_in_interval),
  605. U64_PRINTF_ARG(n_bytes_written_in_interval));
  606. }
  607. return 0;
  608. }
  609. /** Return true iff we have sent/received all the bytes we are willing
  610. * to send/receive this interval. */
  611. static int
  612. hibernate_hard_limit_reached(void)
  613. {
  614. uint64_t hard_limit = get_options()->AccountingMax;
  615. if (!hard_limit)
  616. return 0;
  617. return n_bytes_read_in_interval >= hard_limit
  618. || n_bytes_written_in_interval >= hard_limit;
  619. }
  620. /** Return true iff we have sent/received almost all the bytes we are willing
  621. * to send/receive this interval. */
  622. static int
  623. hibernate_soft_limit_reached(void)
  624. {
  625. const uint64_t acct_max = get_options()->AccountingMax;
  626. #define SOFT_LIM_PCT (.95)
  627. #define SOFT_LIM_BYTES (500*1024*1024)
  628. #define SOFT_LIM_MINUTES (3*60)
  629. /* The 'soft limit' is a fair bit more complicated now than once it was.
  630. * We want to stop accepting connections when ALL of the following are true:
  631. * - We expect to use up the remaining bytes in under 3 hours
  632. * - We have used up 95% of our bytes.
  633. * - We have less than 500MB of bytes left.
  634. */
  635. uint64_t soft_limit = DBL_TO_U64(U64_TO_DBL(acct_max) * SOFT_LIM_PCT);
  636. if (acct_max > SOFT_LIM_BYTES && acct_max - SOFT_LIM_BYTES > soft_limit) {
  637. soft_limit = acct_max - SOFT_LIM_BYTES;
  638. }
  639. if (expected_bandwidth_usage) {
  640. const uint64_t expected_usage =
  641. expected_bandwidth_usage * SOFT_LIM_MINUTES;
  642. if (acct_max > expected_usage && acct_max - expected_usage > soft_limit)
  643. soft_limit = acct_max - expected_usage;
  644. }
  645. if (!soft_limit)
  646. return 0;
  647. return n_bytes_read_in_interval >= soft_limit
  648. || n_bytes_written_in_interval >= soft_limit;
  649. }
  650. /** Called when we get a SIGINT, or when bandwidth soft limit is
  651. * reached. Puts us into "loose hibernation": we don't accept new
  652. * connections, but we continue handling old ones. */
  653. static void
  654. hibernate_begin(hibernate_state_t new_state, time_t now)
  655. {
  656. connection_t *conn;
  657. or_options_t *options = get_options();
  658. if (new_state == HIBERNATE_STATE_EXITING &&
  659. hibernate_state != HIBERNATE_STATE_LIVE) {
  660. log_notice(LD_GENERAL,"SIGINT received %s; exiting now.",
  661. hibernate_state == HIBERNATE_STATE_EXITING ?
  662. "a second time" : "while hibernating");
  663. tor_cleanup();
  664. exit(0);
  665. }
  666. if (new_state == HIBERNATE_STATE_LOWBANDWIDTH &&
  667. hibernate_state == HIBERNATE_STATE_LIVE) {
  668. soft_limit_hit_at = now;
  669. n_seconds_to_hit_soft_limit = n_seconds_active_in_interval;
  670. n_bytes_at_soft_limit = MAX(n_bytes_read_in_interval,
  671. n_bytes_written_in_interval);
  672. }
  673. /* close listeners. leave control listener(s). */
  674. while ((conn = connection_get_by_type(CONN_TYPE_OR_LISTENER)) ||
  675. (conn = connection_get_by_type(CONN_TYPE_AP_LISTENER)) ||
  676. (conn = connection_get_by_type(CONN_TYPE_AP_TRANS_LISTENER)) ||
  677. (conn = connection_get_by_type(CONN_TYPE_AP_DNS_LISTENER)) ||
  678. (conn = connection_get_by_type(CONN_TYPE_AP_NATD_LISTENER)) ||
  679. (conn = connection_get_by_type(CONN_TYPE_DIR_LISTENER))) {
  680. log_info(LD_NET,"Closing listener type %d", conn->type);
  681. connection_mark_for_close(conn);
  682. }
  683. /* XXX kill intro point circs */
  684. /* XXX upload rendezvous service descriptors with no intro points */
  685. if (new_state == HIBERNATE_STATE_EXITING) {
  686. log_notice(LD_GENERAL,"Interrupt: will shut down in %d seconds. Interrupt "
  687. "again to exit now.", options->ShutdownWaitLength);
  688. shutdown_time = time(NULL) + options->ShutdownWaitLength;
  689. } else { /* soft limit reached */
  690. hibernate_end_time = interval_end_time;
  691. }
  692. hibernate_state = new_state;
  693. accounting_record_bandwidth_usage(now, get_or_state());
  694. or_state_mark_dirty(get_or_state(),
  695. get_options()->AvoidDiskWrites ? now+600 : 0);
  696. }
  697. /** Called when we've been hibernating and our timeout is reached. */
  698. static void
  699. hibernate_end(hibernate_state_t new_state)
  700. {
  701. tor_assert(hibernate_state == HIBERNATE_STATE_LOWBANDWIDTH ||
  702. hibernate_state == HIBERNATE_STATE_DORMANT);
  703. /* listeners will be relaunched in run_scheduled_events() in main.c */
  704. log_notice(LD_ACCT,"Hibernation period ended. Resuming normal activity.");
  705. hibernate_state = new_state;
  706. hibernate_end_time = 0; /* no longer hibernating */
  707. stats_n_seconds_working = 0; /* reset published uptime */
  708. }
  709. /** A wrapper around hibernate_begin, for when we get SIGINT. */
  710. void
  711. hibernate_begin_shutdown(void)
  712. {
  713. hibernate_begin(HIBERNATE_STATE_EXITING, time(NULL));
  714. }
  715. /** Return true iff we are currently hibernating. */
  716. int
  717. we_are_hibernating(void)
  718. {
  719. return hibernate_state != HIBERNATE_STATE_LIVE;
  720. }
  721. /** If we aren't currently dormant, close all connections and become
  722. * dormant. */
  723. static void
  724. hibernate_go_dormant(time_t now)
  725. {
  726. connection_t *conn;
  727. if (hibernate_state == HIBERNATE_STATE_DORMANT)
  728. return;
  729. else if (hibernate_state == HIBERNATE_STATE_LOWBANDWIDTH)
  730. hibernate_state = HIBERNATE_STATE_DORMANT;
  731. else
  732. hibernate_begin(HIBERNATE_STATE_DORMANT, now);
  733. log_notice(LD_ACCT,"Going dormant. Blowing away remaining connections.");
  734. /* Close all OR/AP/exit conns. Leave dir conns because we still want
  735. * to be able to upload server descriptors so people know we're still
  736. * running, and download directories so we can detect if we're obsolete.
  737. * Leave control conns because we still want to be controllable.
  738. */
  739. while ((conn = connection_get_by_type(CONN_TYPE_OR)) ||
  740. (conn = connection_get_by_type(CONN_TYPE_AP)) ||
  741. (conn = connection_get_by_type(CONN_TYPE_EXIT))) {
  742. if (CONN_IS_EDGE(conn))
  743. connection_edge_end(TO_EDGE_CONN(conn), END_STREAM_REASON_HIBERNATING);
  744. log_info(LD_NET,"Closing conn type %d", conn->type);
  745. if (conn->type == CONN_TYPE_AP) /* send socks failure if needed */
  746. connection_mark_unattached_ap(TO_EDGE_CONN(conn),
  747. END_STREAM_REASON_HIBERNATING);
  748. else
  749. connection_mark_for_close(conn);
  750. }
  751. if (now < interval_wakeup_time)
  752. hibernate_end_time = interval_wakeup_time;
  753. else
  754. hibernate_end_time = interval_end_time;
  755. accounting_record_bandwidth_usage(now, get_or_state());
  756. or_state_mark_dirty(get_or_state(),
  757. get_options()->AvoidDiskWrites ? now+600 : 0);
  758. }
  759. /** Called when hibernate_end_time has arrived. */
  760. static void
  761. hibernate_end_time_elapsed(time_t now)
  762. {
  763. char buf[ISO_TIME_LEN+1];
  764. /* The interval has ended, or it is wakeup time. Find out which. */
  765. accounting_run_housekeeping(now);
  766. if (interval_wakeup_time <= now) {
  767. /* The interval hasn't changed, but interval_wakeup_time has passed.
  768. * It's time to wake up and start being a server. */
  769. hibernate_end(HIBERNATE_STATE_LIVE);
  770. return;
  771. } else {
  772. /* The interval has changed, and it isn't time to wake up yet. */
  773. hibernate_end_time = interval_wakeup_time;
  774. format_iso_time(buf,interval_wakeup_time);
  775. if (hibernate_state != HIBERNATE_STATE_DORMANT) {
  776. /* We weren't sleeping before; we should sleep now. */
  777. log_notice(LD_ACCT,
  778. "Accounting period ended. Commencing hibernation until "
  779. "%s GMT", buf);
  780. hibernate_go_dormant(now);
  781. } else {
  782. log_notice(LD_ACCT,
  783. "Accounting period ended. This period, we will hibernate"
  784. " until %s GMT",buf);
  785. }
  786. }
  787. }
  788. /** Consider our environment and decide if it's time
  789. * to start/stop hibernating.
  790. */
  791. void
  792. consider_hibernation(time_t now)
  793. {
  794. int accounting_enabled = get_options()->AccountingMax != 0;
  795. char buf[ISO_TIME_LEN+1];
  796. /* If we're in 'exiting' mode, then we just shut down after the interval
  797. * elapses. */
  798. if (hibernate_state == HIBERNATE_STATE_EXITING) {
  799. tor_assert(shutdown_time);
  800. if (shutdown_time <= now) {
  801. log_notice(LD_GENERAL, "Clean shutdown finished. Exiting.");
  802. tor_cleanup();
  803. exit(0);
  804. }
  805. return; /* if exiting soon, don't worry about bandwidth limits */
  806. }
  807. if (hibernate_state == HIBERNATE_STATE_DORMANT) {
  808. /* We've been hibernating because of bandwidth accounting. */
  809. tor_assert(hibernate_end_time);
  810. if (hibernate_end_time > now && accounting_enabled) {
  811. /* If we're hibernating, don't wake up until it's time, regardless of
  812. * whether we're in a new interval. */
  813. return ;
  814. } else {
  815. hibernate_end_time_elapsed(now);
  816. }
  817. }
  818. /* Else, we aren't hibernating. See if it's time to start hibernating, or to
  819. * go dormant. */
  820. if (hibernate_state == HIBERNATE_STATE_LIVE) {
  821. if (hibernate_soft_limit_reached()) {
  822. log_notice(LD_ACCT,
  823. "Bandwidth soft limit reached; commencing hibernation.");
  824. hibernate_begin(HIBERNATE_STATE_LOWBANDWIDTH, now);
  825. } else if (accounting_enabled && now < interval_wakeup_time) {
  826. format_local_iso_time(buf,interval_wakeup_time);
  827. log_notice(LD_ACCT,
  828. "Commencing hibernation. We will wake up at %s local time.",
  829. buf);
  830. hibernate_go_dormant(now);
  831. }
  832. }
  833. if (hibernate_state == HIBERNATE_STATE_LOWBANDWIDTH) {
  834. if (!accounting_enabled) {
  835. hibernate_end_time_elapsed(now);
  836. } else if (hibernate_hard_limit_reached()) {
  837. hibernate_go_dormant(now);
  838. } else if (hibernate_end_time <= now) {
  839. /* The hibernation period ended while we were still in lowbandwidth.*/
  840. hibernate_end_time_elapsed(now);
  841. }
  842. }
  843. }
  844. /** Helper function: called when we get a GETINFO request for an
  845. * accounting-related key on the control connection <b>conn</b>. If we can
  846. * answer the request for <b>question</b>, then set *<b>answer</b> to a newly
  847. * allocated string holding the result. Otherwise, set *<b>answer</b> to
  848. * NULL. */
  849. int
  850. getinfo_helper_accounting(control_connection_t *conn,
  851. const char *question, char **answer,
  852. const char **errmsg)
  853. {
  854. (void) conn;
  855. (void) errmsg;
  856. if (!strcmp(question, "accounting/enabled")) {
  857. *answer = tor_strdup(accounting_is_enabled(get_options()) ? "1" : "0");
  858. } else if (!strcmp(question, "accounting/hibernating")) {
  859. if (hibernate_state == HIBERNATE_STATE_DORMANT)
  860. *answer = tor_strdup("hard");
  861. else if (hibernate_state == HIBERNATE_STATE_LOWBANDWIDTH)
  862. *answer = tor_strdup("soft");
  863. else
  864. *answer = tor_strdup("awake");
  865. } else if (!strcmp(question, "accounting/bytes")) {
  866. *answer = tor_malloc(32);
  867. tor_snprintf(*answer, 32, U64_FORMAT" "U64_FORMAT,
  868. U64_PRINTF_ARG(n_bytes_read_in_interval),
  869. U64_PRINTF_ARG(n_bytes_written_in_interval));
  870. } else if (!strcmp(question, "accounting/bytes-left")) {
  871. uint64_t limit = get_options()->AccountingMax;
  872. uint64_t read_left = 0, write_left = 0;
  873. if (n_bytes_read_in_interval < limit)
  874. read_left = limit - n_bytes_read_in_interval;
  875. if (n_bytes_written_in_interval < limit)
  876. write_left = limit - n_bytes_written_in_interval;
  877. *answer = tor_malloc(64);
  878. tor_snprintf(*answer, 64, U64_FORMAT" "U64_FORMAT,
  879. U64_PRINTF_ARG(read_left), U64_PRINTF_ARG(write_left));
  880. } else if (!strcmp(question, "accounting/interval-start")) {
  881. *answer = tor_malloc(ISO_TIME_LEN+1);
  882. format_iso_time(*answer, interval_start_time);
  883. } else if (!strcmp(question, "accounting/interval-wake")) {
  884. *answer = tor_malloc(ISO_TIME_LEN+1);
  885. format_iso_time(*answer, interval_wakeup_time);
  886. } else if (!strcmp(question, "accounting/interval-end")) {
  887. *answer = tor_malloc(ISO_TIME_LEN+1);
  888. format_iso_time(*answer, interval_end_time);
  889. } else {
  890. *answer = NULL;
  891. }
  892. return 0;
  893. }