X-Git-Url: https://git.whamcloud.com/?a=blobdiff_plain;f=libcfs%2Flibcfs%2Ftracefile.c;h=d455e42a6d66d8c91e9639cd54c1b8fac4ab7c1d;hb=0c00b4a10c951a7a5cba075a3e8181662dab50b8;hp=da566c09079439fb48a5ded35460a2bc274108fb;hpb=58fac30e99f428438a12c6a3f4f6989ef0ec5c22;p=fs%2Flustre-release.git diff --git a/libcfs/libcfs/tracefile.c b/libcfs/libcfs/tracefile.c index da566c0..d455e42 100644 --- a/libcfs/libcfs/tracefile.c +++ b/libcfs/libcfs/tracefile.c @@ -1,6 +1,4 @@ -/* -*- mode: c; c-basic-offset: 8; indent-tabs-mode: nil; -*- - * vim:expandtab:shiftwidth=8:tabstop=8: - * +/* * GPL HEADER START * * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. @@ -26,8 +24,10 @@ * GPL HEADER END */ /* - * Copyright 2008 Sun Microsystems, Inc. All rights reserved + * Copyright (c) 2008, 2010, Oracle and/or its affiliates. All rights reserved. * Use is subject to license terms. + * + * Copyright (c) 2012, Intel Corporation. */ /* * This file is part of Lustre, http://www.lustre.org/ @@ -52,7 +52,7 @@ union cfs_trace_data_union (*cfs_trace_data[TCD_MAX_TYPES])[CFS_NR_CPUS] __cache char cfs_tracefile[TRACEFILE_NAME_SIZE]; long long cfs_tracefile_size = CFS_TRACEFILE_SIZE; static struct tracefiled_ctl trace_tctl; -cfs_semaphore_t cfs_trace_thread_sem; +struct mutex cfs_trace_thread_mutex; static int thread_running = 0; cfs_atomic_t cfs_tage_allocated = CFS_ATOMIC_INIT(0); @@ -71,6 +71,10 @@ static struct cfs_trace_page *cfs_tage_alloc(int gfp) cfs_page_t *page; struct cfs_trace_page *tage; + /* My caller is trying to free memory */ + if (!cfs_in_interrupt() && cfs_memory_pressure_get()) + return NULL; + /* * Don't spam console with allocation failures: they will be reported * by upper layer anyway. @@ -145,20 +149,21 @@ cfs_trace_get_tage_try(struct cfs_trace_cpu_data *tcd, unsigned long len) } if (tcd->tcd_cur_pages < tcd->tcd_max_pages) { - if (tcd->tcd_cur_stock_pages > 0) { - tage = cfs_tage_from_list(tcd->tcd_stock_pages.prev); - -- tcd->tcd_cur_stock_pages; - cfs_list_del_init(&tage->linkage); - } else { - tage = cfs_tage_alloc(CFS_ALLOC_ATOMIC); - if (tage == NULL) { - if (printk_ratelimit()) - printk(CFS_KERN_WARNING - "cannot allocate a tage (%ld)\n", - tcd->tcd_cur_pages); - return NULL; - } - } + if (tcd->tcd_cur_stock_pages > 0) { + tage = cfs_tage_from_list(tcd->tcd_stock_pages.prev); + --tcd->tcd_cur_stock_pages; + cfs_list_del_init(&tage->linkage); + } else { + tage = cfs_tage_alloc(CFS_ALLOC_ATOMIC); + if (unlikely(tage == NULL)) { + if ((!cfs_memory_pressure_get() || + cfs_in_interrupt()) && printk_ratelimit()) + printk(CFS_KERN_WARNING + "cannot allocate a tage (%ld)\n", + tcd->tcd_cur_pages); + return NULL; + } + } tage->used = 0; tage->cpu = cfs_smp_processor_id(); @@ -196,7 +201,7 @@ static void cfs_tcd_shrink(struct cfs_trace_cpu_data *tcd) pgcount + 1, tcd->tcd_cur_pages); CFS_INIT_LIST_HEAD(&pc.pc_pages); - cfs_spin_lock_init(&pc.pc_lock); + spin_lock_init(&pc.pc_lock); cfs_list_for_each_entry_safe_typed(tage, tmp, &tcd->tcd_pages, struct cfs_trace_page, linkage) { @@ -239,8 +244,21 @@ static struct cfs_trace_page *cfs_trace_get_tage(struct cfs_trace_cpu_data *tcd, return tage; } -int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, - const char *file, const char *fn, const int line, +int libcfs_debug_msg(struct libcfs_debug_msg_data *msgdata, + const char *format, ...) +{ + va_list args; + int rc; + + va_start(args, format); + rc = libcfs_debug_vmsg2(msgdata, format, args, NULL); + va_end(args); + + return rc; +} +EXPORT_SYMBOL(libcfs_debug_msg); + +int libcfs_debug_vmsg2(struct libcfs_debug_msg_data *msgdata, const char *format1, va_list args, const char *format2, ...) { @@ -257,6 +275,9 @@ int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, int depth; int i; int remain; + int mask = msgdata->msg_mask; + char *file = (char *)msgdata->msg_file; + cfs_debug_limit_state_t *cdls = msgdata->msg_cdls; if (strchr(file, '/')) file = strrchr(file, '/') + 1; @@ -266,7 +287,7 @@ int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, /* cfs_trace_get_tcd() grabs a lock, which disables preemption and * pins us to a particular CPU. This avoids an smp_processor_id() * warning on Linux when debugging is enabled. */ - cfs_set_ptldebug_header(&header, subsys, mask, line, CDEBUG_STACK()); + cfs_set_ptldebug_header(&header, msgdata, CDEBUG_STACK()); if (tcd == NULL) /* arch may not log in IRQ context */ goto console; @@ -282,8 +303,8 @@ int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, depth = __current_nesting_level(); known_size = strlen(file) + 1 + depth; - if (fn) - known_size += strlen(fn) + 1; + if (msgdata->msg_fn) + known_size += strlen(msgdata->msg_fn) + 1; if (libcfs_debug_binary) known_size += sizeof(header); @@ -309,7 +330,7 @@ int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, max_nob = CFS_PAGE_SIZE - tage->used - known_size; if (max_nob <= 0) { - printk(CFS_KERN_EMERG "negative max_nob: %i\n", + printk(CFS_KERN_EMERG "negative max_nob: %d\n", max_nob); mask |= D_ERROR; cfs_trace_put_tcd(tcd); @@ -340,8 +361,8 @@ int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, } if (*(string_buf+needed-1) != '\n') - printk(CFS_KERN_INFO "format at %s:%d:%s doesn't end in newline\n", - file, line, fn); + printk(CFS_KERN_INFO "format at %s:%d:%s doesn't end in " + "newline\n", file, msgdata->msg_line, msgdata->msg_fn); header.ph_len = known_size + needed; debug_buf = (char *)cfs_page_address(tage->page) + tage->used; @@ -362,10 +383,10 @@ int libcfs_debug_vmsg2(cfs_debug_limit_state_t *cdls, int subsys, int mask, tage->used += strlen(file) + 1; debug_buf += strlen(file) + 1; - if (fn) { - strcpy(debug_buf, fn); - tage->used += strlen(fn) + 1; - debug_buf += strlen(fn) + 1; + if (msgdata->msg_fn) { + strcpy(debug_buf, msgdata->msg_fn); + tage->used += strlen(msgdata->msg_fn) + 1; + debug_buf += strlen(msgdata->msg_fn) + 1; } __LASSERT(debug_buf == string_buf); @@ -412,7 +433,7 @@ console: if (tcd != NULL) { cfs_print_to_console(&header, mask, string_buf, needed, file, - fn); + msgdata->msg_fn); cfs_trace_put_tcd(tcd); } else { string_buf = cfs_trace_get_console_buffer(); @@ -429,12 +450,13 @@ console: remain = CFS_TRACE_CONSOLE_BUFFER_SIZE - needed; if (remain > 0) { va_start(ap, format2); - needed += vsnprintf(string_buf+needed, remain, format2, ap); + needed += vsnprintf(string_buf+needed, remain, + format2, ap); va_end(ap); } } cfs_print_to_console(&header, mask, - string_buf, needed, file, fn); + string_buf, needed, file, msgdata->msg_fn); cfs_trace_put_console_buffer(string_buf); } @@ -443,11 +465,12 @@ console: string_buf = cfs_trace_get_console_buffer(); needed = snprintf(string_buf, CFS_TRACE_CONSOLE_BUFFER_SIZE, - "Skipped %d previous similar message%s\n", - cdls->cdls_count, (cdls->cdls_count > 1) ? "s" : ""); + "Skipped %d previous similar message%s\n", + cdls->cdls_count, + (cdls->cdls_count > 1) ? "s" : ""); cfs_print_to_console(&header, mask, - string_buf, needed, file, fn); + string_buf, needed, file, msgdata->msg_fn); cfs_trace_put_console_buffer(string_buf); cdls->cdls_count = 0; @@ -458,19 +481,8 @@ console: EXPORT_SYMBOL(libcfs_debug_vmsg2); void -libcfs_assertion_failed(const char *expr, const char *file, - const char *func, const int line) -{ - libcfs_debug_msg(NULL, 0, D_EMERG, file, func, line, - "ASSERTION(%s) failed\n", expr); - /* cfs_enter_debugger(); */ - lbug_with_loc(file, func, line); -} -EXPORT_SYMBOL(libcfs_assertion_failed); - -void cfs_trace_assertion_failed(const char *str, - const char *fn, const char *file, int line) + struct libcfs_debug_msg_data *msgdata) { struct ptldebug_header hdr; @@ -478,10 +490,10 @@ cfs_trace_assertion_failed(const char *str, libcfs_catastrophe = 1; cfs_mb(); - cfs_set_ptldebug_header(&hdr, DEBUG_SUBSYSTEM, D_EMERG, line, - CDEBUG_STACK()); + cfs_set_ptldebug_header(&hdr, msgdata, CDEBUG_STACK()); - cfs_print_to_console(&hdr, D_EMERG, str, strlen(str), file, fn); + cfs_print_to_console(&hdr, D_EMERG, str, strlen(str), + msgdata->msg_file, msgdata->msg_fn); LIBCFS_PANIC("Lustre debug assertion failure\n"); @@ -514,10 +526,10 @@ panic_collect_pages(struct page_collection *pc) static void collect_pages_on_all_cpus(struct page_collection *pc) { - struct cfs_trace_cpu_data *tcd; - int i, cpu; + struct cfs_trace_cpu_data *tcd; + int i, cpu; - cfs_spin_lock(&pc->pc_lock); + spin_lock(&pc->pc_lock); cfs_for_each_possible_cpu(cpu) { cfs_tcd_for_each_type_lock(tcd, i, cpu) { cfs_list_splice_init(&tcd->tcd_pages, &pc->pc_pages); @@ -529,7 +541,7 @@ static void collect_pages_on_all_cpus(struct page_collection *pc) } } } - cfs_spin_unlock(&pc->pc_lock); + spin_unlock(&pc->pc_lock); } static void collect_pages(struct page_collection *pc) @@ -550,7 +562,7 @@ static void put_pages_back_on_all_cpus(struct page_collection *pc) struct cfs_trace_page *tmp; int i, cpu; - cfs_spin_lock(&pc->pc_lock); + spin_lock(&pc->pc_lock); cfs_for_each_possible_cpu(cpu) { cfs_tcd_for_each_type_lock(tcd, i, cpu) { cur_head = tcd->tcd_pages.next; @@ -570,7 +582,7 @@ static void put_pages_back_on_all_cpus(struct page_collection *pc) } } } - cfs_spin_unlock(&pc->pc_lock); + spin_unlock(&pc->pc_lock); } static void put_pages_back(struct page_collection *pc) @@ -584,12 +596,12 @@ static void put_pages_back(struct page_collection *pc) * if we have been steadily writing (and otherwise discarding) pages via the * debug daemon. */ static void put_pages_on_tcd_daemon_list(struct page_collection *pc, - struct cfs_trace_cpu_data *tcd) + struct cfs_trace_cpu_data *tcd) { - struct cfs_trace_page *tage; - struct cfs_trace_page *tmp; + struct cfs_trace_page *tage; + struct cfs_trace_page *tmp; - cfs_spin_lock(&pc->pc_lock); + spin_lock(&pc->pc_lock); cfs_list_for_each_entry_safe_typed(tage, tmp, &pc->pc_pages, struct cfs_trace_page, linkage) { @@ -614,7 +626,7 @@ static void put_pages_on_tcd_daemon_list(struct page_collection *pc, tcd->tcd_cur_daemon_pages--; } } - cfs_spin_unlock(&pc->pc_lock); + spin_unlock(&pc->pc_lock); } static void put_pages_on_daemon_list(struct page_collection *pc) @@ -630,11 +642,11 @@ static void put_pages_on_daemon_list(struct page_collection *pc) void cfs_trace_debug_print(void) { - struct page_collection pc; - struct cfs_trace_page *tage; - struct cfs_trace_page *tmp; + struct page_collection pc; + struct cfs_trace_page *tage; + struct cfs_trace_page *tmp; - cfs_spin_lock_init(&pc.pc_lock); + spin_lock_init(&pc.pc_lock); pc.pc_want_daemon_pages = 1; collect_pages(&pc); @@ -670,27 +682,26 @@ void cfs_trace_debug_print(void) int cfs_tracefile_dump_all_pages(char *filename) { - struct page_collection pc; - cfs_file_t *filp; - struct cfs_trace_page *tage; - struct cfs_trace_page *tmp; - int rc; - - CFS_DECL_MMSPACE; - - cfs_tracefile_write_lock(); - - filp = cfs_filp_open(filename, - O_CREAT|O_EXCL|O_WRONLY|O_LARGEFILE, 0600, &rc); - if (!filp) { - if (rc != -EEXIST) - printk(CFS_KERN_ERR - "LustreError: can't open %s for dump: rc %d\n", - filename, rc); - goto out; - } - - cfs_spin_lock_init(&pc.pc_lock); + struct page_collection pc; + struct file *filp; + struct cfs_trace_page *tage; + struct cfs_trace_page *tmp; + int rc; + + CFS_DECL_MMSPACE; + + cfs_tracefile_write_lock(); + + filp = filp_open(filename, O_CREAT|O_EXCL|O_WRONLY|O_LARGEFILE, 0600); + if (IS_ERR(filp)) { + rc = PTR_ERR(filp); + filp = NULL; + printk(KERN_ERR "LustreError: can't open %s for dump: rc %d\n", + filename, rc); + goto out; + } + + spin_lock_init(&pc.pc_lock); pc.pc_want_daemon_pages = 1; collect_pages(&pc); if (cfs_list_empty(&pc.pc_pages)) { @@ -706,8 +717,8 @@ int cfs_tracefile_dump_all_pages(char *filename) __LASSERT_TAGE_INVARIANT(tage); - rc = cfs_filp_write(filp, cfs_page_address(tage->page), - tage->used, cfs_filp_poff(filp)); + rc = filp_write(filp, cfs_page_address(tage->page), + tage->used, filp_poff(filp)); if (rc != (int)tage->used) { printk(CFS_KERN_WARNING "wanted to write %u but wrote " "%d\n", tage->used, rc); @@ -718,24 +729,24 @@ int cfs_tracefile_dump_all_pages(char *filename) cfs_list_del(&tage->linkage); cfs_tage_free(tage); } - CFS_MMSPACE_CLOSE; - rc = cfs_filp_fsync(filp); - if (rc) - printk(CFS_KERN_ERR "sync returns %d\n", rc); - close: - cfs_filp_close(filp); - out: - cfs_tracefile_write_unlock(); - return rc; + CFS_MMSPACE_CLOSE; + rc = filp_fsync(filp); + if (rc) + printk(CFS_KERN_ERR "sync returns %d\n", rc); +close: + filp_close(filp, NULL); +out: + cfs_tracefile_write_unlock(); + return rc; } void cfs_trace_flush_pages(void) { - struct page_collection pc; - struct cfs_trace_page *tage; - struct cfs_trace_page *tmp; + struct page_collection pc; + struct cfs_trace_page *tage; + struct cfs_trace_page *tmp; - cfs_spin_lock_init(&pc.pc_lock); + spin_lock_init(&pc.pc_lock); pc.pc_want_daemon_pages = 1; collect_pages(&pc); @@ -775,6 +786,7 @@ int cfs_trace_copyin_string(char *knl_buffer, int knl_buffer_nob, knl_buffer[nob + 1] = 0; /* terminate */ return 0; } +EXPORT_SYMBOL(cfs_trace_copyin_string); int cfs_trace_copyout_string(char *usr_buffer, int usr_buffer_nob, const char *knl_buffer, char *append) @@ -911,15 +923,17 @@ int cfs_trace_set_debug_mb(int mb) struct cfs_trace_cpu_data *tcd; if (mb < cfs_num_possible_cpus()) { - printk(KERN_ERR "Cannot set debug_mb to %d, the value should be >= %d\n", - mb, cfs_num_possible_cpus()); - return -EINVAL; + printk(CFS_KERN_WARNING + "Lustre: %d MB is too small for debug buffer size, " + "setting it to %d MB.\n", mb, cfs_num_possible_cpus()); + mb = cfs_num_possible_cpus(); } if (mb > limit) { - printk(CFS_KERN_ERR "Lustre: Refusing to set debug buffer size " - "to %dMB - limit is %d\n", mb, limit); - return -EINVAL; + printk(CFS_KERN_WARNING + "Lustre: %d MB is too large for debug buffer size, " + "setting it to %d MB.\n", mb, limit); + mb = limit; } mb /= cfs_num_possible_cpus(); @@ -966,22 +980,22 @@ int cfs_trace_get_debug_mb(void) static int tracefiled(void *arg) { - struct page_collection pc; - struct tracefiled_ctl *tctl = arg; - struct cfs_trace_page *tage; - struct cfs_trace_page *tmp; - cfs_file_t *filp; - int last_loop = 0; - int rc; + struct page_collection pc; + struct tracefiled_ctl *tctl = arg; + struct cfs_trace_page *tage; + struct cfs_trace_page *tmp; + struct file *filp; + int last_loop = 0; + int rc; - CFS_DECL_MMSPACE; + CFS_DECL_MMSPACE; - /* we're started late enough that we pick up init's fs context */ - /* this is so broken in uml? what on earth is going on? */ - cfs_daemonize("ktracefiled"); + /* we're started late enough that we pick up init's fs context */ + /* this is so broken in uml? what on earth is going on? */ + cfs_daemonize("ktracefiled"); - cfs_spin_lock_init(&pc.pc_lock); - cfs_complete(&tctl->tctl_start); + spin_lock_init(&pc.pc_lock); + complete(&tctl->tctl_start); while (1) { cfs_waitlink_t __wait; @@ -994,13 +1008,16 @@ static int tracefiled(void *arg) filp = NULL; cfs_tracefile_read_lock(); if (cfs_tracefile[0] != 0) { - filp = cfs_filp_open(cfs_tracefile, - O_CREAT | O_RDWR | O_LARGEFILE, - 0600, &rc); - if (!(filp)) - printk(CFS_KERN_WARNING "couldn't open %s: " - "%d\n", cfs_tracefile, rc); - } + filp = filp_open(cfs_tracefile, + O_CREAT | O_RDWR | O_LARGEFILE, + 0600); + if (IS_ERR(filp)) { + rc = PTR_ERR(filp); + filp = NULL; + printk(CFS_KERN_WARNING "couldn't open %s: " + "%d\n", cfs_tracefile, rc); + } + } cfs_tracefile_read_unlock(); if (filp == NULL) { put_pages_on_daemon_list(&pc); @@ -1019,11 +1036,11 @@ static int tracefiled(void *arg) if (f_pos >= (off_t)cfs_tracefile_size) f_pos = 0; - else if (f_pos > (off_t)cfs_filp_size(filp)) - f_pos = cfs_filp_size(filp); + else if (f_pos > (off_t)filp_size(filp)) + f_pos = filp_size(filp); - rc = cfs_filp_write(filp, cfs_page_address(tage->page), - tage->used, &f_pos); + rc = filp_write(filp, cfs_page_address(tage->page), + tage->used, &f_pos); if (rc != (int)tage->used) { printk(CFS_KERN_WARNING "wanted to write %u " "but wrote %d\n", tage->used, rc); @@ -1031,9 +1048,9 @@ static int tracefiled(void *arg) __LASSERT(cfs_list_empty(&pc.pc_pages)); } } - CFS_MMSPACE_CLOSE; + CFS_MMSPACE_CLOSE; - cfs_filp_close(filp); + filp_close(filp, NULL); put_pages_on_daemon_list(&pc); if (!cfs_list_empty(&pc.pc_pages)) { int i; @@ -1043,7 +1060,7 @@ static int tracefiled(void *arg) printk(CFS_KERN_ERR "total cpus(%d): ", cfs_num_possible_cpus()); for (i = 0; i < cfs_num_possible_cpus(); i++) - if (cfs_cpu_online(i)) + if (cpu_online(i)) printk(CFS_KERN_ERR "%d(on) ", i); else printk(CFS_KERN_ERR "%d(off) ", i); @@ -1074,7 +1091,7 @@ end_loop: cfs_time_seconds(1)); cfs_waitq_del(&tctl->tctl_waitq, &__wait); } - cfs_complete(&tctl->tctl_stop); + complete(&tctl->tctl_stop); return 0; } @@ -1083,24 +1100,24 @@ int cfs_trace_start_thread(void) struct tracefiled_ctl *tctl = &trace_tctl; int rc = 0; - cfs_mutex_down(&cfs_trace_thread_sem); + mutex_lock(&cfs_trace_thread_mutex); if (thread_running) goto out; - cfs_init_completion(&tctl->tctl_start); - cfs_init_completion(&tctl->tctl_stop); + init_completion(&tctl->tctl_start); + init_completion(&tctl->tctl_stop); cfs_waitq_init(&tctl->tctl_waitq); cfs_atomic_set(&tctl->tctl_shutdown, 0); - if (cfs_kernel_thread(tracefiled, tctl, 0) < 0) { + if (cfs_create_thread(tracefiled, tctl, 0) < 0) { rc = -ECHILD; goto out; } - cfs_wait_for_completion(&tctl->tctl_start); + wait_for_completion(&tctl->tctl_start); thread_running = 1; out: - cfs_mutex_up(&cfs_trace_thread_sem); + mutex_unlock(&cfs_trace_thread_mutex); return rc; } @@ -1108,15 +1125,15 @@ void cfs_trace_stop_thread(void) { struct tracefiled_ctl *tctl = &trace_tctl; - cfs_mutex_down(&cfs_trace_thread_sem); + mutex_lock(&cfs_trace_thread_mutex); if (thread_running) { printk(CFS_KERN_INFO "Lustre: shutting down debug daemon thread...\n"); cfs_atomic_set(&tctl->tctl_shutdown, 1); - cfs_wait_for_completion(&tctl->tctl_stop); + wait_for_completion(&tctl->tctl_stop); thread_running = 0; } - cfs_mutex_up(&cfs_trace_thread_sem); + mutex_unlock(&cfs_trace_thread_mutex); } int cfs_tracefile_init(int max_pages) @@ -1176,14 +1193,14 @@ static void trace_cleanup_on_all_cpus(void) static void cfs_trace_cleanup(void) { - struct page_collection pc; + struct page_collection pc; - CFS_INIT_LIST_HEAD(&pc.pc_pages); - cfs_spin_lock_init(&pc.pc_lock); + CFS_INIT_LIST_HEAD(&pc.pc_pages); + spin_lock_init(&pc.pc_lock); - trace_cleanup_on_all_cpus(); + trace_cleanup_on_all_cpus(); - cfs_tracefile_fini_arch(); + cfs_tracefile_fini_arch(); } void cfs_tracefile_exit(void)