Newer
Older
<?php
Dries Buytaert
committed
/**
* @file
Alex Pott
committed
* Contains \Drupal\dblog\Tests\DbLogTest.
Dries Buytaert
committed
*/
namespace Drupal\dblog\Tests;
catch
committed
Alex Pott
committed
use Drupal\dblog\Controller\DbLogController;
catch
committed
use Drupal\simpletest\WebTestBase;
use SimpleXMLElement;
catch
committed
/**
* Tests logging messages to the database.
*/
Alex Pott
committed
class DbLogTest extends WebTestBase {
/**
* Modules to enable.
*
* @var array
*/
public static $modules = array('dblog', 'node', 'forum', 'help');
/**
* A user with some relevent administrative permissions.
*
* @var object
*/
protected $big_user;
/**
* A user without any permissions.
*
* @var object
*/
protected $any_user;
Angie Byron
committed
public static function getInfo() {
return array(
Alex Pott
committed
'name' => 'DbLog functionality',
'description' => 'Generate events and verify dblog entries; verify user access to log reports based on persmissions.',
Alex Pott
committed
'group' => 'DbLog',
);
}
function setUp() {
parent::setUp();
// Create users with specific permissions.
$this->big_user = $this->drupalCreateUser(array('administer site configuration', 'access administration pages', 'access site reports', 'administer users'));
$this->any_user = $this->drupalCreateUser(array());
}
/**
Jennifer Hodgdon
committed
* Tests Database Logging module functionality through interfaces.
*
* First logs in users, then creates database log events, and finally tests
* Database Logging module functionality through both the admin and user
* interfaces.
*/
Alex Pott
committed
function testDbLog() {
// Login the admin user.
$this->drupalLogin($this->big_user);
$row_limit = 100;
$this->verifyRowLimit($row_limit);
$this->verifyCron($row_limit);
$this->verifyEvents();
$this->verifyReports();
// Login the regular user.
$this->drupalLogin($this->any_user);
$this->verifyReports(403);
}
/**
Jennifer Hodgdon
committed
* Verifies setting of the database log row limit.
*
Jennifer Hodgdon
committed
* @param int $row_limit
* The row limit.
*/
private function verifyRowLimit($row_limit) {
Jennifer Hodgdon
committed
// Change the database log row limit.
$edit = array();
$edit['dblog_row_limit'] = $row_limit;
$this->drupalPost('admin/config/development/logging', $edit, t('Save configuration'));
$this->assertResponse(200);
Dries Buytaert
committed
// Check row limit variable.
Angie Byron
committed
$current_limit = config('dblog.settings')->get('row_limit');
$this->assertTrue($current_limit == $row_limit, format_string('[Cache] Row limit variable of @count equals row limit of @limit', array('@count' => $current_limit, '@limit' => $row_limit)));
}
/**
Jennifer Hodgdon
committed
* Verifies that cron correctly applies the database log row limit.
*
Jennifer Hodgdon
committed
* @param int $row_limit
* The row limit.
*/
private function verifyCron($row_limit) {
// Generate additional log entries.
$this->generateLogEntries($row_limit + 10);
Jennifer Hodgdon
committed
// Verify that the database log row count exceeds the row limit.
Dries Buytaert
committed
$count = db_query('SELECT COUNT(wid) FROM {watchdog}')->fetchField();
$this->assertTrue($count > $row_limit, format_string('Dblog row count of @count exceeds row limit of @limit', array('@count' => $count, '@limit' => $row_limit)));
Jennifer Hodgdon
committed
// Run a cron job.
Dries Buytaert
committed
$this->cronRun();
Jennifer Hodgdon
committed
// Verify that the database log row count equals the row limit plus one
// because cron adds a record after it runs.
Dries Buytaert
committed
$count = db_query('SELECT COUNT(wid) FROM {watchdog}')->fetchField();
$this->assertTrue($count == $row_limit + 1, format_string('Dblog row count of @count equals row limit of @limit plus one', array('@count' => $count, '@limit' => $row_limit)));
}
/**
Jennifer Hodgdon
committed
* Generates a number of random database log events.
*
Jennifer Hodgdon
committed
* @param int $count
* Number of watchdog entries to generate.
* @param string $type
* (optional) The type of watchdog entry. Defaults to 'custom'.
Jennifer Hodgdon
committed
* @param int $severity
* (optional) The severity of the watchdog entry. Defaults to WATCHDOG_NOTICE.
*/
Angie Byron
committed
private function generateLogEntries($count, $type = 'custom', $severity = WATCHDOG_NOTICE) {
global $base_root;
// Prepare the fields to be logged
$log = array(
'type' => $type,
'message' => 'Log entry added to test the dblog row limit.',
'variables' => array(),
'severity' => $severity,
'link' => NULL,
'user' => $this->big_user,
catch
committed
'uid' => isset($this->big_user->uid) ? $this->big_user->uid : 0,
'request_uri' => $base_root . request_uri(),
Dries Buytaert
committed
'referer' => $_SERVER['HTTP_REFERER'],
'ip' => '127.0.0.1',
Dries Buytaert
committed
'timestamp' => REQUEST_TIME,
);
Dries Buytaert
committed
$message = 'Log entry added to test the dblog row limit. Entry #';
for ($i = 0; $i < $count; $i++) {
Dries Buytaert
committed
$log['message'] = $message . $i;
dblog_watchdog($log);
}
}
/**
Jennifer Hodgdon
committed
* Confirms that database log reports are displayed at the correct paths.
*
Jennifer Hodgdon
committed
* @param int $response
* (optional) HTTP response code. Defaults to 200.
*/
private function verifyReports($response = 200) {
$quote = ''';
Jennifer Hodgdon
committed
// View the database log help page.
$this->drupalGet('admin/help/dblog');
$this->assertResponse($response);
if ($response == 200) {
$this->assertText(t('Database Logging'), 'DBLog help was displayed');
}
Jennifer Hodgdon
committed
// View the database log report page.
$this->drupalGet('admin/reports/dblog');
$this->assertResponse($response);
if ($response == 200) {
$this->assertText(t('Recent log messages'), 'DBLog report was displayed');
}
Jennifer Hodgdon
committed
// View the database log page-not-found report page.
$this->drupalGet('admin/reports/page-not-found');
$this->assertResponse($response);
if ($response == 200) {
$this->assertText(t('Top ' . $quote . 'page not found' . $quote . ' errors'), 'DBLog page-not-found report was displayed');
}
Jennifer Hodgdon
committed
// View the database log access-denied report page.
$this->drupalGet('admin/reports/access-denied');
$this->assertResponse($response);
if ($response == 200) {
$this->assertText(t('Top ' . $quote . 'access denied' . $quote . ' errors'), 'DBLog access-denied report was displayed');
}
Jennifer Hodgdon
committed
// View the database log event page.
$this->drupalGet('admin/reports/event/1');
$this->assertResponse($response);
if ($response == 200) {
$this->assertText(t('Details'), 'DBLog event node was displayed');
}
}
/**
Jennifer Hodgdon
committed
* Generates and then verifies various types of events.
*/
private function verifyEvents() {
// Invoke events.
$this->doUser();
Angie Byron
committed
$this->drupalCreateContentType(array('type' => 'article', 'name' => t('Article')));
$this->drupalCreateContentType(array('type' => 'page', 'name' => t('Basic page')));
$this->doNode('article');
$this->doNode('page');
$this->doNode('forum');
Angie Byron
committed
// When a user account is canceled, any content they created remains but the
// uid = 0. Records in the watchdog table related to that user have the uid
// set to zero.
}
/**
Jennifer Hodgdon
committed
* Generates and then verifies some user events.
*/
private function doUser() {
// Set user variables.
Dries Buytaert
committed
$name = $this->randomName();
$pass = user_password();
Jennifer Hodgdon
committed
// Add a user using the form to generate an add user event (which is not
// triggered by drupalCreateUser).
$edit = array();
$edit['name'] = $name;
$edit['mail'] = $name . '@example.com';
$edit['pass[pass1]'] = $pass;
$edit['pass[pass2]'] = $pass;
$edit['status'] = 1;
Dries Buytaert
committed
$this->drupalPost('admin/people/create', $edit, t('Create new account'));
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Retrieve the user object.
Angie Byron
committed
$user = user_load_by_name($name);
$this->assertTrue($user != NULL, format_string('User @name was loaded', array('@name' => $name)));
Jennifer Hodgdon
committed
// pass_raw property is needed by drupalLogin.
$user->pass_raw = $pass;
// Login user.
$this->drupalLogin($user);
// Logout user.
$this->drupalLogout();
Jennifer Hodgdon
committed
// Fetch the row IDs in watchdog that relate to the user.
Dries Buytaert
committed
$result = db_query('SELECT wid FROM {watchdog} WHERE uid = :uid', array(':uid' => $user->uid));
foreach ($result as $row) {
Dries Buytaert
committed
$ids[] = $row->wid;
}
$count_before = (isset($ids)) ? count($ids) : 0;
$this->assertTrue($count_before > 0, format_string('DBLog contains @count records for @name', array('@count' => $count_before, '@name' => $user->name)));
Angie Byron
committed
// Login the admin user.
$this->drupalLogin($this->big_user);
Jennifer Hodgdon
committed
// Delete the user created at the start of this test.
Angie Byron
committed
// We need to POST here to invoke batch_process() in the internal browser.
$this->drupalPost('user/' . $user->uid . '/cancel', array('user_cancel_method' => 'user_cancel_reassign'), t('Cancel account'));
Jennifer Hodgdon
committed
// View the database log report.
$this->drupalGet('admin/reports/dblog');
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Verify that the expected events were recorded.
// Add user.
Jennifer Hodgdon
committed
// Default display includes name and email address; if too long, the email
// address is replaced by three periods.
$this->assertLogMessage(t('New user: %name %email.', array('%name' => $name, '%email' => '<' . $user->mail . '>')), 'DBLog event was recorded: [add user]');
// Login user.
$this->assertLogMessage(t('Session opened for %name.', array('%name' => $name)), 'DBLog event was recorded: [login user]');
// Logout user.
$this->assertLogMessage(t('Session closed for %name.', array('%name' => $name)), 'DBLog event was recorded: [logout user]');
// Delete user.
Dries Buytaert
committed
$message = t('Deleted user: %name %email.', array('%name' => $name, '%email' => '<' . $user->mail . '>'));
$message_text = truncate_utf8(filter_xss($message, array()), 56, TRUE, TRUE);
Jennifer Hodgdon
committed
// Verify that the full message displays on the details page.
Dries Buytaert
committed
$link = FALSE;
if ($links = $this->xpath('//a[text()="' . html_entity_decode($message_text) . '"]')) {
// Found link with the message text.
$links = array_shift($links);
foreach ($links->attributes() as $attr => $value) {
if ($attr == 'href') {
// Extract link to details page.
$link = drupal_substr($value, strpos($value, 'admin/reports/event/'));
$this->drupalGet($link);
// Check for full message text on the details page.
$this->assertRaw($message, 'DBLog event details was found: [delete user]');
Dries Buytaert
committed
break;
}
}
}
$this->assertTrue($link, 'DBLog event was recorded: [delete user]');
Dries Buytaert
committed
// Visit random URL (to generate page not found event).
$not_found_url = $this->randomName(60);
$this->drupalGet($not_found_url);
$this->assertResponse(404);
Jennifer Hodgdon
committed
// View the database log page-not-found report page.
Dries Buytaert
committed
$this->drupalGet('admin/reports/page-not-found');
$this->assertResponse(200);
Angie Byron
committed
// Check that full-length URL displayed.
$this->assertText($not_found_url, 'DBLog event was recorded: [page not found]');
}
/**
Jennifer Hodgdon
committed
* Generates and then verifies some node events.
*
Jennifer Hodgdon
committed
* @param string $type
* A node type (e.g., 'article', 'page' or 'forum').
*/
private function doNode($type) {
// Create user.
$perm = array('create ' . $type . ' content', 'edit own ' . $type . ' content', 'delete own ' . $type . ' content');
$user = $this->drupalCreateUser($perm);
// Login user.
$this->drupalLogin($user);
Jennifer Hodgdon
committed
// Create a node using the form in order to generate an add content event
// (which is not triggered by drupalCreateNode).
$edit = $this->getContent($type);
$langcode = LANGUAGE_NOT_SPECIFIED;
$title = $edit["title"];
$this->drupalPost('node/add/' . $type, $edit, t('Save'));
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Retrieve the node object.
$node = $this->drupalGetNodeByTitle($title);
$this->assertTrue($node != NULL, format_string('Node @title was loaded', array('@title' => $title)));
Jennifer Hodgdon
committed
// Edit the node.
$edit = $this->getContentUpdate($type);
$this->drupalPost('node/' . $node->nid . '/edit', $edit, t('Save'));
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Delete the node.
$this->drupalPost('node/' . $node->nid . '/delete', array(), t('Delete'));
$this->assertResponse(200);
Jennifer Hodgdon
committed
// View the node (to generate page not found event).
$this->drupalGet('node/' . $node->nid);
$this->assertResponse(404);
Jennifer Hodgdon
committed
// View the database log report (to generate access denied event).
$this->drupalGet('admin/reports/dblog');
$this->assertResponse(403);
// Login the admin user.
$this->drupalLogin($this->big_user);
Jennifer Hodgdon
committed
// View the database log report.
$this->drupalGet('admin/reports/dblog');
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Verify that node events were recorded.
// Was node content added?
$this->assertLogMessage(t('@type: added %title.', array('@type' => $type, '%title' => $title)), 'DBLog event was recorded: [content added]');
Jennifer Hodgdon
committed
// Was node content updated?
$this->assertLogMessage(t('@type: updated %title.', array('@type' => $type, '%title' => $title)), 'DBLog event was recorded: [content updated]');
Jennifer Hodgdon
committed
// Was node content deleted?
$this->assertLogMessage(t('@type: deleted %title.', array('@type' => $type, '%title' => $title)), 'DBLog event was recorded: [content deleted]');
Jennifer Hodgdon
committed
// View the database log access-denied report page.
$this->drupalGet('admin/reports/access-denied');
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Verify that the 'access denied' event was recorded.
$this->assertText(t('admin/reports/dblog'), 'DBLog event was recorded: [access denied]');
Jennifer Hodgdon
committed
// View the database log page-not-found report page.
$this->drupalGet('admin/reports/page-not-found');
$this->assertResponse(200);
Jennifer Hodgdon
committed
// Verify that the 'page not found' event was recorded.
$this->assertText(t('node/@nid', array('@nid' => $node->nid)), 'DBLog event was recorded: [page not found]');
}
/**
Jennifer Hodgdon
committed
* Creates random content based on node content type.
*
Jennifer Hodgdon
committed
* @param string $type
* Node content type (e.g., 'article').
*
* @return array
* Random content needed by various node types.
*/
private function getContent($type) {
$langcode = LANGUAGE_NOT_SPECIFIED;
switch ($type) {
case 'forum':
$content = array(
"title" => $this->randomName(8),
"taxonomy_forums[$langcode]" => array(1),
"body[$langcode][0][value]" => $this->randomName(32),
);
Alex Pott
committed
break;
default:
$content = array(
"title" => $this->randomName(8),
Angie Byron
committed
"body[$langcode][0][value]" => $this->randomName(32),
);
Alex Pott
committed
break;
}
return $content;
}
/**
Jennifer Hodgdon
committed
* Creates random content as an update based on node content type.
*
* @param string $type
* Node content type (e.g., 'article').
*
Jennifer Hodgdon
committed
* @return array
* Random content needed by various node types.
*/
private function getContentUpdate($type) {
$langcode = LANGUAGE_NOT_SPECIFIED;
$content = array(
"body[$langcode][0][value]" => $this->randomName(32),
);
return $content;
}
Jennifer Hodgdon
committed
* Tests the addition and clearing of log events through the admin interface.
*
* Logs in the admin user, creates a database log event, and tests the
* functionality of clearing the database log through the admin interface.
*/
protected function testDBLogAddAndClear() {
global $base_root;
Jennifer Hodgdon
committed
// Get a count of how many watchdog entries already exist.
$count = db_query('SELECT COUNT(*) FROM {watchdog}')->fetchField();
$log = array(
'type' => 'custom',
'message' => 'Log entry added to test the doClearTest clear down.',
'variables' => array(),
Angie Byron
committed
'severity' => WATCHDOG_NOTICE,
'link' => NULL,
'user' => $this->big_user,
catch
committed
'uid' => isset($this->big_user->uid) ? $this->big_user->uid : 0,
'request_uri' => $base_root . request_uri(),
'referer' => $_SERVER['HTTP_REFERER'],
'ip' => '127.0.0.1',
'timestamp' => REQUEST_TIME,
);
// Add a watchdog entry.
dblog_watchdog($log);
Jennifer Hodgdon
committed
// Make sure the table count has actually been incremented.
$this->assertEqual($count + 1, db_query('SELECT COUNT(*) FROM {watchdog}')->fetchField(), format_string('dblog_watchdog() added an entry to the dblog :count', array(':count' => $count)));
// Login the admin user.
$this->drupalLogin($this->big_user);
Jennifer Hodgdon
committed
// Post in order to clear the database table.
$this->drupalPost('admin/reports/dblog', array(), t('Clear log messages'));
Jennifer Hodgdon
committed
// Count the rows in watchdog that previously related to the deleted user.
$count = db_query('SELECT COUNT(*) FROM {watchdog}')->fetchField();
$this->assertEqual($count, 0, format_string('DBLog contains :count records after a clear.', array(':count' => $count)));
Jennifer Hodgdon
committed
* Tests the database log filter functionality at admin/reports/dblog.
*/
protected function testFilter() {
$this->drupalLogin($this->big_user);
Jennifer Hodgdon
committed
// Clear the log to ensure that only generated entries will be found.
db_delete('watchdog')->execute();
Jennifer Hodgdon
committed
// Generate 9 random watchdog entries.
$type_names = array();
$types = array();
for ($i = 0; $i < 3; $i++) {
$type_names[] = $type_name = $this->randomName();
Angie Byron
committed
$severity = WATCHDOG_EMERGENCY;
for ($j = 0; $j < 3; $j++) {
$types[] = $type = array(
'count' => $j + 1,
'type' => $type_name,
'severity' => $severity++,
);
$this->generateLogEntries($type['count'], $type['type'], $type['severity']);
}
}
Jennifer Hodgdon
committed
// View the database log page.
$this->drupalGet('admin/reports/dblog');
Jennifer Hodgdon
committed
// Confirm that all the entries are displayed.
$count = $this->getTypeCount($types);
foreach ($types as $key => $type) {
$this->assertEqual($count[$key], $type['count'], 'Count matched');
}
// Filter by each type and confirm that entries with various severities are
// displayed.
foreach ($type_names as $type_name) {
$edit = array(
'type[]' => array($type_name),
);
$this->drupalPost(NULL, $edit, t('Filter'));
// Count the number of entries of this type.
$type_count = 0;
foreach ($types as $type) {
if ($type['type'] == $type_name) {
$type_count += $type['count'];
}
}
$count = $this->getTypeCount($types);
$this->assertEqual(array_sum($count), $type_count, 'Count matched');
}
Jennifer Hodgdon
committed
// Set the filter to match each of the two filter-type attributes and
// confirm the correct number of entries are displayed.
foreach ($types as $key => $type) {
$edit = array(
'type[]' => array($type['type']),
'severity[]' => array($type['severity']),
);
$this->drupalPost(NULL, $edit, t('Filter'));
$count = $this->getTypeCount($types);
$this->assertEqual(array_sum($count), $type['count'], 'Count matched');
}
Angie Byron
committed
$this->drupalGet('admin/reports/dblog', array('query' => array('order' => 'Type')));
$this->assertResponse(200);
$this->assertText(t('Operations'), 'Operations text found');
Angie Byron
committed
// Clear all logs and make sure the confirmation message is found.
$this->drupalPost('admin/reports/dblog', array(), t('Clear log messages'));
$this->assertText(t('Database log cleared.'), 'Confirmation message found');
}
/**
Jennifer Hodgdon
committed
* Gets the database log event information from the browser page.
Jennifer Hodgdon
committed
* @return array
* List of log events where each event is an array with following keys:
* - severity: (int) A database log severity constant.
* - type: (string) The type of database log event.
* - message: (string) The message for this database log event.
* - user: (string) The user associated with this database log event.
*/
protected function getLogEntries() {
$entries = array();
if ($table = $this->xpath('.//table[@id="admin-dblog"]')) {
$table = array_shift($table);
foreach ($table->tbody->tr as $row) {
$entries[] = array(
'severity' => $this->getSeverityConstant($row['class']),
'type' => $this->asText($row->td[1]),
'message' => $this->asText($row->td[3]),
'user' => $this->asText($row->td[4]),
);
}
}
return $entries;
}
/**
Jennifer Hodgdon
committed
* Gets the count of database log entries by database log event type.
Jennifer Hodgdon
committed
* @param array $types
* The type information to compare against.
Jennifer Hodgdon
committed
*
* @return array
* The count of each type keyed by the key of the $types array.
*/
protected function getTypeCount(array $types) {
$entries = $this->getLogEntries();
$count = array_fill(0, count($types), 0);
foreach ($entries as $entry) {
foreach ($types as $key => $type) {
if ($entry['type'] == $type['type'] && $entry['severity'] == $type['severity']) {
$count[$key]++;
break;
}
}
}
return $count;
}
/**
Jennifer Hodgdon
committed
* Gets the watchdog severity constant corresponding to the CSS class.
Jennifer Hodgdon
committed
* @param string $class
* CSS class attribute.
Jennifer Hodgdon
committed
*
* @return int|null
* The watchdog severity constant or NULL if not found.
*/
protected function getSeverityConstant($class) {
Alex Pott
committed
$map = array_flip(DbLogController::getLogLevelClassMap());
// Find the class that contains the severity.
$classes = explode(' ', $class);
foreach ($classes as $class) {
if (isset($map[$class])) {
return $map[$class];
}
}
return NULL;
}
/**
Jennifer Hodgdon
committed
* Extracts the text contained by the XHTML element.
Jennifer Hodgdon
committed
* @param SimpleXMLElement $element
* Element to extract text from.
Jennifer Hodgdon
committed
*
* @return string
* Extracted text.
*/
protected function asText(SimpleXMLElement $element) {
if (!is_object($element)) {
return $this->fail('The element is not an element.');
}
return trim(html_entity_decode(strip_tags($element->asXML())));
}
Angie Byron
committed
/**
Jennifer Hodgdon
committed
* Confirms that a log message appears on the database log overview screen.
Angie Byron
committed
*
Jennifer Hodgdon
committed
* This function should only be used for the admin/reports/dblog page, because
* it checks for the message link text truncated to 56 characters. Other log
* pages have no detail links so they contain the full message text.
Dries Buytaert
committed
*
Jennifer Hodgdon
committed
* @param string $log_message
* The database log message to check.
* @param string $message
Angie Byron
committed
* The message to pass to simpletest.
*/
protected function assertLogMessage($log_message, $message) {
Dries Buytaert
committed
$message_text = truncate_utf8(filter_xss($log_message, array()), 56, TRUE, TRUE);
Jennifer Hodgdon
committed
// After filter_xss(), HTML entities should be converted to their character
// equivalents because assertLink() uses this string in xpath() to query the
// Document Object Model (DOM).
Dries Buytaert
committed
$this->assertLink(html_entity_decode($message_text), 0, $message);
Angie Byron
committed
}