summaryrefslogtreecommitdiffstats
diff options
context:
space:
mode:
authorPranith Kumar K <pkarampu@redhat.com>2014-06-28 04:40:05 +0530
committerVijay Bellur <vbellur@redhat.com>2014-07-07 10:27:00 -0700
commitd6cc24f748c2287d7607de355ef236132c169a15 (patch)
tree96e766526c67e07260b85572fd1df7686a089f4b
parent2c5eb5c708134218bf756cffeb5167bd03499028 (diff)
Changes in statedump for stack, frame, locks
Internal call-stacks don't have lk-owner so it is a bit difficult to confirm if a stack hung by comparing two statedump files. This change prints call-stack, frame's address. This should solve the comparison problem. Lock times and log times don't have same timezone because of which one has to manually convert the times for debugging the issues. This change prints blocked, granted times also in UTC. Also fixed line truncation issue when client-unique-string is big. Change-Id: I116372c0d63476823a36ca6dbfba91648f9234cc BUG: 1114188 Signed-off-by: Pranith Kumar K <pkarampu@redhat.com> Reviewed-on: http://review.gluster.org/8197 Tested-by: Gluster Build System <jenkins@build.gluster.com> Reviewed-by: Raghavendra Bhat <raghavendra@redhat.com> Reviewed-by: Ravishankar N <ravishankar@redhat.com> Reviewed-by: Vijay Bellur <vbellur@redhat.com>
-rw-r--r--libglusterfs/src/stack.c2
-rw-r--r--xlators/features/locks/src/posix.c97
2 files changed, 57 insertions, 42 deletions
diff --git a/libglusterfs/src/stack.c b/libglusterfs/src/stack.c
index 37b338f51cb..a7fb9510399 100644
--- a/libglusterfs/src/stack.c
+++ b/libglusterfs/src/stack.c
@@ -101,6 +101,7 @@ gf_proc_dump_call_frame (call_frame_t *call_frame, const char *key_buf,...)
gf_proc_dump_write("frame-creation-time", "%s", timestr);
}
+ gf_proc_dump_write("frame", "%p", call_frame);
gf_proc_dump_write("ref_count", "%d", my_frame.ref_count);
gf_proc_dump_write("translator", "%s", my_frame.this->name);
gf_proc_dump_write("complete", "%d", my_frame.complete);
@@ -159,6 +160,7 @@ gf_proc_dump_call_stack (call_stack_t *call_stack, const char *key_buf,...)
gf_proc_dump_write("callstack-creation-time", "%s", timestr);
}
+ gf_proc_dump_write("stack", "%p", call_stack);
gf_proc_dump_write("uid", "%d", call_stack->uid);
gf_proc_dump_write("gid", "%d", call_stack->gid);
gf_proc_dump_write("pid", "%d", call_stack->pid);
diff --git a/xlators/features/locks/src/posix.c b/xlators/features/locks/src/posix.c
index 337623d6577..af25a10aaa3 100644
--- a/xlators/features/locks/src/posix.c
+++ b/xlators/features/locks/src/posix.c
@@ -2164,9 +2164,15 @@ pl_dump_lock (char *str, int size, struct gf_flock *flock,
time_t *granted_time, time_t *blkd_time, gf_boolean_t active)
{
char *type_str = NULL;
- char granted[32] = {0,};
- char blocked[32] = {0,};
-
+ char granted[256] = {0,};
+ char blocked[256] = {0,};
+
+ if (granted_time)
+ gf_time_fmt (granted, sizeof (granted), *granted_time,
+ gf_timefmt_FT);
+ if (blkd_time)
+ gf_time_fmt (blocked, sizeof (blocked), *blkd_time,
+ gf_timefmt_FT);
switch (flock->l_type) {
case F_RDLCK:
type_str = "READ";
@@ -2190,7 +2196,7 @@ pl_dump_lock (char *str, int size, struct gf_flock *flock,
(unsigned long long) flock->l_len,
(unsigned long long) flock->l_pid,
lkowner_utoa (owner), trans, conn_id,
- ctime_r (granted_time, granted));
+ granted);
} else {
snprintf (str, size, RANGE_BLKD_GRNTD_FMT,
type_str, flock->l_whence,
@@ -2198,18 +2204,15 @@ pl_dump_lock (char *str, int size, struct gf_flock *flock,
(unsigned long long) flock->l_len,
(unsigned long long) flock->l_pid,
lkowner_utoa (owner), trans, conn_id,
- ctime_r (blkd_time, blocked),
- ctime_r (granted_time, granted));
+ blocked, granted);
}
- }
- else {
+ } else {
snprintf (str, size, RANGE_BLKD_FMT,
type_str, flock->l_whence,
(unsigned long long) flock->l_start,
(unsigned long long) flock->l_len,
(unsigned long long) flock->l_pid,
- lkowner_utoa (owner), trans, conn_id,
- ctime_r (blkd_time, blocked));
+ lkowner_utoa (owner), trans, conn_id, blocked);
}
}
@@ -2219,12 +2222,13 @@ __dump_entrylks (pl_inode_t *pl_inode)
{
pl_dom_list_t *dom = NULL;
pl_entry_lock_t *lock = NULL;
- char blocked[32] = {0,};
- char granted[32] = {0,};
+ char blocked[256] = {0,};
+ char granted[256] = {0,};
int count = 0;
char key[GF_DUMP_MAX_BUF_LEN] = {0,};
+ char *k = "xlator.feature.locks.lock-dump.domain.entrylk";
- char tmp[256];
+ char tmp[4098];
list_for_each_entry (dom, &pl_inode->dom_list, inode_list) {
@@ -2237,26 +2241,33 @@ __dump_entrylks (pl_inode_t *pl_inode)
list_for_each_entry (lock, &dom->entrylk_list, domain_list) {
- gf_proc_dump_build_key(key,
- "xlator.feature.locks.lock-dump.domain.entrylk",
+ gf_time_fmt (granted, sizeof (granted),
+ lock->granted_time.tv_sec, gf_timefmt_FT);
+ gf_proc_dump_build_key(key, k,
"entrylk[%d](ACTIVE)", count );
- if (lock->blkd_time.tv_sec == 0 && lock->blkd_time.tv_usec == 0) {
- snprintf (tmp, 256, ENTRY_GRNTD_FMT,
- lock->type == ENTRYLK_RDLCK ? "ENTRYLK_RDLCK" :
- "ENTRYLK_WRLCK", lock->basename,
+ if (lock->blkd_time.tv_sec == 0) {
+ snprintf (tmp, sizeof (tmp), ENTRY_GRNTD_FMT,
+ lock->type == ENTRYLK_RDLCK ?
+ "ENTRYLK_RDLCK" : "ENTRYLK_WRLCK",
+ lock->basename,
(unsigned long long) lock->client_pid,
- lkowner_utoa (&lock->owner), lock->client,
- lock->connection_id,
- ctime_r (&lock->granted_time.tv_sec, granted));
+ lkowner_utoa (&lock->owner),
+ lock->client,
+ lock->connection_id, granted);
} else {
- snprintf (tmp, 256, ENTRY_BLKD_GRNTD_FMT,
- lock->type == ENTRYLK_RDLCK ? "ENTRYLK_RDLCK" :
- "ENTRYLK_WRLCK", lock->basename,
+ gf_time_fmt (blocked, sizeof (blocked),
+ lock->blkd_time.tv_sec,
+ gf_timefmt_FT);
+ snprintf (tmp, sizeof (tmp),
+ ENTRY_BLKD_GRNTD_FMT,
+ lock->type == ENTRYLK_RDLCK ?
+ "ENTRYLK_RDLCK" : "ENTRYLK_WRLCK",
+ lock->basename,
(unsigned long long) lock->client_pid,
- lkowner_utoa (&lock->owner), lock->client,
+ lkowner_utoa (&lock->owner),
+ lock->client,
lock->connection_id,
- ctime_r (&lock->blkd_time.tv_sec, blocked),
- ctime_r (&lock->granted_time.tv_sec, granted));
+ blocked, granted);
}
gf_proc_dump_write(key, tmp);
@@ -2264,18 +2275,21 @@ __dump_entrylks (pl_inode_t *pl_inode)
count++;
}
- list_for_each_entry (lock, &dom->blocked_entrylks, blocked_locks) {
+ list_for_each_entry (lock, &dom->blocked_entrylks,
+ blocked_locks) {
- gf_proc_dump_build_key(key,
- "xlator.feature.locks.lock-dump.domain.entrylk",
+ gf_time_fmt (blocked, sizeof (blocked),
+ lock->blkd_time.tv_sec, gf_timefmt_FT);
+
+ gf_proc_dump_build_key(key, k,
"entrylk[%d](BLOCKED)", count );
- snprintf (tmp, 256, ENTRY_BLKD_FMT,
- lock->type == ENTRYLK_RDLCK ? "ENTRYLK_RDLCK" :
- "ENTRYLK_WRLCK", lock->basename,
+ snprintf (tmp, sizeof (tmp), ENTRY_BLKD_FMT,
+ lock->type == ENTRYLK_RDLCK ?
+ "ENTRYLK_RDLCK" : "ENTRYLK_WRLCK",
+ lock->basename,
(unsigned long long) lock->client_pid,
lkowner_utoa (&lock->owner), lock->client,
- lock->connection_id,
- ctime_r (&lock->blkd_time.tv_sec, blocked));
+ lock->connection_id, blocked);
gf_proc_dump_write(key, tmp);
@@ -2283,7 +2297,6 @@ __dump_entrylks (pl_inode_t *pl_inode)
}
}
-
}
void
@@ -2305,7 +2318,7 @@ __dump_inodelks (pl_inode_t *pl_inode)
int count = 0;
char key[GF_DUMP_MAX_BUF_LEN];
- char tmp[256];
+ char tmp[4098];
list_for_each_entry (dom, &pl_inode->dom_list, inode_list) {
@@ -2323,7 +2336,7 @@ __dump_inodelks (pl_inode_t *pl_inode)
"inodelk[%d](ACTIVE)",count );
SET_FLOCK_PID (&lock->user_flock, lock);
- pl_dump_lock (tmp, 256, &lock->user_flock,
+ pl_dump_lock (tmp, sizeof (tmp), &lock->user_flock,
&lock->owner,
lock->client, lock->connection_id,
&lock->granted_time.tv_sec,
@@ -2340,7 +2353,7 @@ __dump_inodelks (pl_inode_t *pl_inode)
"inodelk",
"inodelk[%d](BLOCKED)",count );
SET_FLOCK_PID (&lock->user_flock, lock);
- pl_dump_lock (tmp, 256, &lock->user_flock,
+ pl_dump_lock (tmp, sizeof (tmp), &lock->user_flock,
&lock->owner,
lock->client, lock->connection_id,
0, &lock->blkd_time.tv_sec,
@@ -2372,7 +2385,7 @@ __dump_posixlks (pl_inode_t *pl_inode)
int count = 0;
char key[GF_DUMP_MAX_BUF_LEN];
- char tmp[256];
+ char tmp[4098];
list_for_each_entry (lock, &pl_inode->ext_list, list) {
@@ -2382,7 +2395,7 @@ __dump_posixlks (pl_inode_t *pl_inode)
"posixlk[%d](%s)",
count,
lock->blocked ? "BLOCKED" : "ACTIVE");
- pl_dump_lock (tmp, 256, &lock->user_flock,
+ pl_dump_lock (tmp, sizeof (tmp), &lock->user_flock,
&lock->owner, lock->client, NULL,
&lock->granted_time.tv_sec, &lock->blkd_time.tv_sec,
(lock->blocked)? _gf_false: _gf_true);