FAQ SearchLogin
Tuxera Home
View unanswered posts | View active topics It is currently Mon Jun 21, 2021 02:59



Post new topic Reply to topic  [ 12 posts ] 
task mount.ntfs-3g blocked for more than 120 seconds. 
Author Message

Joined: Sat May 14, 2011 14:54
Posts: 7
Post task mount.ntfs-3g blocked for more than 120 seconds.
Need some advise. I'm trying to copy a file from one NTFS disk to another. The system becomes irresponsive for about 20 minutes, HDD led is on all this time.

file size: 300Mb
source: 20Gb Truecrypt image with NTFS located on NTFS partition on USB HDD.
dest: 20Gb Truecrypt image with NTFS located on NTFS partition on laptop HDD
Checked with chkdsk, all fine.

kernel: 3.0.3
ntfs-3g 2011.4.12 external FUSE 28
Archlinux 64bit
2.75Gb RAM

Code:
~12:15 cp -a /media/myprivate{,backup}/Docs/Mail/Voyager/MAIL/autobackup.tbk

Aug 21 12:17:30 localhost kernel: [59059.003863] INFO: task mount.ntfs-3g:17037 blocked for more than 120 seconds.
Aug 21 12:17:30 localhost kernel: [59059.003870] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:17:30 localhost kernel: [59059.003875] mount.ntfs-3g   D 0000000000000002     0 17037      1 0x00000000
Aug 21 12:17:30 localhost kernel: [59059.003884]  ffff880102e2fad8 0000000000000086 ffff880102e2fa18 ffffffff8105400a
Aug 21 12:17:30 localhost kernel: [59059.003893]  ffff8801150a8730 ffff880102e2ffd8 ffff880102e2ffd8 ffff880102e2ffd8
Aug 21 12:17:30 localhost kernel: [59059.003900]  ffff88007a002b20 ffff8801150a8730 ffff880116a7ab20 0000000000000086
Aug 21 12:17:30 localhost kernel: [59059.003908] Call Trace:
Aug 21 12:17:30 localhost kernel: [59059.003922]  [<ffffffff8105400a>] ? check_preempt_wakeup+0x16a/0x230
Aug 21 12:17:30 localhost kernel: [59059.003932]  [<ffffffff8104b644>] ? check_preempt_curr+0x84/0xa0
Aug 21 12:17:30 localhost kernel: [59059.003938]  [<ffffffff8105498d>] ? ttwu_do_wakeup+0x3d/0x140
Aug 21 12:17:30 localhost kernel: [59059.003949]  [<ffffffff81012eb9>] ? read_tsc+0x9/0x20
Aug 21 12:17:30 localhost kernel: [59059.003954]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:17:30 localhost kernel: [59059.003959]  [<ffffffff810f7820>] ? __lock_page+0x70/0x70
Aug 21 12:17:30 localhost kernel: [59059.003964]  [<ffffffff813f0c2f>] io_schedule+0x8f/0xd0
Aug 21 12:17:30 localhost kernel: [59059.003966]  [<ffffffff810f782e>] sleep_on_page+0xe/0x20
Aug 21 12:17:30 localhost kernel: [59059.003968]  [<ffffffff813f14cf>] __wait_on_bit+0x5f/0x90
Aug 21 12:17:30 localhost kernel: [59059.003973]  [<ffffffff8103e270>] ? get_user_pages_fast+0xe0/0x1a0
Aug 21 12:17:30 localhost kernel: [59059.003976]  [<ffffffff810f7a18>] wait_on_page_bit+0x78/0x80
Aug 21 12:17:30 localhost kernel: [59059.003980]  [<ffffffff8107f6c0>] ? autoremove_wake_function+0x40/0x40
Aug 21 12:17:30 localhost kernel: [59059.003983]  [<ffffffff810f8345>] grab_cache_page_write_begin+0x95/0xe0
Aug 21 12:17:30 localhost kernel: [59059.003987]  [<ffffffff8118c320>] ? thaw_bdev+0x90/0x90
Aug 21 12:17:30 localhost kernel: [59059.003991]  [<ffffffff81188218>] block_write_begin+0x38/0xa0
Aug 21 12:17:30 localhost kernel: [59059.003994]  [<ffffffff8118bf23>] blkdev_write_begin+0x23/0x30
Aug 21 12:17:30 localhost kernel: [59059.003997]  [<ffffffff810f7dec>] generic_file_buffered_write+0x10c/0x270
Aug 21 12:17:30 localhost kernel: [59059.003999]  [<ffffffff810f8f89>] __generic_file_aio_write+0x229/0x440
Aug 21 12:17:30 localhost kernel: [59059.004010]  [<ffffffffa04f13a0>] ? fuse_dev_read+0x70/0x80 [fuse]
Aug 21 12:17:30 localhost kernel: [59059.004012]  [<ffffffff8118c4f3>] blkdev_aio_write+0x33/0x90
Aug 21 12:17:30 localhost kernel: [59059.004017]  [<ffffffff81157012>] do_sync_write+0xd2/0x110
Aug 21 12:17:30 localhost kernel: [59059.004022]  [<ffffffff811df66c>] ? security_file_permission+0x2c/0xb0
Aug 21 12:17:30 localhost kernel: [59059.004025]  [<ffffffff81157461>] ? rw_verify_area+0x61/0xf0
Aug 21 12:17:30 localhost kernel: [59059.004027]  [<ffffffff811577bf>] vfs_write+0xaf/0x180
Aug 21 12:17:30 localhost kernel: [59059.004029]  [<ffffffff81157c62>] sys_pwrite64+0x82/0xb0
Aug 21 12:17:30 localhost kernel: [59059.004034]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b
Aug 21 12:19:30 localhost kernel: [59178.797321] INFO: task mount.ntfs-3g:17037 blocked for more than 120 seconds.
Aug 21 12:19:30 localhost kernel: [59178.797326] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:19:30 localhost kernel: [59178.797330] mount.ntfs-3g   D 0000000000000002     0 17037      1 0x00000000
Aug 21 12:19:30 localhost kernel: [59178.797336]  ffff880102e2fad8 0000000000000086 ffff880102e2fa18 ffffffff8105400a
Aug 21 12:19:30 localhost kernel: [59178.797342]  ffff8801150a8730 ffff880102e2ffd8 ffff880102e2ffd8 ffff880102e2ffd8
Aug 21 12:19:30 localhost kernel: [59178.797347]  ffff88007a002b20 ffff8801150a8730 ffff880116a7ab20 0000000000000086
Aug 21 12:19:30 localhost kernel: [59178.797352] Call Trace:
Aug 21 12:19:30 localhost kernel: [59178.797363]  [<ffffffff8105400a>] ? check_preempt_wakeup+0x16a/0x230
Aug 21 12:19:30 localhost kernel: [59178.797370]  [<ffffffff8104b644>] ? check_preempt_curr+0x84/0xa0
Aug 21 12:19:30 localhost kernel: [59178.797374]  [<ffffffff8105498d>] ? ttwu_do_wakeup+0x3d/0x140
Aug 21 12:19:30 localhost kernel: [59178.797380]  [<ffffffff81012eb9>] ? read_tsc+0x9/0x20
Aug 21 12:19:30 localhost kernel: [59178.797386]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:19:30 localhost kernel: [59178.797394]  [<ffffffff810f7820>] ? __lock_page+0x70/0x70
Aug 21 12:19:30 localhost kernel: [59178.797403]  [<ffffffff813f0c2f>] io_schedule+0x8f/0xd0
Aug 21 12:19:30 localhost kernel: [59178.797405]  [<ffffffff810f782e>] sleep_on_page+0xe/0x20
Aug 21 12:19:30 localhost kernel: [59178.797408]  [<ffffffff813f14cf>] __wait_on_bit+0x5f/0x90
Aug 21 12:19:30 localhost kernel: [59178.797412]  [<ffffffff8103e270>] ? get_user_pages_fast+0xe0/0x1a0
Aug 21 12:19:30 localhost kernel: [59178.797415]  [<ffffffff810f7a18>] wait_on_page_bit+0x78/0x80
Aug 21 12:19:30 localhost kernel: [59178.797419]  [<ffffffff8107f6c0>] ? autoremove_wake_function+0x40/0x40
Aug 21 12:19:30 localhost kernel: [59178.797422]  [<ffffffff810f8345>] grab_cache_page_write_begin+0x95/0xe0
Aug 21 12:19:30 localhost kernel: [59178.797432]  [<ffffffff8118c320>] ? thaw_bdev+0x90/0x90
Aug 21 12:19:30 localhost kernel: [59178.797436]  [<ffffffff81188218>] block_write_begin+0x38/0xa0
Aug 21 12:19:30 localhost kernel: [59178.797438]  [<ffffffff8118bf23>] blkdev_write_begin+0x23/0x30
Aug 21 12:19:30 localhost kernel: [59178.797441]  [<ffffffff810f7dec>] generic_file_buffered_write+0x10c/0x270
Aug 21 12:19:30 localhost kernel: [59178.797444]  [<ffffffff810f8f89>] __generic_file_aio_write+0x229/0x440
Aug 21 12:19:30 localhost kernel: [59178.797454]  [<ffffffffa04f13a0>] ? fuse_dev_read+0x70/0x80 [fuse]
Aug 21 12:19:30 localhost kernel: [59178.797457]  [<ffffffff8118c4f3>] blkdev_aio_write+0x33/0x90
Aug 21 12:19:30 localhost kernel: [59178.797461]  [<ffffffff81157012>] do_sync_write+0xd2/0x110
Aug 21 12:19:30 localhost kernel: [59178.797466]  [<ffffffff811df66c>] ? security_file_permission+0x2c/0xb0
Aug 21 12:19:30 localhost kernel: [59178.797469]  [<ffffffff81157461>] ? rw_verify_area+0x61/0xf0
Aug 21 12:19:30 localhost kernel: [59178.797471]  [<ffffffff811577bf>] vfs_write+0xaf/0x180
Aug 21 12:19:30 localhost kernel: [59178.797473]  [<ffffffff81157c62>] sys_pwrite64+0x82/0xb0
Aug 21 12:19:30 localhost kernel: [59178.797477]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b
Aug 21 12:21:01 localhost -- MARK --
Aug 21 12:21:30 localhost kernel: [59298.590821] INFO: task mount.ntfs-3g:17037 blocked for more than 120 seconds.
Aug 21 12:21:30 localhost kernel: [59298.590826] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:21:30 localhost kernel: [59298.590830] mount.ntfs-3g   D 0000000000000002     0 17037      1 0x00000000
Aug 21 12:21:30 localhost kernel: [59298.590835]  ffff880102e2fad8 0000000000000086 ffff880102e2fa18 ffffffff8105400a
Aug 21 12:21:30 localhost kernel: [59298.590841]  ffff8801150a8730 ffff880102e2ffd8 ffff880102e2ffd8 ffff880102e2ffd8
Aug 21 12:21:30 localhost kernel: [59298.590846]  ffff88007a002b20 ffff8801150a8730 ffff880116a7ab20 0000000000000086
Aug 21 12:21:30 localhost kernel: [59298.590852] Call Trace:
Aug 21 12:21:30 localhost kernel: [59298.590862]  [<ffffffff8105400a>] ? check_preempt_wakeup+0x16a/0x230
Aug 21 12:21:30 localhost kernel: [59298.590869]  [<ffffffff8104b644>] ? check_preempt_curr+0x84/0xa0
Aug 21 12:21:30 localhost kernel: [59298.590873]  [<ffffffff8105498d>] ? ttwu_do_wakeup+0x3d/0x140
Aug 21 12:21:30 localhost kernel: [59298.590879]  [<ffffffff81012eb9>] ? read_tsc+0x9/0x20
Aug 21 12:21:30 localhost kernel: [59298.590886]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:21:30 localhost kernel: [59298.590896]  [<ffffffff810f7820>] ? __lock_page+0x70/0x70
Aug 21 12:21:30 localhost kernel: [59298.590900]  [<ffffffff813f0c2f>] io_schedule+0x8f/0xd0
Aug 21 12:21:30 localhost kernel: [59298.590903]  [<ffffffff810f782e>] sleep_on_page+0xe/0x20
Aug 21 12:21:30 localhost kernel: [59298.590905]  [<ffffffff813f14cf>] __wait_on_bit+0x5f/0x90
Aug 21 12:21:30 localhost kernel: [59298.590909]  [<ffffffff8103e270>] ? get_user_pages_fast+0xe0/0x1a0
Aug 21 12:21:30 localhost kernel: [59298.590912]  [<ffffffff810f7a18>] wait_on_page_bit+0x78/0x80
Aug 21 12:21:30 localhost kernel: [59298.590917]  [<ffffffff8107f6c0>] ? autoremove_wake_function+0x40/0x40
Aug 21 12:21:30 localhost kernel: [59298.590920]  [<ffffffff810f8345>] grab_cache_page_write_begin+0x95/0xe0
Aug 21 12:21:30 localhost kernel: [59298.590924]  [<ffffffff8118c320>] ? thaw_bdev+0x90/0x90
Aug 21 12:21:30 localhost kernel: [59298.590928]  [<ffffffff81188218>] block_write_begin+0x38/0xa0
Aug 21 12:21:30 localhost kernel: [59298.590930]  [<ffffffff8118bf23>] blkdev_write_begin+0x23/0x30
Aug 21 12:21:30 localhost kernel: [59298.590933]  [<ffffffff810f7dec>] generic_file_buffered_write+0x10c/0x270
Aug 21 12:21:30 localhost kernel: [59298.590936]  [<ffffffff810f8f89>] __generic_file_aio_write+0x229/0x440
Aug 21 12:21:30 localhost kernel: [59298.590946]  [<ffffffffa04f13a0>] ? fuse_dev_read+0x70/0x80 [fuse]
Aug 21 12:21:30 localhost kernel: [59298.590949]  [<ffffffff8118c4f3>] blkdev_aio_write+0x33/0x90
Aug 21 12:21:30 localhost kernel: [59298.590953]  [<ffffffff81157012>] do_sync_write+0xd2/0x110
Aug 21 12:21:30 localhost kernel: [59298.590959]  [<ffffffff811df66c>] ? security_file_permission+0x2c/0xb0
Aug 21 12:21:30 localhost kernel: [59298.590961]  [<ffffffff81157461>] ? rw_verify_area+0x61/0xf0
Aug 21 12:21:30 localhost kernel: [59298.590963]  [<ffffffff811577bf>] vfs_write+0xaf/0x180
Aug 21 12:21:30 localhost kernel: [59298.590966]  [<ffffffff81157c62>] sys_pwrite64+0x82/0xb0
Aug 21 12:21:30 localhost kernel: [59298.590970]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b
Aug 21 12:23:30 localhost kernel: [59418.384263] INFO: task mount.ntfs-3g:17037 blocked for more than 120 seconds.
Aug 21 12:23:30 localhost kernel: [59418.384268] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:23:30 localhost kernel: [59418.384272] mount.ntfs-3g   D 0000000000000002     0 17037      1 0x00000000
Aug 21 12:23:30 localhost kernel: [59418.384278]  ffff880102e2fad8 0000000000000086 ffff880102e2fa18 ffffffff8105400a
Aug 21 12:23:30 localhost kernel: [59418.384283]  ffff8801150a8730 ffff880102e2ffd8 ffff880102e2ffd8 ffff880102e2ffd8
Aug 21 12:23:30 localhost kernel: [59418.384288]  ffff88007a002b20 ffff8801150a8730 ffff880116a7ab20 0000000000000086
Aug 21 12:23:30 localhost kernel: [59418.384292] Call Trace:
Aug 21 12:23:30 localhost kernel: [59418.384303]  [<ffffffff8105400a>] ? check_preempt_wakeup+0x16a/0x230
Aug 21 12:23:30 localhost kernel: [59418.384310]  [<ffffffff8104b644>] ? check_preempt_curr+0x84/0xa0
Aug 21 12:23:30 localhost kernel: [59418.384313]  [<ffffffff8105498d>] ? ttwu_do_wakeup+0x3d/0x140
Aug 21 12:23:30 localhost kernel: [59418.384319]  [<ffffffff81012eb9>] ? read_tsc+0x9/0x20
Aug 21 12:23:30 localhost kernel: [59418.384325]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:23:30 localhost kernel: [59418.384331]  [<ffffffff810f7820>] ? __lock_page+0x70/0x70
Aug 21 12:23:30 localhost kernel: [59418.384336]  [<ffffffff813f0c2f>] io_schedule+0x8f/0xd0
Aug 21 12:23:30 localhost kernel: [59418.384340]  [<ffffffff810f782e>] sleep_on_page+0xe/0x20
Aug 21 12:23:30 localhost kernel: [59418.384343]  [<ffffffff813f14cf>] __wait_on_bit+0x5f/0x90
Aug 21 12:23:30 localhost kernel: [59418.384348]  [<ffffffff8103e270>] ? get_user_pages_fast+0xe0/0x1a0
Aug 21 12:23:30 localhost kernel: [59418.384351]  [<ffffffff810f7a18>] wait_on_page_bit+0x78/0x80
Aug 21 12:23:30 localhost kernel: [59418.384357]  [<ffffffff8107f6c0>] ? autoremove_wake_function+0x40/0x40
Aug 21 12:23:30 localhost kernel: [59418.384361]  [<ffffffff810f8345>] grab_cache_page_write_begin+0x95/0xe0
Aug 21 12:23:30 localhost kernel: [59418.384366]  [<ffffffff8118c320>] ? thaw_bdev+0x90/0x90
Aug 21 12:23:30 localhost kernel: [59418.384371]  [<ffffffff81188218>] block_write_begin+0x38/0xa0
Aug 21 12:23:30 localhost kernel: [59418.384374]  [<ffffffff8118bf23>] blkdev_write_begin+0x23/0x30
Aug 21 12:23:30 localhost kernel: [59418.384378]  [<ffffffff810f7dec>] generic_file_buffered_write+0x10c/0x270
Aug 21 12:23:30 localhost kernel: [59418.384382]  [<ffffffff810f8f89>] __generic_file_aio_write+0x229/0x440
Aug 21 12:23:30 localhost kernel: [59418.384394]  [<ffffffffa04f13a0>] ? fuse_dev_read+0x70/0x80 [fuse]
Aug 21 12:23:30 localhost kernel: [59418.384397]  [<ffffffff8118c4f3>] blkdev_aio_write+0x33/0x90
Aug 21 12:23:30 localhost kernel: [59418.384402]  [<ffffffff81157012>] do_sync_write+0xd2/0x110
Aug 21 12:23:30 localhost kernel: [59418.384409]  [<ffffffff811df66c>] ? security_file_permission+0x2c/0xb0
Aug 21 12:23:30 localhost kernel: [59418.384412]  [<ffffffff81157461>] ? rw_verify_area+0x61/0xf0
Aug 21 12:23:30 localhost kernel: [59418.384415]  [<ffffffff811577bf>] vfs_write+0xaf/0x180
Aug 21 12:23:30 localhost kernel: [59418.384418]  [<ffffffff81157c62>] sys_pwrite64+0x82/0xb0
Aug 21 12:23:30 localhost kernel: [59418.384424]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b

Aug 21 12:27:30 localhost kernel: [59657.971253] INFO: task khugepaged:31 blocked for more than 120 seconds.
Aug 21 12:27:30 localhost kernel: [59657.971259] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:27:30 localhost kernel: [59657.971262] khugepaged      D 00000001010f3a4f     0    31      2 0x00000000
Aug 21 12:27:30 localhost kernel: [59657.971269]  ffff880115b8f760 0000000000000046 ffff880100000000 ffffffff810ff96a
Aug 21 12:27:30 localhost kernel: [59657.971275]  ffff880116a947e0 ffff880115b8ffd8 ffff880115b8ffd8 ffff880115b8ffd8
Aug 21 12:27:30 localhost kernel: [59657.971279]  ffffffff8169b020 ffff880116a947e0 ffff88011bffbe00 0000000200000000
Aug 21 12:27:30 localhost kernel: [59657.971285] Call Trace:
Aug 21 12:27:30 localhost kernel: [59657.971296]  [<ffffffff810ff96a>] ? free_pcppages_bulk+0x42a/0x470
Aug 21 12:27:30 localhost kernel: [59657.971303]  [<ffffffff8121f4cb>] ? radix_tree_delete+0x16b/0x2d0
Aug 21 12:27:30 localhost kernel: [59657.971310]  [<ffffffff81012eb9>] ? read_tsc+0x9/0x20
Aug 21 12:27:30 localhost kernel: [59657.971316]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:27:30 localhost kernel: [59657.971323]  [<ffffffff810f7820>] ? __lock_page+0x70/0x70
Aug 21 12:27:30 localhost kernel: [59657.971329]  [<ffffffff813f0c2f>] io_schedule+0x8f/0xd0
Aug 21 12:27:30 localhost kernel: [59657.971333]  [<ffffffff810f782e>] sleep_on_page+0xe/0x20
Aug 21 12:27:30 localhost kernel: [59657.971337]  [<ffffffff813f14cf>] __wait_on_bit+0x5f/0x90
Aug 21 12:27:30 localhost kernel: [59657.971341]  [<ffffffff810f7a18>] wait_on_page_bit+0x78/0x80
Aug 21 12:27:30 localhost kernel: [59657.971347]  [<ffffffff8107f6c0>] ? autoremove_wake_function+0x40/0x40
Aug 21 12:27:30 localhost kernel: [59657.971355]  [<ffffffff81109b35>] shrink_page_list+0x405/0x910
Aug 21 12:27:30 localhost kernel: [59657.971360]  [<ffffffff8114ea2c>] ? mem_cgroup_del_lru_list+0x2c/0xc0
Aug 21 12:27:30 localhost kernel: [59657.971365]  [<ffffffff81107a92>] ? update_isolated_counts+0x162/0x190
Aug 21 12:27:30 localhost kernel: [59657.971367]  [<ffffffff8110a601>] shrink_inactive_list+0x2e1/0x470
Aug 21 12:27:30 localhost kernel: [59657.971371]  [<ffffffff811158e9>] ? fragmentation_index+0x19/0x70
Aug 21 12:27:30 localhost kernel: [59657.971373]  [<ffffffff811096cf>] ? putback_lru_page+0x7f/0xe0
Aug 21 12:27:30 localhost kernel: [59657.971376]  [<ffffffff8110af69>] shrink_zone+0x489/0x600
Aug 21 12:27:30 localhost kernel: [59657.971378]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:27:30 localhost kernel: [59657.971381]  [<ffffffff8110b40e>] do_try_to_free_pages+0xae/0x400
Aug 21 12:27:30 localhost kernel: [59657.971383]  [<ffffffff8110bad4>] try_to_free_pages+0xb4/0x1e0
Aug 21 12:27:30 localhost kernel: [59657.971386]  [<ffffffff811001cc>] __alloc_pages_nodemask+0x52c/0x820
Aug 21 12:27:30 localhost kernel: [59657.971389]  [<ffffffff8114d5db>] ? __mem_cgroup_threshold+0xab/0x1b0
Aug 21 12:27:30 localhost kernel: [59657.971393]  [<ffffffff8113973a>] alloc_pages_vma+0x9a/0x180
Aug 21 12:27:30 localhost kernel: [59657.971396]  [<ffffffff81148145>] khugepaged+0x795/0x13c0
Aug 21 12:27:30 localhost kernel: [59657.971399]  [<ffffffff8107f680>] ? abort_exclusive_wait+0xb0/0xb0
Aug 21 12:27:30 localhost kernel: [59657.971402]  [<ffffffff811479b0>] ? khugepaged_defrag_store+0x70/0x70
Aug 21 12:27:30 localhost kernel: [59657.971404]  [<ffffffff8107ed2c>] kthread+0x8c/0xa0
Aug 21 12:27:30 localhost kernel: [59657.971409]  [<ffffffff813f5524>] kernel_thread_helper+0x4/0x10
Aug 21 12:27:30 localhost kernel: [59657.971411]  [<ffffffff8107eca0>] ? kthread_worker_fn+0x190/0x190
Aug 21 12:27:30 localhost kernel: [59657.971414]  [<ffffffff813f5520>] ? gs_change+0x13/0x13
Aug 21 12:27:30 localhost kernel: [59657.971440] INFO: task firefox-bin:17131 blocked for more than 120 seconds.
Aug 21 12:27:30 localhost kernel: [59657.971441] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:27:30 localhost kernel: [59657.971443] firefox-bin     D 00000001010f3b06     0 17131      1 0x00000000
Aug 21 12:27:30 localhost kernel: [59657.971446]  ffff88005abebe28 0000000000000082 0000000000000000 000000002da04b3e
Aug 21 12:27:30 localhost kernel: [59657.971449]  ffff880043134f10 ffff88005abebfd8 ffff88005abebfd8 ffff88005abebfd8
Aug 21 12:27:30 localhost kernel: [59657.971452]  ffff8801169c7300 ffff880043134f10 ffff88005abebdf8 ffffffff81160f52
Aug 21 12:27:30 localhost kernel: [59657.971455] Call Trace:
Aug 21 12:27:30 localhost kernel: [59657.971458]  [<ffffffff81160f52>] ? pipe_read+0x2d2/0x4e0
Aug 21 12:27:30 localhost kernel: [59657.971461]  [<ffffffff813f3135>] rwsem_down_failed_common+0xc5/0x160
Aug 21 12:27:30 localhost kernel: [59657.971464]  [<ffffffff813f31e3>] rwsem_down_write_failed+0x13/0x20
Aug 21 12:27:30 localhost kernel: [59657.971468]  [<ffffffff81225ee3>] call_rwsem_down_write_failed+0x13/0x20
Aug 21 12:27:30 localhost kernel: [59657.971470]  [<ffffffff813f2835>] ? down_write+0x25/0x27
Aug 21 12:27:30 localhost kernel: [59657.971474]  [<ffffffff81124512>] sys_mmap_pgoff+0x122/0x230
Aug 21 12:27:30 localhost kernel: [59657.971478]  [<ffffffff81157a77>] ? sys_read+0x67/0x90
Aug 21 12:27:30 localhost kernel: [59657.971480]  [<ffffffff81010262>] sys_mmap+0x22/0x30
Aug 21 12:27:30 localhost kernel: [59657.971483]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b
Aug 21 12:27:30 localhost kernel: [59657.971487] INFO: task firefox-bin:17159 blocked for more than 120 seconds.
Aug 21 12:27:30 localhost kernel: [59657.971488] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:27:30 localhost kernel: [59657.971490] firefox-bin     D 00000001010f3b40     0 17159      1 0x00000000
Aug 21 12:27:30 localhost kernel: [59657.971492]  ffff88005a4bbd60 0000000000000082 0000000000000000 0000000000000000
Aug 21 12:27:30 localhost kernel: [59657.971496]  ffff88007a170e60 ffff88005a4bbfd8 ffff88005a4bbfd8 ffff88005a4bbfd8
Aug 21 12:27:30 localhost kernel: [59657.971498]  ffffffff8169b020 ffff88007a170e60 ffffffff81093867 ffff88005a4bbcd8
Aug 21 12:27:30 localhost kernel: [59657.971501] Call Trace:
Aug 21 12:27:30 localhost kernel: [59657.971506]  [<ffffffff81093867>] ? futex_wait+0x1c7/0x2c0
Aug 21 12:27:30 localhost kernel: [59657.971510]  [<ffffffff81062226>] ? current_fs_time+0x16/0x60
Aug 21 12:27:30 localhost kernel: [59657.971514]  [<ffffffff81055b00>] ? try_to_wake_up+0x280/0x280
Aug 21 12:27:30 localhost kernel: [59657.971516]  [<ffffffff810933d4>] ? get_futex_key+0x174/0x2d0
Aug 21 12:27:30 localhost kernel: [59657.971519]  [<ffffffff813f3135>] rwsem_down_failed_common+0xc5/0x160
Aug 21 12:27:30 localhost kernel: [59657.971521]  [<ffffffff81093aba>] ? futex_wake+0x10a/0x130
Aug 21 12:27:30 localhost kernel: [59657.971524]  [<ffffffff813f3205>] rwsem_down_read_failed+0x15/0x17
Aug 21 12:27:30 localhost kernel: [59657.971527]  [<ffffffff81225eb4>] call_rwsem_down_read_failed+0x14/0x30
Aug 21 12:27:30 localhost kernel: [59657.971529]  [<ffffffff813f2807>] ? down_read+0x17/0x20
Aug 21 12:27:30 localhost kernel: [59657.971533]  [<ffffffff810398c1>] do_page_fault+0x191/0x4d0
Aug 21 12:27:30 localhost kernel: [59657.971538]  [<ffffffff8131de3f>] ? move_addr_to_user+0x8f/0xb0
Aug 21 12:27:30 localhost kernel: [59657.971540]  [<ffffffff8131f218>] ? sys_getpeername+0xd8/0x120
Aug 21 12:27:30 localhost kernel: [59657.971543]  [<ffffffff81096075>] ? sys_futex+0x105/0x1a0
Aug 21 12:27:30 localhost kernel: [59657.971546]  [<ffffffff813f3e45>] page_fault+0x25/0x30
Aug 21 12:27:30 localhost kernel: [59657.971549] INFO: task firefox-bin:17177 blocked for more than 120 seconds.
Aug 21 12:27:30 localhost kernel: [59657.971550] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:27:30 localhost kernel: [59657.971552] firefox-bin     D 00000001010f80d3     0 17177      1 0x00000000
Aug 21 12:27:30 localhost kernel: [59657.971554]  ffff880011701de0 0000000000000082 0000000000000000 ffff880011701cc8
Aug 21 12:27:30 localhost kernel: [59657.971558]  ffff880080e3ab20 ffff880011701fd8 ffff880011701fd8 ffff880011701fd8
Aug 21 12:27:30 localhost kernel: [59657.971560]  ffff8801169c4f10 ffff880080e3ab20 ffffffff810933d4 000000000000044c
Aug 21 12:27:30 localhost kernel: [59657.971564] Call Trace:
Aug 21 12:27:30 localhost kernel: [59657.971566]  [<ffffffff810933d4>] ? get_futex_key+0x174/0x2d0
Aug 21 12:27:30 localhost kernel: [59657.971568]  [<ffffffff81093aba>] ? futex_wake+0x10a/0x130
Aug 21 12:27:30 localhost kernel: [59657.971571]  [<ffffffff813f3135>] rwsem_down_failed_common+0xc5/0x160
Aug 21 12:27:30 localhost kernel: [59657.971574]  [<ffffffff811416cf>] ? kmem_cache_free+0x2f/0x160
Aug 21 12:27:30 localhost kernel: [59657.971576]  [<ffffffff813f3205>] rwsem_down_read_failed+0x15/0x17
Aug 21 12:27:30 localhost kernel: [59657.971579]  [<ffffffff81225eb4>] call_rwsem_down_read_failed+0x14/0x30
Aug 21 12:27:30 localhost kernel: [59657.971581]  [<ffffffff813f2807>] ? down_read+0x17/0x20
Aug 21 12:27:30 localhost kernel: [59657.971584]  [<ffffffff8111984d>] sys_madvise+0x7d/0x740
Aug 21 12:27:30 localhost kernel: [59657.971587]  [<ffffffff81096075>] ? sys_futex+0x105/0x1a0
Aug 21 12:27:30 localhost kernel: [59657.971589]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b
Aug 21 12:29:30 localhost kernel: [59777.764748] INFO: task khugepaged:31 blocked for more than 120 seconds.
Aug 21 12:29:30 localhost kernel: [59777.764753] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:29:30 localhost kernel: [59777.764756] khugepaged      D 00000001010f3a4f     0    31      2 0x00000000
Aug 21 12:29:30 localhost kernel: [59777.764763]  ffff880115b8f760 0000000000000046 ffff880100000000 ffffffff810ff96a
Aug 21 12:29:30 localhost kernel: [59777.764768]  ffff880116a947e0 ffff880115b8ffd8 ffff880115b8ffd8 ffff880115b8ffd8
Aug 21 12:29:30 localhost kernel: [59777.764773]  ffffffff8169b020 ffff880116a947e0 ffff88011bffbe00 0000000200000000
Aug 21 12:29:30 localhost kernel: [59777.764778] Call Trace:
Aug 21 12:29:30 localhost kernel: [59777.764790]  [<ffffffff810ff96a>] ? free_pcppages_bulk+0x42a/0x470
Aug 21 12:29:30 localhost kernel: [59777.764797]  [<ffffffff8121f4cb>] ? radix_tree_delete+0x16b/0x2d0
Aug 21 12:29:30 localhost kernel: [59777.764804]  [<ffffffff81012eb9>] ? read_tsc+0x9/0x20
Aug 21 12:29:30 localhost kernel: [59777.764810]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:29:30 localhost kernel: [59777.764817]  [<ffffffff810f7820>] ? __lock_page+0x70/0x70
Aug 21 12:29:30 localhost kernel: [59777.764822]  [<ffffffff813f0c2f>] io_schedule+0x8f/0xd0
Aug 21 12:29:30 localhost kernel: [59777.764827]  [<ffffffff810f782e>] sleep_on_page+0xe/0x20
Aug 21 12:29:30 localhost kernel: [59777.764830]  [<ffffffff813f14cf>] __wait_on_bit+0x5f/0x90
Aug 21 12:29:30 localhost kernel: [59777.764835]  [<ffffffff810f7a18>] wait_on_page_bit+0x78/0x80
Aug 21 12:29:30 localhost kernel: [59777.764841]  [<ffffffff8107f6c0>] ? autoremove_wake_function+0x40/0x40
Aug 21 12:29:30 localhost kernel: [59777.764846]  [<ffffffff81109b35>] shrink_page_list+0x405/0x910
Aug 21 12:29:30 localhost kernel: [59777.764858]  [<ffffffff8114ea2c>] ? mem_cgroup_del_lru_list+0x2c/0xc0
Aug 21 12:29:30 localhost kernel: [59777.764863]  [<ffffffff81107a92>] ? update_isolated_counts+0x162/0x190
Aug 21 12:29:30 localhost kernel: [59777.764865]  [<ffffffff8110a601>] shrink_inactive_list+0x2e1/0x470
Aug 21 12:29:30 localhost kernel: [59777.764869]  [<ffffffff811158e9>] ? fragmentation_index+0x19/0x70
Aug 21 12:29:30 localhost kernel: [59777.764871]  [<ffffffff811096cf>] ? putback_lru_page+0x7f/0xe0
Aug 21 12:29:30 localhost kernel: [59777.764873]  [<ffffffff8110af69>] shrink_zone+0x489/0x600
Aug 21 12:29:30 localhost kernel: [59777.764876]  [<ffffffff81089d4d>] ? ktime_get_ts+0xad/0xe0
Aug 21 12:29:30 localhost kernel: [59777.764878]  [<ffffffff8110b40e>] do_try_to_free_pages+0xae/0x400
Aug 21 12:29:30 localhost kernel: [59777.764881]  [<ffffffff8110bad4>] try_to_free_pages+0xb4/0x1e0
Aug 21 12:29:30 localhost kernel: [59777.764883]  [<ffffffff811001cc>] __alloc_pages_nodemask+0x52c/0x820
Aug 21 12:29:30 localhost kernel: [59777.764887]  [<ffffffff8114d5db>] ? __mem_cgroup_threshold+0xab/0x1b0
Aug 21 12:29:30 localhost kernel: [59777.764891]  [<ffffffff8113973a>] alloc_pages_vma+0x9a/0x180
Aug 21 12:29:30 localhost kernel: [59777.764894]  [<ffffffff81148145>] khugepaged+0x795/0x13c0
Aug 21 12:29:30 localhost kernel: [59777.764897]  [<ffffffff8107f680>] ? abort_exclusive_wait+0xb0/0xb0
Aug 21 12:29:30 localhost kernel: [59777.764899]  [<ffffffff811479b0>] ? khugepaged_defrag_store+0x70/0x70
Aug 21 12:29:30 localhost kernel: [59777.764902]  [<ffffffff8107ed2c>] kthread+0x8c/0xa0
Aug 21 12:29:30 localhost kernel: [59777.764906]  [<ffffffff813f5524>] kernel_thread_helper+0x4/0x10
Aug 21 12:29:30 localhost kernel: [59777.764909]  [<ffffffff8107eca0>] ? kthread_worker_fn+0x190/0x190
Aug 21 12:29:30 localhost kernel: [59777.764912]  [<ffffffff813f5520>] ? gs_change+0x13/0x13
Aug 21 12:29:30 localhost kernel: [59777.764941] INFO: task firefox-bin:17131 blocked for more than 120 seconds.
Aug 21 12:29:30 localhost kernel: [59777.764943] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Aug 21 12:29:30 localhost kernel: [59777.764944] firefox-bin     D 00000001010f3b06     0 17131      1 0x00000000
Aug 21 12:29:30 localhost kernel: [59777.764947]  ffff88005abebe28 0000000000000082 0000000000000000 000000002da04b3e
Aug 21 12:29:30 localhost kernel: [59777.764950]  ffff880043134f10 ffff88005abebfd8 ffff88005abebfd8 ffff88005abebfd8
Aug 21 12:29:30 localhost kernel: [59777.764953]  ffff8801169c7300 ffff880043134f10 ffff88005abebdf8 ffffffff81160f52
Aug 21 12:29:30 localhost kernel: [59777.764956] Call Trace:
Aug 21 12:29:30 localhost kernel: [59777.764960]  [<ffffffff81160f52>] ? pipe_read+0x2d2/0x4e0
Aug 21 12:29:30 localhost kernel: [59777.764963]  [<ffffffff813f3135>] rwsem_down_failed_common+0xc5/0x160
Aug 21 12:29:30 localhost kernel: [59777.764966]  [<ffffffff813f31e3>] rwsem_down_write_failed+0x13/0x20
Aug 21 12:29:30 localhost kernel: [59777.764970]  [<ffffffff81225ee3>] call_rwsem_down_write_failed+0x13/0x20
Aug 21 12:29:30 localhost kernel: [59777.764972]  [<ffffffff813f2835>] ? down_write+0x25/0x27
Aug 21 12:29:30 localhost kernel: [59777.764976]  [<ffffffff81124512>] sys_mmap_pgoff+0x122/0x230
Aug 21 12:29:30 localhost kernel: [59777.764984]  [<ffffffff81157a77>] ? sys_read+0x67/0x90
Aug 21 12:29:30 localhost kernel: [59777.764987]  [<ffffffff81010262>] sys_mmap+0x22/0x30
Aug 21 12:29:30 localhost kernel: [59777.764989]  [<ffffffff813f4402>] system_call_fastpath+0x16/0x1b

~12:45 HDD led off



Sun Aug 21, 2011 11:04
Profile
NTFS-3G Lead Developer

Joined: Tue Sep 04, 2007 17:22
Posts: 1286
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hi,

Quote:
source: 20Gb Truecrypt image with NTFS located on NTFS partition on USB HDD.
dest: 20Gb Truecrypt image with NTFS located on NTFS partition on laptop HDD

Does this mean a nested NTFS partition (an ntfs image mounted into an ntfs partition) ?

If this is the case, each time you update the inner image, you have to update the timestamps on the outer partition. This may be very heavy the inner image is big and fragmented.

In ntfs-3g_ntfsprogs-2011.4.12AR.6, I have added a new option "delay_mtime" for delaying the updating of the timestamps until files are closed. You might try using this option for mounting the outer partition. If the size of the inner image does not change, you will get a significant improvement.

Regards

Jean-Pierre


Sun Aug 21, 2011 11:57
Profile

Joined: Sat May 14, 2011 14:54
Posts: 7
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Thanks, I'll try.


Sun Aug 21, 2011 14:09
Profile

Joined: Sat Apr 07, 2012 17:54
Posts: 5
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
I have the same problem with (K)Ubuntu 11.10 and pendrive KINGSTON DT 100 G2 32GB (5MB W / 10MB R) NTFS.
It is taking 2+ hours to copy only 1.2GB !! Also it takes lots of time to umount (+20min)
Formatting same pendrive with ext2/3, it lasts 14min to copy same 1.2GB and umounts immediately. I have tried lot of things, including using last ntfs-3g and FUSE, but nothing solved the problem.

I also noted that mount.ntfs-3g and umount processes remains o lot in Disk Sleep (DS) state.

My old pendrive KINGSTON DT 100 G1 16GB NTFS takes 8m25s to copy same 1.2GB and
does not block task mount.ntfs-3g.

Any ideias?

Kubuntu 11.10 Linux 3.0.0-17-generic-pae #30-Ubuntu SMP
ntfs-3g 2012.1.15AR.1 external FUSE 28 - Third Generation NTFS Driver
Configuration type 1, XATTRS are on, POSIX ACLS are off
pendrive KINGSTON DT 100 G2 32GB.


Sat Apr 07, 2012 20:39
Profile
NTFS-3G Lead Developer

Joined: Tue Sep 04, 2007 17:22
Posts: 1286
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hi,

Quote:
It is taking 2+ hours to copy only 1.2GB !! Also it takes lots of time to umount (+20min)

Is this a single file ? What were your mount options ?
Quote:
Formatting same pendrive with ext2/3, it lasts 14min to copy same 1.2GB and umounts immediately.

So, you must also have formatted yourself as ntfs. With what options ?
Quote:
Any ideias?

I would first suspect a pathological fragmentation, which could be triggered by a clumsy formatting parameter.

Replacing "big-file" below by the path to a big file mounted on your pendrive (formatted as ntfs), can you post the output of :
Code:
# with the device mounted
ls -li big-file
getfattr -e hex -n system.ntfs_attrib big-file
df big-file

The first command will output an inode number (the first number displayed), as root unmount the device and post the output of :
Code:
# as root, with the device not mounted
ntfsinfo -fm device
ntfsinfo -fvi inode-number device | grep runs

Replace inode-number above by the actual number, and device by the device path (/dev/something)


Sat Apr 07, 2012 21:53
Profile

Joined: Sat Apr 07, 2012 17:54
Posts: 5
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hello jpa

> Is this a single file ? What were your mount options ?

Actually is a directory with some sub-directories totaling
242 files

I have formatted NTFS with only -f (fast) parameter.

Will run the tests you suggest with an 4.2GB ISO DVD file and post the results later here

Thank you for your help


Sat Apr 07, 2012 23:30
Profile

Joined: Sat Apr 07, 2012 17:54
Posts: 5
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hello jpa,
here we go:

I copy a single DVD ISO of 1.4GB to the pendrive: I takes 4m42s !!!

I mounted the pendrive with:
> pmount /dev/sdf1
resulting
> mount
/dev/sdf1 on /media/sdf1 type fuseblk (rw,noexec,nosuid,nodev,allow_other,default_permissions,blksize=4096)

The commands outputs you asked to run are below.

> # with the device mounted
> ls -li /media/sdf1/2009_04_19_07h59m_pm.iso
64 -rw------- 1 freitas freitas 1428553728 2012-04-07 21:08 /media/sdf1/2009_04_19_07h59m_pm.iso

> getfattr -e hex -n system.ntfs_attrib /media/sdf1/2009_04_19_07h59m_pm.iso
getfattr: Removing leading '/' from absolute path names
# file: media/sdf1/2009_04_19_07h59m_pm.iso
system.ntfs_attrib=0x20000000

> df /media/sdf1/2009_04_19_07h59m_pm.iso
Filesystem 1K-blocks Used Available Use% Mounted on
/dev/sdf1 30485372 1467588 29017784 5% /media/sdf1

> # as root, with the device not mounted
> ntfsinfo -fm /dev/sdf1
Volume Information
Name of device: /dev/sdf1
Device state: 11
Volume Name:
Volume State: 27
Volume Version: 3.1
Sector Size: 512
Cluster Size: 4096
Index Block Size: 4096
Volume Size in Clusters: 7621343
MFT Information
MFT Record Size: 1024
MFT Zone Multiplier: 0
MFT Data Position: 24
MFT Zone Start: 0
MFT Zone End: 952671
MFT Zone Position: 4
Current Position in First Data Zone: 952671
Current Position in Second Data Zone: 0
LCN of Data Attribute for FILE_MFT: 4
FILE_MFTMirr Size: 4
LCN of Data Attribute for File_MFTMirr: 3810671
Size of Attribute Definition Table: 2560
FILE_Bitmap Information
FILE_Bitmap MFT Record Number: 6
State of FILE_Bitmap Inode: 80
Length of Attribute List: 0
Attribute List: (null)
Number of Attached Extent Inodes: 0
FILE_Bitmap Data Attribute Information
Decompressed Runlist: not done yet
Base Inode: 6
Attribute Types: not done yet
Attribute Name Length: 0
Attribute State: 3
Attribute Allocated Size: 954368
Attribute Data Size: 952672
Attribute Initialized Size: 952672
Attribute Compressed Size: 0
Compression Block Size: 0
Compression Block Size Bits: 0
Compression Block Clusters: 0

> ntfsinfo -fvi 64 /dev/sdf1 | grep runs
Total runs: 1 (fragments: 1)

Thats is it!
Thanks


Sun Apr 08, 2012 02:34
Profile
NTFS-3G Lead Developer

Joined: Tue Sep 04, 2007 17:22
Posts: 1286
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hi,

Quote:
Actually is a directory with some sub-directories totaling 242 files

Then a fragmentation issue is unlikely. How big was your biggest file ?
Quote:
I have formatted NTFS with only -f (fast) parameter.

Formatting parameters are correct, as shown by the data you posted.
Quote:
I copy a single DVD ISO of 1.4GB to the pendrive: I takes 4m42s !!!

This means 5MB/s, which is a normal throughput on a pendrive.

Everything is normal in the data you posted. The device has apparently been newly formatted and it is not fragmented at all. You probably re-formatted and wiped out some wrong condition.

Try copying your directory tree again (what command are you using to make the copy ?). If you get into the same problem, try copying a single directory at a time and avoid having your current directory on the pendrive.

If the issue does not show any more, I will probably not be able to get an explanation.

Regards

Jean-Pierre


Sun Apr 08, 2012 08:54
Profile

Joined: Sat Apr 07, 2012 17:54
Posts: 5
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hello Jean-Pierre

> Then a fragmentation issue is unlikely. How big was your biggest file ?
My biggest file has 186730374 bytes (a few of this size), my smaller has
0 bytes (some 50 of this size) and the next smaller present hundreds files
with less than 100 bytes.

I made a mistake informing you the total of files of the whole tree: it has
6233 files

I send in the attachment a gziped of the whole dir contents produced by command
du -Sab ~/PWB_HOME/ | sort -n | grep "\." > /tmp/PWB_HOME_files_list.txt

> Try copying your directory tree again (what command are you using to make the copy ?).
cp -r ~/PWB_HOME /media/sdf1
I copied again and it takes 3h08m !!!!

Copy a single directory at a time does not solve my problem because I need to use
this pendrive to daily transport my files from work to home.

It seems to me that the performance problem is related with time spent to create many small files.

Once again, what intrigues me is that with my old 16GB DT100 G1 NTFS pendrive it takes
a fair 8m25s to copy same directory.

Anyway, I thank you very much for your interest and support

Regards,
Freitas


Attachments:
PWB_HOME_files_list.txt.gz [42.45 KiB]
Downloaded 872 times
Mon Apr 09, 2012 06:56
Profile
NTFS-3G Lead Developer

Joined: Tue Sep 04, 2007 17:22
Posts: 1286
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hi,

Quote:
I send in the attachment a gziped of the whole dir contents produced by command
du -Sab ~/PWB_HOME/ | sort -n | grep "\." > /tmp/PWB_HOME_files_list.txt

Based on your data, I have built a file tree similar to yours on an internal disk (same names, same sizes, with random contents), then I copied to a USB key.

On the first try (with a recent kernel), this lasted 31mn. Examining the timestamps of the created file, I saw the copy got stuck on two occasions (6mn33 and 3mn23).

I retried with and old kernel (2.6.19), still using ntfs-3g-2012.1.15AR.1, and the copy lasted 12mn, so there must be some issue related to newer kernels (I suspect a directory locking issue).
If you still have the files copied to the USB key, can you make a full list of them, sorted by modification times, in order to identify whether there are unusual gaps between times ?

Quote:
Copy a single directory at a time does not solve my problem because I need to use
this pendrive to daily transport my files from work to home.

Until the issue is understood and solved, I suggest you transport your files in a tarball :
Code:
# with a recent kernel
[linux@dimension acls]$ time tar -cf /media/pny16/PWB_HOME/PWB_HOME.tar PWB_HOME

real    10m10.656s
user    0m0.629s 
sys     0m13.984s
[linux@dimension acls]$ time sudo umount /media/pny16

real    1m16.197s
user    0m0.013s
sys     0m0.457s

Regards

Jean-Pierre


Mon Apr 09, 2012 16:22
Profile

Joined: Sat Apr 07, 2012 17:54
Posts: 5
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hi Jean-Pierre,

> if you still have the files copied to the USB key, can you make a full list of them, sorted by >modification times, in order to identify whether there are unusual gaps between times ?

I send in the attachment a gziped of the whole dir contents produced by command
find /media/47F4918C6A9DEE92/PWB_HOME/ -printf '%T+ %s %p\n' | sort -r

Hope this command represents the correct modification times (if not, pleas send me an alternative command line).
There are many interesting timing data.
For example, according data, file dt_rio_1.mpg (~170MB) took ~ 10s while file dt_rio_2.mpg (~190MB) took ~ 1m50s.

Regards,
Freitas


Attachments:
PWB_HOME_files_list_sort_modified_time.txt.gz [81.71 KiB]
Downloaded 862 times
Mon Apr 09, 2012 19:07
Profile
NTFS-3G Lead Developer

Joined: Tue Sep 04, 2007 17:22
Posts: 1286
Post Re: task mount.ntfs-3g blocked for more than 120 seconds.
Hi,

Quote:
There are many interesting timing data.
For example, according data, file dt_rio_1.mpg (~170MB) took ~ 10s while file dt_rio_2.mpg (~190MB) took ~ 1m50s.

When the gap between the timestamps is over one minute, it does not appear to have much relation with file sizes. As the USB keys are slow, the data is kept a long time in the cache and the timestamps tell when the data is created in the cache, not when it is output to the key.

I have no idea how this behavior could be caused by ntfs-3g....

Regards

Jean-Pierre


Tue Apr 10, 2012 21:44
Profile
Display posts from previous:  Sort by  
Post new topic Reply to topic  [ 12 posts ] 


Who is online

Users browsing this forum: No registered users and 3 guests


You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot post attachments in this forum

Search for:
Jump to:  
cron
Powered by phpBB © 2000, 2002, 2005, 2007 phpBB Group.
Original forum style by Vjacheslav Trushkin.