Project

General

Profile

Bug #1206 » messages

Brian Chrisman, 07/28/2011 02:51 PM

 
Jul 28 21:29:33 test09 kernel: imklog 4.6.2, log source = /proc/kmsg started.
Jul 28 21:29:33 test09 rsyslogd: [origin software="rsyslogd" swVersion="4.6.2" x-pid="1254" x-info="http://www.rsyslog.com"] (re)start
Jul 28 21:29:36 test09 kernel: device fsid 2139715e-a7af-4f5d-9e4c-851e6b8c2531 devid 1 transid 169 /dev/sdd7
Jul 28 21:29:36 test09 kernel: device fsid 4072d3ed-80f8-43ab-b8bf-fb11c20f3664 devid 1 transid 156 /dev/sdc7
Jul 28 21:29:37 test09 kernel: device fsid 5e037491-7ec7-4dc1-bac5-4e921237ae66 devid 1 transid 164 /dev/sda7
Jul 28 21:29:37 test09 kernel: device fsid e63cf870-5ab4-47b2-b7eb-da51c26275f0 devid 1 transid 145 /dev/sdb7
Jul 28 21:29:37 test09 kernel: device fsid 5e037491-7ec7-4dc1-bac5-4e921237ae66 devid 1 transid 164 /dev/sda7
Jul 28 21:29:37 test09 kernel: device fsid 2139715e-a7af-4f5d-9e4c-851e6b8c2531 devid 1 transid 169 /dev/sdd7
Jul 28 21:29:37 test09 kernel: device fsid 4072d3ed-80f8-43ab-b8bf-fb11c20f3664 devid 1 transid 156 /dev/sdc7
Jul 28 21:29:37 test09 kernel: device fsid 5e037491-7ec7-4dc1-bac5-4e921237ae66 devid 1 transid 168 /dev/sda7
Jul 28 21:29:37 test09 kernel: device fsid e63cf870-5ab4-47b2-b7eb-da51c26275f0 devid 1 transid 145 /dev/sdb7
Jul 28 21:29:37 test09 kernel: device fsid e63cf870-5ab4-47b2-b7eb-da51c26275f0 devid 1 transid 145 /dev/sdb7
Jul 28 21:29:39 test09 kernel: device fsid 2139715e-a7af-4f5d-9e4c-851e6b8c2531 devid 1 transid 169 /dev/sdd7
Jul 28 21:29:39 test09 kernel: device fsid 4072d3ed-80f8-43ab-b8bf-fb11c20f3664 devid 1 transid 156 /dev/sdc7
Jul 28 21:29:39 test09 kernel: device fsid 5e037491-7ec7-4dc1-bac5-4e921237ae66 devid 1 transid 168 /dev/sda7
Jul 28 21:29:39 test09 kernel: device fsid e63cf870-5ab4-47b2-b7eb-da51c26275f0 devid 1 transid 149 /dev/sdb7
Jul 28 21:29:40 test09 kernel: device fsid 4072d3ed-80f8-43ab-b8bf-fb11c20f3664 devid 1 transid 156 /dev/sdc7
Jul 28 21:29:40 test09 kernel: device fsid 2139715e-a7af-4f5d-9e4c-851e6b8c2531 devid 1 transid 169 /dev/sdd7
Jul 28 21:29:40 test09 kernel: device fsid 4072d3ed-80f8-43ab-b8bf-fb11c20f3664 devid 1 transid 160 /dev/sdc7
Jul 28 21:29:40 test09 kernel: device fsid 5e037491-7ec7-4dc1-bac5-4e921237ae66 devid 1 transid 168 /dev/sda7
Jul 28 21:29:40 test09 kernel: device fsid e63cf870-5ab4-47b2-b7eb-da51c26275f0 devid 1 transid 149 /dev/sdb7
Jul 28 21:29:40 test09 kernel: device fsid 2139715e-a7af-4f5d-9e4c-851e6b8c2531 devid 1 transid 169 /dev/sdd7
Jul 28 21:29:46 test09 kernel: libceph: mon0 192.168.98.111:6789 connection failed
Jul 28 21:29:56 test09 kernel: libceph: mon0 192.168.98.111:6789 connection failed
Jul 28 21:30:06 test09 kernel: libceph: mon0 192.168.98.111:6789 connection failed
Jul 28 21:30:16 test09 kernel: libceph: mon0 192.168.98.111:6789 connection failed
Jul 28 21:30:26 test09 kernel: libceph: mon0 192.168.98.111:6789 connection failed
Jul 28 21:30:36 test09 kernel: libceph: mon0 192.168.98.111:6789 connection failed
Jul 28 21:31:26 test09 kernel: libceph: client4210 fsid 78b94248-795b-e408-544a-bdcdea79c057
Jul 28 21:31:26 test09 kernel: libceph: mon0 192.168.98.109:6789 session established
Jul 28 21:31:42 test09 kernel: NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
Jul 28 21:31:42 test09 kernel: NFSD: starting 90-second grace period
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:01 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 1
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 1 -> 2
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:1060 : send_renew_caps to mds0 (up:active)
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800371c5cc0 front 28
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800371c5cc0 to mds0 22=client_session len 28+0+0 -----
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (3)
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800371c5cc0 seq 14 type 22 len 28+0+0 0 pgs
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 2086927209 data_crc 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 95
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 95 left
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (3)
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (2)
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1398 : got ack for seq 14 type 22 at ffff8800371c5cc0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800371c5cc0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800371c5cc0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1572 : got hdr type 22 front 28 data 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800371c5cc0 front 28
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800371c5cc0 28 (713343520) + 0 (0) + 0 (0)
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800371c5cc0 14 from mds0 22=client_session len 28+0 (713343520 0 0) =====
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:2292 : handle_session mds0 renewcaps ffff88003b72e800 state open seq 11
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:1097 : renewed_caps mds0 ttl now 4302584020, was fresh, now stale
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800371c5cc0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800371c5cc0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 13 -> 14
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:01 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (1)
Jul 28 21:35:01 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 2 -> 1
Jul 28 21:35:06 test09 kernel: libceph: mon_client.c:698 : monc delayed_work
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003c11e000
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:393 : con_get ffff88003c11e000 nref = 1 -> 2
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003c11e000
Jul 28 21:35:06 test09 kernel: libceph: mon_client.c:190 : __send_subscribe sub_sent=0 exp=0 want_osd=0
Jul 28 21:35:06 test09 kernel: libceph: mon_client.c:179 : __schedule_delayed after 20000
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003c11e000
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003c11e000 ret 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003c11e000 state 29 nref 2
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003c11e000
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003c11e000 1 left
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003c11e000 0 left in 0 kvecs ret = 1
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003c11e000 ret 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:402 : con_put ffff88003c11e000 nref = 2 -> 1
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:06 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 1
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 1 -> 2
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003b72e840
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (3)
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 28 nref 1
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003b72e840
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 1 left
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:06 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (1)
Jul 28 21:35:06 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 2 -> 1
Jul 28 21:35:11 test09 kernel: libceph: osd_client.c:1145 : osds timeout
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:11 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 1
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 1 -> 2
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003b72e840
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (3)
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 28 nref 1
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003b72e840
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 1 left
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:11 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (1)
Jul 28 21:35:11 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 2 -> 1
Jul 28 21:35:11 test09 kernel: libceph: osd_client.c:708 : __remove_old_osds ffff88003bb90da8
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:16 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 1
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 1 -> 2
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003b72e840
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (3)
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 28 nref 1
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003b72e840
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 1 left
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:16 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (1)
Jul 28 21:35:16 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 2 -> 1
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask pAsLsXsFs)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: dir.c:581 : lookup ffff88003ab6cd90 dentry ffff88003b304600 'foo42'
Jul 28 21:35:19 test09 kernel: ceph: dir.c:1202 : dentry_lru_add ffff8800288025a0 ffff88003b304600 'foo42'
Jul 28 21:35:19 test09 kernel: ceph: dir.c:605 : dir ffff88003ab6cd90 flags are 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff88003af70000
Jul 28 21:35:19 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff88003af702e0 need=2
Jul 28 21:35:19 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff88003af702e0 3 = 1 used + 2 resv + 0 avail
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:575 : __register_request ffff88003af70000 tid 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=1 (1192267954) mode 0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:218 : choose_frag(471090b2) = 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 1
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 1 -> 2
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff88003af70000 tid 3 lookup (attempt 1)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1597 : dentry ffff88003b304600 1/foo42
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5080 front 119
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = ffff88003ab6cd90
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5080 to mds0 24=client_request len 119+0+0 -----
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (4)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5080 seq 15 type 24 len 119+0+0 0 pgs
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 2434972429 data_crc 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 186
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 186 left
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (4)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1398 : got ack for seq 15 type 24 at ffff8800372c5080
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 343 data 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5d80 front 343
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5d80 343 (2791856334) + 0 (0) + 0 (0)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5d80 15 from mds0 26=client_reply len 343+0 (2791856334 0 0) =====
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff88003af70000
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff88003af70000 tid 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 3 result -2
Jul 28 21:35:19 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:19 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:19 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:19 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880038596557 ffff880038596557
Jul 28 21:35:19 test09 kernel: ceph: inode.c:946 : fill_trace ffff88003af70000 is_dentry 1 is_target 0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 42 had 42
Jul 28 21:35:19 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXsFs seq 3
Jul 28 21:35:19 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs
Jul 28 21:35:19 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXsFs now pAsLsXsFs seq 3 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1072 : fill_trace null dentry
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1077 : d_instantiate ffff88003b304600 NULL
Jul 28 21:35:19 test09 kernel: ceph: inode.c:813 : update_dentry_lease ffff88003b304600 duration 0 ms ttl 4302542030
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:19 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003af702e0 count=2
Jul 28 21:35:19 test09 kernel: ceph: caps.c:221 : unreserve caps 3 = 1 used + 0 resv + 2 avail
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1999 : do_request ffff88003af70000 done, result -2
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5080
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5080
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5d80
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5d80
Jul 28 21:35:19 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:19 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003af702e0 count=0
Jul 28 21:35:19 test09 kernel: ceph: dir.c:635 : lookup result= (null)
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:19 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:19 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:19 test09 kernel: ceph: dir.c:700 : create in dir ffff88003ab6cd90 dentry ffff88003b304600 name 'foo42'
Jul 28 21:35:19 test09 kernel: ceph: dir.c:674 : mknod in dir ffff88003ab6cd90 dentry ffff88003b304600 mode 0100644 rdev 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff88003af70000
Jul 28 21:35:19 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff88003af702e0 need=2
Jul 28 21:35:19 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff88003af702e0 3 = 1 used + 2 resv + 0 avail
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:575 : __register_request ffff88003af70000 tid 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=1 (1192267954) mode 2
Jul 28 21:35:19 test09 kernel: ceph: inode.c:218 : choose_frag(471090b2) = 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 2
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff88003af70000 tid 4 mknod (attempt 1)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1597 : dentry ffff88003b304600 1/foo42
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5d80 front 168
Jul 28 21:35:19 test09 kernel: ceph: caps.c:3000 : encode_inode_release ffff88003ab6cd90 mds0 used|dirty p drop Fs unless Fx
Jul 28 21:35:19 test09 kernel: ceph: caps.c:3015 : encode_inode_release ffff88003ab6cd90 cap ffff88001fff47a8 pAsLsXsFs -> pAsLsXs
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = ffff88003ab6cd90
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 14 -> 15
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5d80 to mds0 24=client_request len 163+0+0 -----
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (5)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5d80 seq 16 type 24 len 163+0+0 0 pgs
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 2812113507 data_crc 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 230
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 230 left
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (2)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (3)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1398 : got ack for seq 16 type 24 at ffff8800372c5d80
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 580 data 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5080 front 580
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5080 580 (3606390303) + 0 (0) + 0 (0)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5080 16 from mds0 26=client_reply len 580+0 (3606390303 0 0) =====
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff88003af70000
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff88003af70000 tid 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 4 result 0
Jul 28 21:35:19 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:19 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:19 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:19 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880024d45644 ffff880024d45644
Jul 28 21:35:19 test09 kernel: ceph: inode.c:946 : fill_trace ffff88003af70000 is_dentry 1 is_target 1
Jul 28 21:35:19 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 44 had 42
Jul 28 21:35:19 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:19 test09 kernel: ceph: inode.c:618 : ffff88003ab6cd90 mode 040755 uid.gid 0.0
Jul 28 21:35:19 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXs seq 4
Jul 28 21:35:19 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:19 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXs now pAsLsXs seq 4 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:299 : alloc_inode ffff88003ab6c300
Jul 28 21:35:19 test09 kernel: ceph: inode.c:56 : get_inode created new inode ffff88003ab6c300 10000000006.fffffffffffffffe ino 10000000006
Jul 28 21:35:19 test09 kernel: ceph: inode.c:61 : get_inode on 1099511627782=10000000006.fffffffffffffffe got ffff88003ab6c300
Jul 28 21:35:19 test09 kernel: ceph: inode.c:913 : dn ffff88003b304600 attached to ffff88003ab6c300 ino 10000000006.fffffffffffffffe
Jul 28 21:35:19 test09 kernel: ceph: inode.c:813 : update_dentry_lease ffff88003b304600 duration 30000 ms ttl 4302572114
Jul 28 21:35:19 test09 kernel: ceph: dir.c:1218 : dentry_lru_touch ffff8800288025a0 ffff88003b304600 'foo42' (offset 0)
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1123 : final dn ffff88003b304600
Jul 28 21:35:19 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6c300 ino 10000000006.fffffffffffffffe v 80 had 0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:618 : ffff88003ab6c300 mode 0100644 uid.gid 0.0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:449 : size 0 -> 0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:455 : truncate_seq 0 -> 1
Jul 28 21:35:19 test09 kernel: ceph: inode.c:477 : truncate_size 0 -> 18446744073709551615
Jul 28 21:35:19 test09 kernel: ceph: inode.c:640 : max_size 0 -> 4194304
Jul 28 21:35:19 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6c300 mds0 cap 2 pAsxLsXsxFsxcrwb seq 2
Jul 28 21:35:19 test09 kernel: ceph: caps.c:248 : get_cap ctx=ffff88003af702e0 (2) 3 = 1 used + 2 resv + 0 avail
Jul 28 21:35:19 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:19 test09 kernel: ceph: snap.c:66 : get_realm ffff880024d1cbc0 1 -> 2
Jul 28 21:35:19 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c300 cap ffff88001fff4928 issued -
Jul 28 21:35:19 test09 kernel: ceph: caps.c:607 : issued pAsxLsXsxFsxcrwb, mds wanted -, actual -, queueing
Jul 28 21:35:19 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003ab6c300 min 5693 max 60693
Jul 28 21:35:19 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003ab6c300 flags 0 at 4302602997
Jul 28 21:35:19 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6c300 (10000000006.fffffffffffffffe) cap ffff88001fff4928 pAsxLsXsxFsxcrwb now pAsxLsXsxFsxcrwb seq 2 mds0
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:19 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003af702e0 count=1
Jul 28 21:35:19 test09 kernel: ceph: caps.c:221 : unreserve caps 3 = 2 used + 0 resv + 1 avail
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1999 : do_request ffff88003af70000 done, result 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5d80
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5d80
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5080
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5080
Jul 28 21:35:19 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:19 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003af702e0 count=0
Jul 28 21:35:19 test09 kernel: ceph: export.c:63 : encode_fh ffff88003b304600 connectable
Jul 28 21:35:19 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff880024d45400
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff880024d456e0 need=1
Jul 28 21:35:19 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff880024d456e0 3 = 2 used + 1 resv + 0 avail
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:575 : __register_request ffff880024d45400 tid 5
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=0 (0) mode 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 3
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff880024d45400 tid 5 getattr (attempt 1)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1593 : inode ffff88003ab6cd90 1.fffffffffffffffe
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5280 front 114
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = (null)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 15 -> 16
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5280 to mds0 24=client_request len 114+0+0 -----
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5280 seq 17 type 24 len 114+0+0 0 pgs
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 4286524091 data_crc 0
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 181
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 181 left
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:19 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:19 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (4)
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1398 : got ack for seq 17 type 24 at ffff8800372c5280
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 312 data 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5080 front 312
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5080 312 (1966054639) + 0 (0) + 0 (0)
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5080 17 from mds0 26=client_reply len 312+0 (1966054639 0 0) =====
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff880024d45400
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff880024d45400 tid 5
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 5 result 0
Jul 28 21:35:20 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:20 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:20 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:20 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880019972338 ffff880019972338
Jul 28 21:35:20 test09 kernel: ceph: inode.c:946 : fill_trace ffff880024d45400 is_dentry 0 is_target 1
Jul 28 21:35:20 test09 kernel: ceph: inode.c:61 : get_inode on 1=1.fffffffffffffffe got ffff88003ab6cd90
Jul 28 21:35:20 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 44 had 44
Jul 28 21:35:20 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXsFs seq 5
Jul 28 21:35:20 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:20 test09 kernel: ceph: caps.c:498 : marking ffff88003ab6cd90 NOT complete
Jul 28 21:35:20 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXsFs now pAsLsXsFs seq 5 mds0
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:20 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff880024d456e0 count=1
Jul 28 21:35:20 test09 kernel: ceph: caps.c:221 : unreserve caps 3 = 2 used + 0 resv + 1 avail
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 16 -> 17
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:1999 : do_request ffff880024d45400 done, result 0
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5280
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5280
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5080
Jul 28 21:35:20 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5080
Jul 28 21:35:20 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:20 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:20 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff880024d456e0 count=0
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1795 : do_getattr result=0
Jul 28 21:35:20 test09 kernel: ceph: file.c:140 : open inode ffff88003ab6c300 ino 10000000006.fffffffffffffffe file ffff880038321780 flags 32769 (32769)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:20 test09 kernel: ceph: file.c:169 : open ffff88003ab6c300 fmode 2 want pAsxXsxFxwb issued pAsxLsXsxFsxcrwb using existing
Jul 28 21:35:20 test09 kernel: ceph: file.c:75 : init_file ffff88003ab6c300 ffff880038321780 0100644 (regular)
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c300 mask pAsLsXsFs mode 0100644
Jul 28 21:35:20 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c300 cap ffff88001fff4928 mds0
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:20 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask pAsLsXsFs)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:20 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000006 (1/471090b2)
Jul 28 21:35:20 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000006 ffff88003ab6c300 dentry ffff88003b304600
Jul 28 21:35:20 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000006 (1/471090b2)
Jul 28 21:35:20 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000006 ffff88003ab6c300 dentry ffff88003b304600
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1506 : __do_pending_vmtruncate ffff88003ab6c300 none pending
Jul 28 21:35:20 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1593 : setattr ffff88003ab6c300 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1638 : setattr ffff88003ab6c300 atime 1311888919.764556000 -> 1311888920.82728844
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1660 : setattr ffff88003ab6c300 mtime 1311888919.764556000 -> 1311888920.82728844
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1712 : setattr ffff88003ab6c300 ctime 1311888919.764556000 -> 1311888920.82728844 (ignored)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:1349 : __mark_dirty_caps ffff88003ab6c300 Fx dirty - -> Fx
Jul 28 21:35:20 test09 kernel: ceph: caps.c:1356 : inode ffff88003ab6c300 now dirty snapc ffff880026e5eec0
Jul 28 21:35:20 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003ab6c300 min 5469 max 60469
Jul 28 21:35:20 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003ab6c300 flags 0 at 4302603000
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1755 : setattr ffff88003ab6c300 result=0 (Fx locally, 0 remote)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003bb902e0 count=0
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1506 : __do_pending_vmtruncate ffff88003ab6c300 none pending
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c300 mask pAsLsXsFs mode 0100644
Jul 28 21:35:20 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c300 cap ffff88001fff4928 mds0
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c300 mask pAsLsXsFs mode 0100644
Jul 28 21:35:20 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c300 cap ffff88001fff4928 mds0
Jul 28 21:35:20 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000006 (1/471090b2)
Jul 28 21:35:20 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000006 ffff88003ab6c300 dentry ffff88003b304600
Jul 28 21:35:20 test09 kernel: ceph: file.c:273 : release inode ffff88003ab6c300 file ffff880038321780
Jul 28 21:35:20 test09 kernel: ceph: caps.c:2967 : put_fmode ffff88003ab6c300 fmode 2 1 -> 0
Jul 28 21:35:20 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:20 test09 kernel: ceph: caps.c:1518 : check_caps ffff88003ab6c300 file_want - used - dirty Fx flushing - issued pAsxLsXsxFsxcrwb revoking - retain pAsLsXsFs
Jul 28 21:35:20 test09 kernel: ceph: caps.c:1567 : mds0 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb implemented pAsxLsXsxFsxcrwb revoking -
Jul 28 21:35:20 test09 kernel: ceph: caps.c:1618 : delaying issued pAsxLsXsxFsxcrwb -> pAsLsXsFs, wanted - -> -
Jul 28 21:35:20 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003ab6c300 min 5436 max 60436
Jul 28 21:35:20 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003ab6c300 flags 0 at 4302603000
Jul 28 21:35:20 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c300 mask pAsLsXsFs mode 0100644
Jul 28 21:35:20 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c300 cap ffff88001fff4928 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:20 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c300 cap ffff88001fff4928 mds0
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:21 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 2
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:1060 : send_renew_caps to mds0 (up:active)
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5880 front 28
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5880 to mds0 22=client_session len 28+0+0 -----
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (4)
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5880 seq 18 type 22 len 28+0+0 0 pgs
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 2071541657 data_crc 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 95
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 95 left
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (4)
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1398 : got ack for seq 18 type 22 at ffff8800372c5880
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5880
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5880
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1572 : got hdr type 22 front 28 data 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5880 front 28
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5880 28 (56562841) + 0 (0) + 0 (0)
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5880 18 from mds0 22=client_session len 28+0 (56562841 0 0) =====
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:2292 : handle_session mds0 renewcaps ffff88003b72e800 state open seq 12
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:1097 : renewed_caps mds0 ttl now 4302604022, was fresh, now stale
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5880
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5880
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 17 -> 18
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:21 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (2)
Jul 28 21:35:21 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 3 -> 2
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask pAsLsXsFs)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: dir.c:581 : lookup ffff88003ab6cd90 dentry ffff88003a98b540 'foo43'
Jul 28 21:35:24 test09 kernel: ceph: dir.c:1202 : dentry_lru_add ffff880028802550 ffff88003a98b540 'foo43'
Jul 28 21:35:24 test09 kernel: ceph: dir.c:605 : dir ffff88003ab6cd90 flags are 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff880024d45400
Jul 28 21:35:24 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff880024d456e0 need=2
Jul 28 21:35:24 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff880024d456e0 4 = 2 used + 2 resv + 0 avail
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:575 : __register_request ffff880024d45400 tid 6
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=1 (664719831) mode 0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:218 : choose_frag(279ed1d7) = 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 2
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 2 -> 3
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff880024d45400 tid 6 lookup (attempt 1)
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1597 : dentry ffff88003a98b540 1/foo43
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5880 front 119
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = ffff88003ab6cd90
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5880 to mds0 24=client_request len 119+0+0 -----
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (5)
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5880 seq 19 type 24 len 119+0+0 0 pgs
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 3657516034 data_crc 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 186
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 186 left
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 20, queueing work
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (5)
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1398 : got ack for seq 19 type 24 at ffff8800372c5880
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 343 data 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5d80 front 343
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5d80 343 (3144153937) + 0 (0) + 0 (0)
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5d80 19 from mds0 26=client_reply len 343+0 (3144153937 0 0) =====
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff880024d45400
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff880024d45400 tid 6
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 6 result -2
Jul 28 21:35:24 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:24 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:24 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:24 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880018f64f57 ffff880018f64f57
Jul 28 21:35:24 test09 kernel: ceph: inode.c:946 : fill_trace ffff880024d45400 is_dentry 1 is_target 0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 44 had 44
Jul 28 21:35:24 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXsFs seq 6
Jul 28 21:35:24 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs
Jul 28 21:35:24 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXsFs now pAsLsXsFs seq 6 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1072 : fill_trace null dentry
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1077 : d_instantiate ffff88003a98b540 NULL
Jul 28 21:35:24 test09 kernel: ceph: inode.c:813 : update_dentry_lease ffff88003a98b540 duration 0 ms ttl 4302547268
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:24 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff880024d456e0 count=2
Jul 28 21:35:24 test09 kernel: ceph: caps.c:221 : unreserve caps 4 = 2 used + 0 resv + 2 avail
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1999 : do_request ffff880024d45400 done, result -2
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5880
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5880
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5d80
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5d80
Jul 28 21:35:24 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:24 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff880024d456e0 count=0
Jul 28 21:35:24 test09 kernel: ceph: dir.c:635 : lookup result= (null)
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:24 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:24 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:24 test09 kernel: ceph: dir.c:700 : create in dir ffff88003ab6cd90 dentry ffff88003a98b540 name 'foo43'
Jul 28 21:35:24 test09 kernel: ceph: dir.c:674 : mknod in dir ffff88003ab6cd90 dentry ffff88003a98b540 mode 0100644 rdev 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff880024d45400
Jul 28 21:35:24 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff880024d456e0 need=2
Jul 28 21:35:24 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff880024d456e0 4 = 2 used + 2 resv + 0 avail
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:575 : __register_request ffff880024d45400 tid 7
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=1 (664719831) mode 2
Jul 28 21:35:24 test09 kernel: ceph: inode.c:218 : choose_frag(279ed1d7) = 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 3
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff880024d45400 tid 7 mknod (attempt 1)
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1597 : dentry ffff88003a98b540 1/foo43
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5d80 front 168
Jul 28 21:35:24 test09 kernel: ceph: caps.c:3000 : encode_inode_release ffff88003ab6cd90 mds0 used|dirty p drop Fs unless Fx
Jul 28 21:35:24 test09 kernel: ceph: caps.c:3015 : encode_inode_release ffff88003ab6cd90 cap ffff88001fff47a8 pAsLsXsFs -> pAsLsXs
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = ffff88003ab6cd90
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 18 -> 19
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5d80 to mds0 24=client_request len 163+0+0 -----
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:24 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5d80 seq 20 type 24 len 163+0+0 0 pgs
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 4163810636 data_crc 0
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 230
Jul 28 21:35:24 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 230 left
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (5)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1398 : got ack for seq 20 type 24 at ffff8800372c5d80
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 580 data 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5280 front 580
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5280 580 (3075928165) + 0 (0) + 0 (0)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5280 20 from mds0 26=client_reply len 580+0 (3075928165 0 0) =====
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff880024d45400
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 7 result 0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:25 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880029b1ee44 ffff880029b1ee44
Jul 28 21:35:25 test09 kernel: ceph: inode.c:946 : fill_trace ffff880024d45400 is_dentry 1 is_target 1
Jul 28 21:35:25 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 45 had 44
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:25 test09 kernel: ceph: inode.c:618 : ffff88003ab6cd90 mode 040755 uid.gid 0.0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXs seq 7
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:25 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXs now pAsLsXs seq 7 mds0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:299 : alloc_inode ffff88003ab6c848
Jul 28 21:35:25 test09 kernel: ceph: inode.c:56 : get_inode created new inode ffff88003ab6c848 10000000007.fffffffffffffffe ino 10000000007
Jul 28 21:35:25 test09 kernel: ceph: inode.c:61 : get_inode on 1099511627783=10000000007.fffffffffffffffe got ffff88003ab6c848
Jul 28 21:35:25 test09 kernel: ceph: inode.c:913 : dn ffff88003a98b540 attached to ffff88003ab6c848 ino 10000000007.fffffffffffffffe
Jul 28 21:35:25 test09 kernel: ceph: inode.c:813 : update_dentry_lease ffff88003a98b540 duration 30000 ms ttl 4302577352
Jul 28 21:35:25 test09 kernel: ceph: dir.c:1218 : dentry_lru_touch ffff880028802550 ffff88003a98b540 'foo43' (offset 0)
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1123 : final dn ffff88003a98b540
Jul 28 21:35:25 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6c848 ino 10000000007.fffffffffffffffe v 84 had 0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:618 : ffff88003ab6c848 mode 0100644 uid.gid 0.0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:449 : size 0 -> 0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:455 : truncate_seq 0 -> 1
Jul 28 21:35:25 test09 kernel: ceph: inode.c:477 : truncate_size 0 -> 18446744073709551615
Jul 28 21:35:25 test09 kernel: ceph: inode.c:640 : max_size 0 -> 4194304
Jul 28 21:35:25 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6c848 mds0 cap 3 pAsxLsXsxFsxcrwb seq 2
Jul 28 21:35:25 test09 kernel: ceph: caps.c:248 : get_cap ctx=ffff880024d456e0 (2) 4 = 2 used + 2 resv + 0 avail
Jul 28 21:35:25 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:66 : get_realm ffff880024d1cbc0 2 -> 3
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c848 cap ffff88001fff48a8 issued -
Jul 28 21:35:25 test09 kernel: ceph: caps.c:607 : issued pAsxLsXsxFsxcrwb, mds wanted -, actual -, queueing
Jul 28 21:35:25 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003ab6c848 min 5494 max 60494
Jul 28 21:35:25 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003ab6c848 flags 0 at 4302608000
Jul 28 21:35:25 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6c848 (10000000007.fffffffffffffffe) cap ffff88001fff48a8 pAsxLsXsxFsxcrwb now pAsxLsXsxFsxcrwb seq 2 mds0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff880024d456e0 count=1
Jul 28 21:35:25 test09 kernel: ceph: caps.c:221 : unreserve caps 4 = 3 used + 0 resv + 1 avail
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1999 : do_request ffff880024d45400 done, result 0
Jul 28 21:35:25 test09 kernel: ceph: export.c:63 : encode_fh ffff88003a98b540 connectable
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff88003bffb800
Jul 28 21:35:25 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff88003bffbae0 need=1
Jul 28 21:35:25 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff88003bffbae0 4 = 3 used + 1 resv + 0 avail
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:575 : __register_request ffff88003bffb800 tid 8
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=0 (0) mode 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 5
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 6 -> 7
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff88003bffb800 tid 8 getattr (attempt 1)
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1593 : inode ffff88003ab6cd90 1.fffffffffffffffe
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5880 front 114
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = (null)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 19 -> 20
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (6)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5880 to mds0 24=client_request len 114+0+0 -----
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 7 -> 8
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (8)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 8 -> 7
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 7 -> 6
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5880 seq 21 type 24 len 114+0+0 0 pgs
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 3319352177 data_crc 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 181
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 181 left
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (5)
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 6 -> 7
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (7)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1398 : got ack for seq 21 type 24 at ffff8800372c5880
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 312 data 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800371c5ac0 front 312
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800371c5ac0 312 (1677953617) + 0 (0) + 0 (0)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800371c5ac0 21 from mds0 26=client_reply len 312+0 (1677953617 0 0) =====
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff88003bffb800
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff88003bffb800 tid 8
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 8 result 0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:25 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:25 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880037225338 ffff880037225338
Jul 28 21:35:25 test09 kernel: ceph: inode.c:946 : fill_trace ffff88003bffb800 is_dentry 0 is_target 1
Jul 28 21:35:25 test09 kernel: ceph: inode.c:61 : get_inode on 1=1.fffffffffffffffe got ffff88003ab6cd90
Jul 28 21:35:25 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 46 had 45
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:25 test09 kernel: ceph: inode.c:618 : ffff88003ab6cd90 mode 040755 uid.gid 0.0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXsFs seq 8
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:25 test09 kernel: ceph: caps.c:498 : marking ffff88003ab6cd90 NOT complete
Jul 28 21:35:25 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXsFs now pAsLsXsFs seq 8 mds0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003bffbae0 count=1
Jul 28 21:35:25 test09 kernel: ceph: caps.c:221 : unreserve caps 4 = 3 used + 0 resv + 1 avail
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:1999 : do_request ffff88003bffb800 done, result 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5880
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5880
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800371c5ac0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800371c5ac0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 7 -> 6
Jul 28 21:35:25 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003bffbae0 count=0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1795 : do_getattr result=0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: ceph: file.c:140 : open inode ffff88003ab6c848 ino 10000000007.fffffffffffffffe file ffff88003955e480 flags 32769 (32769)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:25 test09 kernel: ceph: file.c:169 : open ffff88003ab6c848 fmode 2 want pAsxXsxFxwb issued pAsxLsXsxFsxcrwb using existing
Jul 28 21:35:25 test09 kernel: ceph: file.c:75 : init_file ffff88003ab6c848 ffff88003955e480 0100644 (regular)
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c848 mask pAsLsXsFs mode 0100644
Jul 28 21:35:25 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c848 cap ffff88001fff48a8 mds0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:25 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask pAsLsXsFs)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:25 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000007 (1/279ed1d7)
Jul 28 21:35:25 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000007 ffff88003ab6c848 dentry ffff88003a98b540
Jul 28 21:35:25 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000007 (1/279ed1d7)
Jul 28 21:35:25 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000007 ffff88003ab6c848 dentry ffff88003a98b540
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1506 : __do_pending_vmtruncate ffff88003ab6c848 none pending
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1593 : setattr ffff88003ab6c848 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1638 : setattr ffff88003ab6c848 atime 1311888925.1595000 -> 1311888925.234728664
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1660 : setattr ffff88003ab6c848 mtime 1311888925.1595000 -> 1311888925.234728664
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1712 : setattr ffff88003ab6c848 ctime 1311888925.1595000 -> 1311888925.234728664 (ignored)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:1349 : __mark_dirty_caps ffff88003ab6c848 Fx dirty - -> Fx
Jul 28 21:35:25 test09 kernel: ceph: caps.c:1356 : inode ffff88003ab6c848 now dirty snapc ffff880026e5eec0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003ab6c848 min 5328 max 60328
Jul 28 21:35:25 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003ab6c848 flags 0 at 4302608000
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1755 : setattr ffff88003ab6c848 result=0 (Fx locally, 0 remote)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88002863eae0 count=0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1506 : __do_pending_vmtruncate ffff88003ab6c848 none pending
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c848 mask pAsLsXsFs mode 0100644
Jul 28 21:35:25 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c848 cap ffff88001fff48a8 mds0
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c848 mask pAsLsXsFs mode 0100644
Jul 28 21:35:25 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c848 cap ffff88001fff48a8 mds0
Jul 28 21:35:25 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000007 (1/279ed1d7)
Jul 28 21:35:25 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000007 ffff88003ab6c848 dentry ffff88003a98b540
Jul 28 21:35:25 test09 kernel: ceph: file.c:273 : release inode ffff88003ab6c848 file ffff88003955e480
Jul 28 21:35:25 test09 kernel: ceph: caps.c:2967 : put_fmode ffff88003ab6c848 fmode 2 1 -> 0
Jul 28 21:35:25 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:25 test09 kernel: ceph: caps.c:1518 : check_caps ffff88003ab6c848 file_want - used - dirty Fx flushing - issued pAsxLsXsxFsxcrwb revoking - retain pAsLsXsFs
Jul 28 21:35:25 test09 kernel: ceph: caps.c:1567 : mds0 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb implemented pAsxLsXsxFsxcrwb revoking -
Jul 28 21:35:25 test09 kernel: ceph: caps.c:1618 : delaying issued pAsxLsXsxFsxcrwb -> pAsLsXsFs, wanted - -> -
Jul 28 21:35:25 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003ab6c848 min 5328 max 60328
Jul 28 21:35:25 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003ab6c848 flags 0 at 4302608000
Jul 28 21:35:25 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6c848 mask pAsLsXsFs mode 0100644
Jul 28 21:35:25 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6c848 cap ffff88001fff48a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:25 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6c848 cap ffff88001fff48a8 mds0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 27 data 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800371c5ac0 front 27
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800371c5ac0 27 (3512814663) + 0 (0) + 0 (0)
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800371c5ac0 22 from mds0 26=client_reply len 27+0 (3512814663 0 0) =====
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff880024d45400
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff880024d45400 tid 7
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:2135 : got safe reply 7, mds0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5d80
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5d80
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5280
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5280
Jul 28 21:35:25 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:25 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff880024d456e0 count=0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800371c5ac0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800371c5ac0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 20 -> 22
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:25 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:25 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:26 test09 kernel: libceph: mon_client.c:698 : monc delayed_work
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003c11e000
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:393 : con_get ffff88003c11e000 nref = 1 -> 2
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003c11e000
Jul 28 21:35:26 test09 kernel: libceph: mon_client.c:190 : __send_subscribe sub_sent=0 exp=0 want_osd=0
Jul 28 21:35:26 test09 kernel: libceph: mon_client.c:179 : __schedule_delayed after 20000
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003c11e000
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003c11e000 ret 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003c11e000 state 29 nref 2
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003c11e000
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003c11e000 1 left
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003c11e000 0 left in 0 kvecs ret = 1
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003c11e000 ret 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:402 : con_put ffff88003c11e000 nref = 2 -> 1
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:26 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 3
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003b72e840
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (5)
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:26 test09 kernel: libceph: osd_client.c:1145 : osds timeout
Jul 28 21:35:26 test09 kernel: libceph: osd_client.c:708 : __remove_old_osds ffff88003bb90da8
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 28 nref 1
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003b72e840
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 1 left
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:26 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (3)
Jul 28 21:35:26 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 4 -> 3
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask pAsLsXsFs)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: dir.c:581 : lookup ffff88003ab6cd90 dentry ffff88003a9da800 'foo44'
Jul 28 21:35:27 test09 kernel: ceph: dir.c:1202 : dentry_lru_add ffff880028802500 ffff88003a9da800 'foo44'
Jul 28 21:35:27 test09 kernel: ceph: dir.c:605 : dir ffff88003ab6cd90 flags are 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff8800372bc400
Jul 28 21:35:27 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff8800372bc6e0 need=2
Jul 28 21:35:27 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff8800372bc6e0 5 = 3 used + 2 resv + 0 avail
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:575 : __register_request ffff8800372bc400 tid 9
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=1 (-2030282693) mode 0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:218 : choose_frag(86fc583b) = 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 3
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 3 -> 4
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff8800372bc400 tid 9 lookup (attempt 1)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1597 : dentry ffff88003a9da800 1/foo44
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5e80 front 119
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = ffff88003ab6cd90
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5e80 to mds0 24=client_request len 119+0+0 -----
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5e80 seq 22 type 24 len 119+0+0 0 pgs
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 1385457402 data_crc 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 186
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 186 left
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (5)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1398 : got ack for seq 22 type 24 at ffff8800372c5e80
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 343 data 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5180 front 343
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5180 343 (2691423894) + 0 (0) + 0 (0)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5180 23 from mds0 26=client_reply len 343+0 (2691423894 0 0) =====
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff8800372bc400
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff8800372bc400 tid 9
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 9 result -2
Jul 28 21:35:27 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:27 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:27 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:27 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff88003bc94157 ffff88003bc94157
Jul 28 21:35:27 test09 kernel: ceph: inode.c:946 : fill_trace ffff8800372bc400 is_dentry 1 is_target 0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 46 had 46
Jul 28 21:35:27 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXsFs seq 9
Jul 28 21:35:27 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs
Jul 28 21:35:27 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXsFs now pAsLsXsFs seq 9 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1072 : fill_trace null dentry
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1077 : d_instantiate ffff88003a9da800 NULL
Jul 28 21:35:27 test09 kernel: ceph: inode.c:813 : update_dentry_lease ffff88003a9da800 duration 0 ms ttl 4302550113
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:27 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff8800372bc6e0 count=2
Jul 28 21:35:27 test09 kernel: ceph: caps.c:221 : unreserve caps 5 = 3 used + 0 resv + 2 avail
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1999 : do_request ffff8800372bc400 done, result -2
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5e80
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5e80
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5180
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5180
Jul 28 21:35:27 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:27 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff8800372bc6e0 count=0
Jul 28 21:35:27 test09 kernel: ceph: dir.c:635 : lookup result= (null)
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask As mode 040755
Jul 28 21:35:27 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask As)
Jul 28 21:35:27 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:27 test09 kernel: ceph: dir.c:700 : create in dir ffff88003ab6cd90 dentry ffff88003a9da800 name 'foo44'
Jul 28 21:35:27 test09 kernel: ceph: dir.c:674 : mknod in dir ffff88003ab6cd90 dentry ffff88003a9da800 mode 0100644 rdev 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff8800372bc400
Jul 28 21:35:27 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff8800372bc6e0 need=2
Jul 28 21:35:27 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff8800372bc6e0 5 = 3 used + 2 resv + 0 avail
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:575 : __register_request ffff8800372bc400 tid 10
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=1 (-2030282693) mode 2
Jul 28 21:35:27 test09 kernel: ceph: inode.c:218 : choose_frag(86fc583b) = 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 4
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff8800372bc400 tid 10 mknod (attempt 1)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1597 : dentry ffff88003a9da800 1/foo44
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5180 front 168
Jul 28 21:35:27 test09 kernel: ceph: caps.c:3000 : encode_inode_release ffff88003ab6cd90 mds0 used|dirty p drop Fs unless Fx
Jul 28 21:35:27 test09 kernel: ceph: caps.c:3015 : encode_inode_release ffff88003ab6cd90 cap ffff88001fff47a8 pAsLsXsFs -> pAsLsXs
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = ffff88003ab6cd90
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 22 -> 23
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (5)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5180 to mds0 24=client_request len 163+0+0 -----
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 6 -> 7
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (7)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 7 -> 6
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5180 seq 23 type 24 len 163+0+0 0 pgs
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 2791128136 data_crc 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 230
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 230 left
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (5)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1398 : got ack for seq 23 type 24 at ffff8800372c5180
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 580 data 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5080 front 580
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800372c5080 580 (2743597094) + 0 (0) + 0 (0)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800372c5080 24 from mds0 26=client_reply len 580+0 (2743597094 0 0) =====
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff8800372bc400
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 10 result 0
Jul 28 21:35:27 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:27 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:27 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:27 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff88003ac91a44 ffff88003ac91a44
Jul 28 21:35:27 test09 kernel: ceph: inode.c:946 : fill_trace ffff8800372bc400 is_dentry 1 is_target 1
Jul 28 21:35:27 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 47 had 46
Jul 28 21:35:27 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:27 test09 kernel: ceph: inode.c:618 : ffff88003ab6cd90 mode 040755 uid.gid 0.0
Jul 28 21:35:27 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXs seq 10
Jul 28 21:35:27 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:27 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXs now pAsLsXs seq 10 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:299 : alloc_inode ffff88003aaa4d90
Jul 28 21:35:27 test09 kernel: ceph: inode.c:56 : get_inode created new inode ffff88003aaa4d90 10000000008.fffffffffffffffe ino 10000000008
Jul 28 21:35:27 test09 kernel: ceph: inode.c:61 : get_inode on 1099511627784=10000000008.fffffffffffffffe got ffff88003aaa4d90
Jul 28 21:35:27 test09 kernel: ceph: inode.c:913 : dn ffff88003a9da800 attached to ffff88003aaa4d90 ino 10000000008.fffffffffffffffe
Jul 28 21:35:27 test09 kernel: ceph: inode.c:813 : update_dentry_lease ffff88003a9da800 duration 30000 ms ttl 4302580198
Jul 28 21:35:27 test09 kernel: ceph: dir.c:1218 : dentry_lru_touch ffff880028802500 ffff88003a9da800 'foo44' (offset 0)
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1123 : final dn ffff88003a9da800
Jul 28 21:35:27 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003aaa4d90 ino 10000000008.fffffffffffffffe v 88 had 0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:618 : ffff88003aaa4d90 mode 0100644 uid.gid 0.0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:449 : size 0 -> 0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:455 : truncate_seq 0 -> 1
Jul 28 21:35:27 test09 kernel: ceph: inode.c:477 : truncate_size 0 -> 18446744073709551615
Jul 28 21:35:27 test09 kernel: ceph: inode.c:640 : max_size 0 -> 4194304
Jul 28 21:35:27 test09 kernel: ceph: caps.c:527 : add_cap ffff88003aaa4d90 mds0 cap 4 pAsxLsXsxFsxcrwb seq 2
Jul 28 21:35:27 test09 kernel: ceph: caps.c:248 : get_cap ctx=ffff8800372bc6e0 (2) 5 = 3 used + 2 resv + 0 avail
Jul 28 21:35:27 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:27 test09 kernel: ceph: snap.c:66 : get_realm ffff880024d1cbc0 3 -> 4
Jul 28 21:35:27 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003aaa4d90 cap ffff88001fff40a8 issued -
Jul 28 21:35:27 test09 kernel: ceph: caps.c:607 : issued pAsxLsXsxFsxcrwb, mds wanted -, actual -, queueing
Jul 28 21:35:27 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003aaa4d90 min 5648 max 60648
Jul 28 21:35:27 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003aaa4d90 flags 0 at 4302611000
Jul 28 21:35:27 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003aaa4d90 (10000000008.fffffffffffffffe) cap ffff88001fff40a8 pAsxLsXsxFsxcrwb now pAsxLsXsxFsxcrwb seq 2 mds0
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:27 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff8800372bc6e0 count=1
Jul 28 21:35:27 test09 kernel: ceph: caps.c:221 : unreserve caps 5 = 4 used + 0 resv + 1 avail
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1999 : do_request ffff8800372bc400 done, result 0
Jul 28 21:35:27 test09 kernel: ceph: export.c:63 : encode_fh ffff88003a9da800 connectable
Jul 28 21:35:27 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1937 : do_request on ffff88002863e800
Jul 28 21:35:27 test09 kernel: ceph: caps.c:160 : reserve caps ctx=ffff88002863eae0 need=1
Jul 28 21:35:27 test09 kernel: ceph: caps.c:199 : reserve caps ctx=ffff88002863eae0 5 = 4 used + 1 resv + 0 avail
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:575 : __register_request ffff88002863e800 tid 11
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:690 : __choose_mds ffff88003ab6cd90 is_hash=0 (0) mode 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:748 : choose_mds ffff88003ab6cd90 1.fffffffffffffffe mds0 (auth cap ffff88001fff47a8)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 6
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 6 -> 7
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 7 -> 8
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1848 : do_request mds0 session ffff88003b72e800 state open
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1751 : prepare_send_request ffff88002863e800 tid 11 getattr (attempt 1)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1593 : inode ffff88003ab6cd90 1.fffffffffffffffe
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800372c5e80 front 114
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1802 : r_locked_dir = (null)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 23 -> 24
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (7)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:2200 : ----- ffff8800372c5e80 to mds0 24=client_request len 114+0+0 -----
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 8 -> 9
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (9)
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 9 -> 8
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:1962 : do_request waiting
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 8 -> 7
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 20 nref 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:507 : prepare_write_message ffff8800372c5e80 seq 24 type 24 len 114+0+0 0 pgs
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:539 : prepare_write_message front_crc 1197424424 data_crc 0
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:452 : prepare_write_message_footer ffff88003b72e840
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 181
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 181 left
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:27 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (6)
Jul 28 21:35:27 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 7 -> 6
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 6 -> 7
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (7)
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:133 : ceph_data_ready on ffff88003b72e840 state = 4, queueing work
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 7 -> 8
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (8)
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1882 : try_read got tag 8
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:940 : prepare_read_ack ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1398 : got ack for seq 24 type 24 at ffff8800372c5e80
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 312 data 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800371c5ac0 front 312
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800371c5ac0 312 (4224734411) + 0 (0) + 0 (0)
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800371c5ac0 25 from mds0 26=client_reply len 312+0 (4224734411 0 0) =====
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff88002863e800
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff88002863e800 tid 11
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:2149 : handle_reply tid 11 result 0
Jul 28 21:35:28 test09 kernel: ceph: snap.c:631 : update_snap_trace deletion=0
Jul 28 21:35:28 test09 kernel: ceph: snap.c:150 : lookup_snap_realm 1 ffff880024d1cbc0
Jul 28 21:35:28 test09 kernel: ceph: snap.c:688 : update_snap_trace 1 ffff880024d1cbc0 seq 1 unchanged
Jul 28 21:35:28 test09 kernel: ceph: snap.c:692 : done with 1 ffff880024d1cbc0, invalidated=0, ffff880018f64738 ffff880018f64738
Jul 28 21:35:28 test09 kernel: ceph: inode.c:946 : fill_trace ffff88002863e800 is_dentry 0 is_target 1
Jul 28 21:35:28 test09 kernel: ceph: inode.c:61 : get_inode on 1=1.fffffffffffffffe got ffff88003ab6cd90
Jul 28 21:35:28 test09 kernel: ceph: inode.c:573 : fill_inode ffff88003ab6cd90 ino 1.fffffffffffffffe v 48 had 47
Jul 28 21:35:28 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:28 test09 kernel: ceph: inode.c:618 : ffff88003ab6cd90 mode 040755 uid.gid 0.0
Jul 28 21:35:28 test09 kernel: ceph: caps.c:527 : add_cap ffff88003ab6cd90 mds0 cap 1 pAsLsXsFs seq 11
Jul 28 21:35:28 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXs
Jul 28 21:35:28 test09 kernel: ceph: caps.c:498 : marking ffff88003ab6cd90 NOT complete
Jul 28 21:35:28 test09 kernel: ceph: caps.c:618 : add_cap inode ffff88003ab6cd90 (1.fffffffffffffffe) cap ffff88001fff47a8 pAsLsXsFs now pAsLsXsFs seq 11 mds0
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1183 : fill_trace done err=0
Jul 28 21:35:28 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88002863eae0 count=1
Jul 28 21:35:28 test09 kernel: ceph: caps.c:221 : unreserve caps 5 = 4 used + 0 resv + 1 avail
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:1971 : do_request waited, got 0
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:1999 : do_request ffff88002863e800 done, result 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5e80
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5e80
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800371c5ac0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800371c5ac0
Jul 28 21:35:28 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 8 -> 7
Jul 28 21:35:28 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88002863eae0 count=0
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1795 : do_getattr result=0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:28 test09 kernel: ceph: file.c:140 : open inode ffff88003aaa4d90 ino 10000000008.fffffffffffffffe file ffff88003955e480 flags 32769 (32769)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:28 test09 kernel: ceph: file.c:169 : open ffff88003aaa4d90 fmode 2 want pAsxXsxFxwb issued pAsxLsXsxFsxcrwb using existing
Jul 28 21:35:28 test09 kernel: ceph: file.c:75 : init_file ffff88003aaa4d90 ffff88003955e480 0100644 (regular)
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003aaa4d90 mask pAsLsXsFs mode 0100644
Jul 28 21:35:28 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003aaa4d90 cap ffff88001fff40a8 mds0
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003ab6cd90 mask pAsLsXsFs mode 040755
Jul 28 21:35:28 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003ab6cd90 cap ffff88001fff47a8 issued pAsLsXsFs (mask pAsLsXsFs)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003ab6cd90 cap ffff88001fff47a8 mds0
Jul 28 21:35:28 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000008 (1/86fc583b)
Jul 28 21:35:28 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000008 ffff88003aaa4d90 dentry ffff88003a9da800
Jul 28 21:35:28 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000008 (1/86fc583b)
Jul 28 21:35:28 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000008 ffff88003aaa4d90 dentry ffff88003a9da800
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1506 : __do_pending_vmtruncate ffff88003aaa4d90 none pending
Jul 28 21:35:28 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1593 : setattr ffff88003aaa4d90 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1638 : setattr ffff88003aaa4d90 atime 1311888927.847888000 -> 1311888928.83728565
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1660 : setattr ffff88003aaa4d90 mtime 1311888927.847888000 -> 1311888928.83728565
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1712 : setattr ffff88003aaa4d90 ctime 1311888927.847888000 -> 1311888928.83728565 (ignored)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:1349 : __mark_dirty_caps ffff88003aaa4d90 Fx dirty - -> Fx
Jul 28 21:35:28 test09 kernel: ceph: caps.c:1356 : inode ffff88003aaa4d90 now dirty snapc ffff880026e5eec0
Jul 28 21:35:28 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003aaa4d90 min 5479 max 60479
Jul 28 21:35:28 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003aaa4d90 flags 0 at 4302611000
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1755 : setattr ffff88003aaa4d90 result=0 (Fx locally, 0 remote)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff88003aeebae0 count=0
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1506 : __do_pending_vmtruncate ffff88003aaa4d90 none pending
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003aaa4d90 mask pAsLsXsFs mode 0100644
Jul 28 21:35:28 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003aaa4d90 cap ffff88001fff40a8 mds0
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003aaa4d90 mask pAsLsXsFs mode 0100644
Jul 28 21:35:28 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003aaa4d90 cap ffff88001fff40a8 mds0
Jul 28 21:35:28 test09 kernel: ceph: export.c:155 : __cfh_to_dentry 10000000008 (1/86fc583b)
Jul 28 21:35:28 test09 kernel: ceph: export.c:195 : __cfh_to_dentry 10000000008 ffff88003aaa4d90 dentry ffff88003a9da800
Jul 28 21:35:28 test09 kernel: ceph: file.c:273 : release inode ffff88003aaa4d90 file ffff88003955e480
Jul 28 21:35:28 test09 kernel: ceph: caps.c:2967 : put_fmode ffff88003aaa4d90 fmode 2 1 -> 0
Jul 28 21:35:28 test09 kernel: ceph: caps.c:678 : __ceph_caps_issued ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb
Jul 28 21:35:28 test09 kernel: ceph: caps.c:1518 : check_caps ffff88003aaa4d90 file_want - used - dirty Fx flushing - issued pAsxLsXsxFsxcrwb revoking - retain pAsLsXsFs
Jul 28 21:35:28 test09 kernel: ceph: caps.c:1567 : mds0 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb implemented pAsxLsXsxFsxcrwb revoking -
Jul 28 21:35:28 test09 kernel: ceph: caps.c:1618 : delaying issued pAsxLsXsxFsxcrwb -> pAsLsXsFs, wanted - -> -
Jul 28 21:35:28 test09 kernel: ceph: caps.c:410 : __cap_set_timeouts ffff88003aaa4d90 min 5479 max 60479
Jul 28 21:35:28 test09 kernel: ceph: caps.c:426 : __cap_delay_requeue ffff88003aaa4d90 flags 0 at 4302611000
Jul 28 21:35:28 test09 kernel: ceph: inode.c:1782 : do_getattr inode ffff88003aaa4d90 mask pAsLsXsFs mode 0100644
Jul 28 21:35:28 test09 kernel: ceph: caps.c:753 : __ceph_caps_issued_mask ffff88003aaa4d90 cap ffff88001fff40a8 issued pAsxLsXsxFsxcrwb (mask pAsLsXsFs)
Jul 28 21:35:28 test09 kernel: ceph: caps.c:717 : __touch_cap ffff88003aaa4d90 cap ffff88001fff40a8 mds0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1882 : try_read got tag 7
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:956 : prepare_read_message ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1519 : read_partial_message con ffff88003b72e840 msg (null)
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1572 : got hdr type 26 front 27 data 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2344 : ceph_msg_new ffff8800371c5ac0 front 27
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1656 : read_partial_message got msg ffff8800371c5ac0 27 (3512814663) + 0 (0) + 0 (0)
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1706 : ===== ffff8800371c5ac0 26 from mds0 26=client_reply len 27+0 (3512814663 0 0) =====
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:2056 : handle_reply ffff8800372bc400
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:596 : __unregister_request ffff8800372bc400 tid 10
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:2135 : got safe reply 10, mds0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5180
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5180
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800372c5080
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800372c5080
Jul 28 21:35:28 test09 kernel: ceph: caps.c:2199 : put_cap_refs ffff88003ab6cd90 had p
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 7 -> 6
Jul 28 21:35:28 test09 kernel: ceph: caps.c:212 : unreserve caps ctx=ffff8800372bc6e0 count=0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2442 : ceph_msg_put last one on ffff8800371c5ac0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:2427 : msg_kfree ffff8800371c5ac0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:946 : prepare_read_tag ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:566 : prepare_write_ack ffff88003b72e840 24 -> 26
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 9
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 9 left
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (5)
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 4 nref 1
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:28 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:28 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:31 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 4
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003b72e840
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 28 nref 1
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003b72e840
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 1 left
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:31 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:31 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:2938 : mdsc delayed_work
Jul 28 21:35:36 test09 kernel: ceph: caps.c:2911 : check_delayed_caps
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:355 : lookup_mds_session ffff88003b72e800 4
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 4 -> 5
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:2271 : con_keepalive ffff88003b72e840
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:322 : mdsc get_session ffff88003b72e800 5 -> 6
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:3318 : mdsc con_get ffff88003b72e800 ok (6)
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1957 : queue_con ffff88003b72e840
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:1223 : add_cap_releases ffff88003b72e800 mds0 extra 680
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:1329 : send_cap_releases mds0
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 6 -> 5
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1824 : try_read start on ffff88003b72e840
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1828 : try_read tag 1 in_base_pos 0
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1925 : try_read done on ffff88003b72e840 ret 0
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1724 : try_write start ffff88003b72e840 state 28 nref 1
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 0
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:586 : prepare_write_keepalive ffff88003b72e840
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1727 : try_write out_kvec_bytes 1
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:705 : write_partial_kvec ffff88003b72e840 1 left
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:733 : write_partial_kvec ffff88003b72e840 0 left in 0 kvecs ret = 1
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1802 : try_write nothing else to write.
Jul 28 21:35:36 test09 kernel: libceph: messenger.c:1805 : try_write done on ffff88003b72e840 ret 0
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:3329 : mdsc con_put ffff88003b72e800 (4)
Jul 28 21:35:36 test09 kernel: ceph: mds_client.c:333 : mdsc put_session ffff88003b72e800 5 -> 4
(4-4/4)