Implemented memcache_debug and apc_debug options for cache operations tracking

Aleksander Machniak

2015-05-08 44708ec647ca45ff7f2ae01108ca3773100fe06a

 program/lib/Roundcube/rcube_cache_shared.php

@@ -41,9 +41,13 @@
    private $packed;
    private $index;
    private $table;
    private $debug;
    private $cache         = array();
    private $cache_changes = array();
    private $cache_sums    = array();
    private $max_packet    = -1;

    const DEBUG_LINE_LENGTH = 4096;


    /**
@@ -62,17 +66,19 @@
        $type  = strtolower($type);

        if ($type == 'memcache') {
            $this->type = 'memcache';
            $this->db   = $rcube->get_memcache();
            $this->type  = 'memcache';
            $this->db    = $rcube->get_memcache();
            $this->debug = $rcube->config->get('memcache_debug');
        }
        else if ($type == 'apc') {
            $this->type = 'apc';
            $this->db   = function_exists('apc_exists'); // APC 3.1.4 required
            $this->type  = 'apc';
            $this->db    = function_exists('apc_exists'); // APC 3.1.4 required
            $this->debug = $rcube->config->get('apc_debug');
        }
        else {
            $this->type  = 'db';
            $this->db    = $rcube->get_dbh();
            $this->table = $this->db->table_name('cache_shared');
            $this->table = $this->db->table_name('cache_shared', true);
        }

        // convert ttl string to seconds
@@ -193,12 +199,23 @@
    {
        if ($this->type == 'db' && $this->db && $this->ttl) {
            $this->db->query(
                "DELETE FROM " . $this->table
                . " WHERE cache_key LIKE ?"
                . " AND " . $this->db->unixtimestamp('created') . " < ?",
                $this->prefix . '.%',
                time() - $this->ttl);
                "DELETE FROM {$this->table}"
                . " WHERE `cache_key` LIKE ?"
                . " AND `expires` < " . $this->db->now(),
                $this->prefix . '.%');
        }
    }


    /**
     * Remove expired records of all caches
     */
    static function gc()
    {
        $rcube = rcube::get_instance();
        $db    = $rcube->get_dbh();

        $db->query("DELETE FROM " . $db->table_name('cache_shared', true) . " WHERE `expires` < " . $db->now());
    }


@@ -244,10 +261,20 @@

        if ($this->type != 'db') {
            if ($this->type == 'memcache') {
                $data = $this->db->get($this->ckey($key));
                $ckey = $this->ckey($key);
                $data = $this->db->get($ckey);

                if ($this->debug) {
                    $this->debug('get', $ckey, $data);
                }
            }
            else if ($this->type == 'apc') {
                $data = apc_fetch($this->ckey($key));
                $ckey = $this->ckey($key);
                $data = apc_fetch($ckey);

                if ($this->debug) {
                    $this->debug('fetch', $ckey, $data);
                }
            }

            if ($data) {
@@ -267,12 +294,12 @@
        }
        else {
            $sql_result = $this->db->limitquery(
                "SELECT data, cache_key".
                " FROM " . $this->table .
                " WHERE cache_key = ?".
                "SELECT `data`, `cache_key`".
                " FROM {$this->table}" .
                " WHERE `cache_key` = ?".
                // for better performance we allow more records for one key
                // get the newer one
                " ORDER BY created DESC",
                " ORDER BY `created` DESC",
                0, 1, $this->prefix . '.' . $key);

            if ($sql_arr = $this->db->fetch_assoc($sql_result)) {
@@ -301,13 +328,19 @@
     * Writes single cache record into DB.
     *
     * @param string $key  Cache key name
     * @param mxied  $data Serialized cache data 
     * @param mixed  $data Serialized cache data
     *
     * @param boolean True on success, False on failure
     */
    private function write_record($key, $data)
    {
        if (!$this->db) {
            return false;
        }

        // don't attempt to write too big data sets
        if (strlen($data) > $this->max_packet_size()) {
            trigger_error("rcube_cache: max_packet_size ($this->max_packet) exceeded for key $key. Tried to write " . strlen($data) . " bytes", E_USER_WARNING);
            return false;
        }

@@ -320,16 +353,18 @@

        // Remove NULL rows (here we don't need to check if the record exist)
        if ($data == 'N;') {
            $this->db->query("DELETE FROM " . $this->table . " WHERE cache_key = ?", $key);
            $this->db->query("DELETE FROM {$this->table} WHERE `cache_key` = ?", $key);
            return true;
        }

        // update existing cache record
        if ($key_exists) {
            $result = $this->db->query(
                "UPDATE " . $this->table .
                " SET created = " . $this->db->now() . ", data = ?" .
                " WHERE cache_key = ?",
                "UPDATE {$this->table}" .
                " SET `created` = " . $this->db->now() .
                    ", `expires` = " . ($this->ttl ? $this->db->now($this->ttl) : 'NULL') .
                    ", `data` = ?".
                " WHERE `cache_key` = ?",
                $data, $key);
        }
        // add new cache record
@@ -337,9 +372,9 @@
            // for better performance we allow more records for one key
            // so, no need to check if record exist (see rcube_cache::read_record())
            $result = $this->db->query(
                "INSERT INTO ".$this->table.
                " (created, cache_key, data)".
                " VALUES (".$this->db->now().", ?, ?)",
                "INSERT INTO {$this->table}".
                " (`created`, `expires`, `cache_key`, `data`)".
                " VALUES (".$this->db->now().", " . ($this->ttl ? $this->db->now($this->ttl) : 'NULL') . ", ?, ?)",
                $key, $data);
        }

@@ -388,15 +423,15 @@

        // Remove all keys (in specified cache)
        if ($key === null) {
            $where = " WHERE cache_key LIKE " . $this->db->quote($this->prefix.'.%');
            $where = " WHERE `cache_key` LIKE " . $this->db->quote($this->prefix.'.%');
        }
        // Remove keys by name prefix
        else if ($prefix_mode) {
            $where = " WHERE cache_key LIKE " . $this->db->quote($this->prefix.'.'.$key.'%');
            $where = " WHERE `cache_key` LIKE " . $this->db->quote($this->prefix.'.'.$key.'%');
        }
        // Remove one key by name
        else {
            $where = " WHERE cache_key = " . $this->db->quote($this->prefix.'.'.$key);
            $where = " WHERE `cache_key` = " . $this->db->quote($this->prefix.'.'.$key);
        }

        $this->db->query("DELETE FROM " . $this->table . $where);
@@ -419,12 +454,19 @@
            if (!$result) {
                $result = $this->db->set($key, $data, MEMCACHE_COMPRESSED, $this->ttl);
            }
            if ($this->debug) {
                $this->debug('set', $key, $data, $result);
            }
        }
        else if ($this->type == 'apc') {
            if (apc_exists($key)) {
                apc_delete($key);
            }
            $result = apc_store($key, $data, $this->ttl);

            if ($this->debug) {
                $this->debug('store', $key, $data, $result);
            }
        }

        // Update index
@@ -448,11 +490,21 @@
    private function delete_record($key, $index=true)
    {
        if ($this->type == 'memcache') {
            $ckey = $this->ckey($key);
            // #1488592: use 2nd argument
            $this->db->delete($this->ckey($key), 0);
            $result = $this->db->delete($ckey, 0);

            if ($this->debug) {
                $this->debug('delete', $ckey, null, $result);
            }
        }
        else {
            apc_delete($this->ckey($key));
            $ckey   = $this->ckey($key);
            $result = apc_delete($ckey);

            if ($this->debug) {
                $this->debug('delete', $ckey, null, $result);
            }
        }

        if ($index) {
@@ -509,9 +561,17 @@

        if ($this->type == 'memcache') {
            $data = $this->db->get($index_key);

            if ($this->debug) {
                $this->debug('get', $index_key, $data);
            }
        }
        else if ($this->type == 'apc') {
            $data = apc_fetch($index_key);

            if ($this->debug) {
                $this->debug('fetch', $index_key, $data);
            }
        }

        $this->index = $data ? unserialize($data) : array();
@@ -565,4 +625,55 @@

        return $this->packed ? @unserialize($data) : $data;
    }

    /**
     * Determine the maximum size for cache data to be written
     */
    private function max_packet_size()
    {
        if ($this->max_packet < 0) {
            $this->max_packet = 2097152; // default/max is 2 MB

            if ($this->type == 'db') {
                if ($value = $this->db->get_variable('max_allowed_packet', $this->max_packet)) {
                    $this->max_packet = $value;
                }
                $this->max_packet -= 2000;
            }
            else if ($this->type == 'memcache') {
                $stats = $this->db->getStats();
                $remaining = $stats['limit_maxbytes'] - $stats['bytes'];
                $this->max_packet = min($remaining / 5, $this->max_packet);
            }
            else if ($this->type == 'apc' && function_exists('apc_sma_info')) {
                $stats = apc_sma_info();
                $this->max_packet = min($stats['avail_mem'] / 5, $this->max_packet);
            }
        }

        return $this->max_packet;
    }

    /**
     * Write memcache/apc debug info to the log
     */
    private function debug($type, $key, $data = null, $result = null)
    {
        $line = strtoupper($type) . ' ' . $key;

        if ($data !== null) {
            $line .= ' ' . ($this->packed ? $data : serialize($data));

            if (($len = strlen($line)) > self::DEBUG_LINE_LENGTH) {
                $diff = $len - self::DEBUG_LINE_LENGTH;
                $line = substr($line, 0, self::DEBUG_LINE_LENGTH) . "... [truncated $diff bytes]";
            }
        }

        if ($result !== null) {
            $line .= ' [' . ($result ? 'TRUE' : 'FALSE') . ']';
        }

        rcube::write_log($this->type, $line);
    }
}