Attachment 609277 Details for Bug 802423 – Gluster volume log

Gluster volume log

gluster_clvolume1_lock_translator_trace_20120903.log (text/x-log), 24.03 KB, created by Andreas Schmechtig on 2012-09-03 07:19:11 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Andreas Schmechtig

Created: 2012-09-03 07:19:11 UTC

Size: 24.03 KB

patch

obsolete

>[2012-09-03 09:01:32.627495] W [glusterfsd.c:831:cleanup_and_exit] (-->/lib64/libc.so.6(clone+0x6d) [0x3d9c0e767d] (-->/lib64/libpthread.so.0() [0x3d9c807851] (-->/usr/sbin/glusterfsd(glusterfs_sigwaiter+0xdd) [0x405cfd]))) 0-: received signum (15), shutting down
>[2012-09-03 09:01:32.627645] E [rpcsvc.c:1155:rpcsvc_program_unregister_portmap] 0-rpc-service: Could not unregister with portmap
>[2012-09-03 09:02:01.692887] I [glusterfsd.c:1666:main] 0-/usr/sbin/glusterfsd: Started running /usr/sbin/glusterfsd version 3.3.0
>[2012-09-03 09:02:01.988985] I [graph.c:241:gf_add_cmdline_options] 0-clvolume1-server: adding option 'listen-port' for volume 'clvolume1-server' with value '24013'
>[2012-09-03 09:02:01.989023] I [graph.c:241:gf_add_cmdline_options] 0-clvolume1-posix: adding option 'glusterd-uuid' for volume 'clvolume1-posix' with value 'e971f0ba-0ead-49e1-82ad-dacefa4755be'
>[2012-09-03 09:02:02.071322] W [options.c:782:xl_opt_validate] 0-clvolume1-server: option 'listen-port' is deprecated, preferred is 'transport.socket.listen-port', continuing with correction
>[2012-09-03 09:02:02.087479] W [posix.c:4057:init] 0-clvolume1-posix: Posix access control list is not supported.
>Given volfile:
>+------------------------------------------------------------------------------+
>  1: volume clvolume1-posix
>  2:     type storage/posix
>  3:     option directory /export1
>  4:     option volume-id b06f990b-938d-4069-905f-132995f9f360
>  5: end-volume
>  6: 
>  7: volume clvolume1-access-control
>  8:     type features/access-control
>  9:     subvolumes clvolume1-posix
> 10: end-volume
> 11: 
> 12: volume clvolume1-locks
> 13:     type features/locks
> 14:     subvolumes clvolume1-access-control
> 15:     option trace on
> 16: end-volume
> 17: 
> 18: volume clvolume1-io-threads
> 19:     type performance/io-threads
> 20:     subvolumes clvolume1-locks
> 21: end-volume
> 22: 
> 23: volume clvolume1-index
> 24:     type features/index
> 25:     option index-base /export1/.glusterfs/indices
> 26:     subvolumes clvolume1-io-threads
> 27: end-volume
> 28: 
> 29: volume clvolume1-marker
> 30:     type features/marker
> 31:     option volume-uuid b06f990b-938d-4069-905f-132995f9f360
> 32:     option timestamp-file /var/lib/glusterd/vols/clvolume1/marker.tstamp
> 33:     option xtime off
> 34:     option quota off
> 35:     subvolumes clvolume1-index
> 36: end-volume
> 37: 
> 38: volume /export1
> 39:     type debug/io-stats
> 40:     option latency-measurement off
> 41:     option count-fop-hits off
> 42:     subvolumes clvolume1-marker
> 43: end-volume
> 44: 
> 45: volume clvolume1-server
> 46:     type protocol/server
> 47:     option transport-type tcp
> 48:     option auth.login./export1.allow 80380ece-d6d0-45a1-8803-9958be88c429
> 49:     option auth.login.80380ece-d6d0-45a1-8803-9958be88c429.password ea1d8e96-f47e-463f-a3c8-099d8c750224
> 50:     option auth.addr./export1.allow *
> 51:     subvolumes /export1
> 52: end-volume
>
>+------------------------------------------------------------------------------+
>[2012-09-03 09:02:05.640262] W [socket.c:410:__socket_keepalive] 0-socket: failed to set keep idle on socket 8
>[2012-09-03 09:02:05.640298] W [socket.c:1876:socket_server_event_handler] 0-socket.glusterfsd: Failed to set keep-alive: Operation not supported
>[2012-09-03 09:02:05.809360] I [server-handshake.c:571:server_setvolume] 0-clvolume1-server: accepted client from cdusat01.de.comdirect.com-1539-2012/09/03-09:02:01:714611-clvolume1-client-0-0 (version: 3.3.0)
>[2012-09-03 09:02:05.911677] I [server-handshake.c:571:server_setvolume] 0-clvolume1-server: accepted client from cdusat01.de.comdirect.com-1551-2012/09/03-09:02:01:755620-clvolume1-client-0-0 (version: 3.3.0)
>[2012-09-03 09:02:10.448669] I [server-handshake.c:571:server_setvolume] 0-clvolume1-server: accepted client from cdusat01.de.comdirect.com-1865-2012/09/03-09:02:06:265285-clvolume1-client-0-0 (version: 3.3.0)
>[2012-09-03 09:02:24.388138] I [server-handshake.c:571:server_setvolume] 0-clvolume1-server: accepted client from cdusat02.de.comdirect.com-1567-2012/09/03-09:02:19:327322-clvolume1-client-0-0 (version: 3.3.0)
>[2012-09-03 09:02:24.393571] I [server-handshake.c:571:server_setvolume] 0-clvolume1-server: accepted client from cdusat02.de.comdirect.com-1560-2012/09/03-09:02:19:302980-clvolume1-client-0-0 (version: 3.3.0)
>[2012-09-03 09:02:28.177186] I [server-handshake.c:571:server_setvolume] 0-clvolume1-server: accepted client from cdusat02.de.comdirect.com-1878-2012/09/03-09:02:24:023013-clvolume1-client-0-0 (version: 3.3.0)
>[2012-09-03 09:06:14.316056] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=522608828, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=36} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=WRITE, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:06:14.316157] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=522608828, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=36} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=WRITE, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:06:14.316557] I [common.c:378:pl_trace_flush] 0-clvolume1-locks: [FLUSH] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=37} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls}
>[2012-09-03 09:06:14.316911] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=38} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=UNLOCK, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:06:14.316952] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=2577, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=38} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=UNLOCK, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:06:14.317298] I [common.c:395:pl_trace_release] 0-clvolume1-locks: [RELEASE] Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls}
>[2012-09-03 09:06:14.582120] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=e17c3d4881fd84bc, Transport=0x16f9da0, Frame=43} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLKW, type=READ, start=23596, len=0, pid=2577, lk-owner=e17c3d4881fd84bc}
>[2012-09-03 09:06:14.582182] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=2577, lk-owner=e17c3d4881fd84bc, Transport=0x16f9da0, Frame=43} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLKW, type=READ, start=23596, len=0, pid=2577, lk-owner=e17c3d4881fd84bc}
>[2012-09-03 09:06:14.703081] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=44} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:14.703177] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=44} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:19.717149] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=45} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:19.717223] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=45} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:24.728381] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=46} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:24.728413] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=46} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:29.740931] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=47} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:29.740981] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=47} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:34.755207] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=48} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:34.755257] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=48} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:39.769367] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=49} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:39.769418] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=49} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:44.785597] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=53} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:44.785645] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=53} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:49.800496] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=54} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:49.800548] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=54} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:54.816157] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=55} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:54.816209] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=55} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:59.830791] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=57} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:06:59.830853] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=57} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:04.845303] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=58} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:04.845363] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=58} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:09.855389] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=59} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:09.855440] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=59} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:14.869468] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=60} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:14.869526] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=60} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:19.883795] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=61} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:19.883857] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=61} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:24.897733] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=62} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:24.897783] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=62} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:29.911982] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=63} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:29.912032] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=63} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:34.926033] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=64} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:34.926127] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=64} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:39.940435] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=65} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:39.940508] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [TRYAGAIN] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=65} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=FCNTL, cmd=SETLK, type=WRITE, start=2147483539, len=1, pid=2577, lk-owner=ed49ea482eb51663}
>[2012-09-03 09:07:43.313484] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=522600492, lk-owner=2c40261f517f0000, Transport=0x16f9da0, Frame=67} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=WRITE, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:43.313563] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=522600492, lk-owner=2c40261f517f0000, Transport=0x16f9da0, Frame=67} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=WRITE, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:43.314019] I [common.c:378:pl_trace_flush] 0-clvolume1-locks: [FLUSH] Locker = {Pid=2577, lk-owner=ed49ea482eb51663, Transport=0x16f9da0, Frame=68} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls}
>[2012-09-03 09:07:43.314463] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2577, lk-owner=2c40261f517f0000, Transport=0x16f9da0, Frame=69} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=UNLOCK, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:43.314501] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=2577, lk-owner=2c40261f517f0000, Transport=0x16f9da0, Frame=69} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=UNLOCK, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:43.314864] I [common.c:395:pl_trace_release] 0-clvolume1-locks: [RELEASE] Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls}
>[2012-09-03 09:07:45.962654] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=522608828, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=87} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=WRITE, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:45.962705] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=522608828, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=87} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=WRITE, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:45.963176] I [common.c:378:pl_trace_flush] 0-clvolume1-locks: [FLUSH] Locker = {Pid=2623, lk-owner=57b449106a7bad64, Transport=0x16f9da0, Frame=88} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls}
>[2012-09-03 09:07:45.963496] I [common.c:271:pl_trace_in] 0-clvolume1-locks: [REQUEST] Locker = {Pid=2623, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=89} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=UNLOCK, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:45.963536] I [common.c:323:pl_trace_out] 0-clvolume1-locks: [GRANTED] Locker = {Pid=2623, lk-owner=bc60261f517f0000, Transport=0x16f9da0, Frame=89} Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls} Lock = {lock=INODELK, cmd=SETLK, type=UNLOCK, domain: clvolume1-replicate-0, start=0, len=0, pid=0}
>[2012-09-03 09:07:45.963863] I [common.c:395:pl_trace_release] 0-clvolume1-locks: [RELEASE] Lockee = {gfid=f093990d-e7c9-4a95-8f04-2d8895574501, fd=0x16dfafc, path=/test/lpar_report_20120809.xls}

Actions: View

Attachments on bug 802423: 608548 | 608549 | 608550 | 609273 | 609275 | 609277 | 609279