• The forum software that supports hummy.tv has been upgraded to XenForo 2.3!

    Please bear with us as we continue to tweak things, and feel free to post any questions, issues or suggestions in the upgrade thread.

ntfs-3g works well ... but not always at boot-up

Thanks for the reminder - there was a bug in the upload page, hopefully fixed now, so if xyz321 could try again...
 
More and more curious.. worked fine for me.

ntfsupload.png


It's there now anyway.
 
Is this a fixed version of the package?
In theory it should fix the problem with "Box B", but I have to say it has always worked for me. This new version should retry a few times if it fails to unmount the drive.

In the case of box A, has the partition type been set to NTFS in the partition table?
 
Yes I have not noticed my NTFS drive not mounting either, although other users have reported problems in the past.
 
Many thanks to xyz321 for updating ntfs-3g and to af123 for uploading it. I am most grateful.

In theory it should fix the problem with "Box B", but I have to say it has always worked for me. This new version should retry a few times if it fails to unmount the drive.

In the case of box A, has the partition type been set to NTFS in the partition table?

I have been testing with the new update, several times, yesterday evening and this afternoon.

ntfs-3g does now work on "BoxB" at boot-up .... hooray! :D It re-tries a few times and then clicks in. (rag.log below)

ntfs-3g still doesn't work on "BoxA" at boot-up, though as before, it will work if the external NTFS drive is removed and reconnected. (see rag.log below)

I don't know where to look for a partition table, please explain where to find it and I will check.

To double check that it's not the external drive that is causing problems, I tried the external NTFS drive that was connected to "BoxA" on "BoxB" - where it did mount successfully at boot-up using ntfs-3g - so it seems like it's a difference in the 'boxes' and not the drives thats causing problems? ...

BoxA has original virtual-disk, and mvdisks installed, but does not have ntfsprogs installed.

BoxB has virtual-disk2, and ntfsprogs installed, but does not have mvdisks installed.

Is this relevant in any way? (What does ntfsprogs do exactly?)

I am interested that xyz123 or Black Hole have always found ntfs-3g to work fine at boot-up ... I wonder what I'm doing wrong :confused:
.... straight out of the box, NTFS external drives sometimes come with back-up and/or encryption software pre-installed on the disk, which I generally ignore or scoop up and tidy away into a folder - could those files/folders be causing problems for BoxA?

rag.log for BoxA (which has mvdisks and original virtual-disk installed ... )
ntfs-3g still not working at boot-up
Code:
>>> Contents of /var/log/rag.log 11.84 KiB
--------- Info for Modinit PID 378 ---------
378: Date: Sat Jan 1 00:00:08 GMT 2000
378: MDEV: sda1
378: ACTION: add
378: Model: HDR
378: Device: /dev/sda1
378: Disk: sda
378:01/01/2000 00:00: Waiting for disk to become ready...
378:01/01/2000 00:00: disk ready after 0 seconds
378:01/01/2000 00:00: /dev/sda1 is NOT formatted as ext2/3
378:01/01/2000 00:00: /dev/sda1 is NOT removable.
378:01/01/2000 00:00: Waiting for the disk to mount...
378:01/01/2000 00:00: still waiting...
378:01/01/2000 00:00: still waiting...
--------- Info for Modinit PID 550 ---------
550: Date: Tue Jan 21 14:21:50 GMT 2014
550: MDEV: sdb1
550: ACTION: add
550: Model: HDR
550: Device: /dev/sdb1
550: Disk: sdb
--------- Info for Modinit PID 554 ---------
554: Date: Tue Jan 21 14:21:50 GMT 2014
554: MDEV: sdb2
554: ACTION: add
554: Model: HDR
554: Device: /dev/sdb2
554: Disk: sdb
554:21/01/2014 14:21: sdb2 is candidate for mod [/mnt/hd2]
554:21/01/2014 14:21: Waiting for disk to become ready...
550:21/01/2014 14:21: Waiting for disk to become ready...
554:21/01/2014 14:21: disk ready after 0 seconds
--------- Info for Modinit PID 546 ---------
546: Date: Tue Jan 21 14:21:51 GMT 2014
546: MDEV: sdb3
546: ACTION: add
546: Model: HDR
546: Device: /dev/sdb3
546: Disk: sdb
546:21/01/2014 14:21: Waiting for disk to become ready...
554:21/01/2014 14:21: /dev/sdb2 is formatted as ext2/3
554:21/01/2014 14:21: /dev/sdb2 is NOT removable.
554:21/01/2014 14:21: Waiting for the disk to mount...
550:21/01/2014 14:21: disk ready after 0 seconds
546:21/01/2014 14:21: disk ready after 0 seconds
550:21/01/2014 14:21: /dev/sdb1 is formatted as ext2/3
550:21/01/2014 14:21: /dev/sdb1 is NOT removable.
550:21/01/2014 14:21: Waiting for the disk to mount...
546:21/01/2014 14:21: /dev/sdb3 is formatted as ext2/3
546:21/01/2014 14:21: /dev/sdb3 is NOT removable.
546:21/01/2014 14:21: Waiting for the disk to mount...
378:21/01/2014 14:21: /dev/sda1 mounted on /media/drive1
378:21/01/2014 14:21: mounted after 2 seconds.
378:21/01/2014 14:21: Waiting for modinit to complete.
378:21/01/2014 14:21: still waiting...
554:21/01/2014 14:21: still waiting...
546:21/01/2014 14:21: still waiting...
550:21/01/2014 14:21: /dev/sdb1 mounted on /mnt/hd1
550:21/01/2014 14:21: mounted after 0 seconds.
550:21/01/2014 14:21: Waiting for modinit to complete.
550:21/01/2014 14:21: still waiting...
378:21/01/2014 14:21: still waiting...
550:21/01/2014 14:21: still waiting...
554:21/01/2014 14:21: /dev/sdb2 mounted on /mnt/hd2
554:21/01/2014 14:21: mounted after 1 second.
378:21/01/2014 14:21: still waiting...
554:21/01/2014 14:21: +++ Running modinit +++
546:21/01/2014 14:21: /dev/sdb3 mounted on /mnt/hd3
546:21/01/2014 14:21: mounted after 1 second.
546:21/01/2014 14:21: Waiting for modinit to complete.
546:21/01/2014 14:21: still waiting...
550:21/01/2014 14:21: still waiting...
378:21/01/2014 14:21: still waiting...
546:21/01/2014 14:21: still waiting...
550:21/01/2014 14:21: still waiting...
378:21/01/2014 14:21: still waiting...
546:21/01/2014 14:21: still waiting...
550:21/01/2014 14:21: still waiting...
378:21/01/2014 14:21: still waiting...
546:21/01/2014 14:21: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:22: still waiting...
550:21/01/2014 14:22: still waiting...
546:21/01/2014 14:22: still waiting...
378:21/01/2014 14:23: timeout, aborting.
550:21/01/2014 14:23: still waiting...
546:21/01/2014 14:23: still waiting...
550:21/01/2014 14:23: timeout, aborting.
546:21/01/2014 14:23: still waiting...
546:21/01/2014 14:23: timeout, aborting.
554:21/01/2014 14:23: +++ Modinit complete +++
554:21/01/2014 14:23: sdb: Non-removable disk, skipping package scan.
--------- Info for Modinit PID 5861 ---------
5861: Date: Tue Jan 21 14:26:57 GMT 2014
5861: MDEV: sda1
5861: ACTION: remove
5861: Model: HDR
5861: Device: /dev/sda1
5861: Disk: sda
--------- Info for Modinit PID 6481 ---------
6481: Date: Tue Jan 21 14:27:42 GMT 2014
6481: MDEV: sda1
6481: ACTION: add
6481: Model: HDR
6481: Device: /dev/sda1
6481: Disk: sda
6481:21/01/2014 14:27: Waiting for disk to become ready...
6481:21/01/2014 14:27: disk ready after 0 seconds
6481:21/01/2014 14:27: /dev/sda1 is NOT formatted as ext2/3
6481:21/01/2014 14:27: /dev/sda1 is NOT removable.
6481:21/01/2014 14:27: Waiting for the disk to mount...
6481:21/01/2014 14:27: /dev/sda1 mounted on /media/drive1
6481:21/01/2014 14:27: mounted after 0 seconds.
6631:21/01/2014 14:27: ntfs-3g: fuse filesystem is available
6631:21/01/2014 14:27: ntfs-3g: Attempting to remount /dev/sda1...
6631:21/01/2014 14:27: ntfs-3g: Unmounted /media/drive1
6631:21/01/2014 14:27: ntfs-3g: Mounting /media/usb-drive1 on /dev/sda1
mkdir: can't create directory '/media/My Video/usb-drive1': File exists
6481:21/01/2014 14:27: sda: Non-removable disk, skipping package scan.
--------- Info for Modinit PID 7999 ---------
7999: Date: Tue Jan 21 14:29:39 GMT 2014
7999: MDEV: sda1
7999: ACTION: remove
7999: Model: HDR
7999: Device: /dev/sda1
7999: Disk: sda
7999:21/01/2014 14:29: Mount point: /media/usb-drive1
8026:21/01/2014 14:29: ntfs-3g: Unmounting /media/usb-drive1
umount: can't forcibly umount /dev/sda1: Invalid argument
rag.log for BoxB (which has virtual-disk2 and ntfsprogs installed and no mvdisks ... )
ntfs-3g now working at boot-up, after a few tries
Code:
>>> Contents of /var/log/rag.log 6.86 KiB
--------- Info for Modinit PID 367 ---------
367: Date: Sat Jan 1 00:00:08 GMT 2000
367: MDEV: sda1
367: ACTION: add
 
367: Model: HDR
367: Device: /dev/sda1
367: Disk: sda
367:01/01/2000 00:00: Waiting for disk to become ready...
367:01/01/2000 00:00: disk ready after 0 seconds
367:01/01/2000 00:00: /dev/sda1 is NOT formatted as ext2/3
367:01/01/2000 00:00: /dev/sda1 is NOT removable.
367:01/01/2000 00:00: Waiting for the disk to mount...
367:01/01/2000 00:00: still waiting...
367:01/01/2000 00:00: still waiting...
367:01/01/2000 00:00: still waiting...
--------- Info for Modinit PID 548 ---------
548: Date: Tue Jan 21 14:32:38 GMT 2014
548: MDEV: sdb3
548: ACTION: add
548: Model: HDR
548: Device: /dev/sdb3
548: Disk: sdb
--------- Info for Modinit PID 554 ---------
554: Date: Tue Jan 21 14:32:38 GMT 2014
554: MDEV: sdb2
554: ACTION: add
554: Model: HDR
554: Device: /dev/sdb2
554: Disk: sdb
--------- Info for Modinit PID 550 ---------
550: Date: Tue Jan 21 14:32:38 GMT 2014
550: MDEV: sdb1
550: ACTION: add
550: Model: HDR
550: Device: /dev/sdb1
550: Disk: sdb
554:21/01/2014 14:32: sdb2 is candidate for mod [/mnt/hd2]
550:21/01/2014 14:32: Waiting for disk to become ready...
550:21/01/2014 14:32: disk ready after 0 seconds
554:21/01/2014 14:32: Waiting for disk to become ready...
548:21/01/2014 14:32: Waiting for disk to become ready...
550:21/01/2014 14:32: /dev/sdb1 is formatted as ext2/3
550:21/01/2014 14:32: /dev/sdb1 is NOT removable.
548:21/01/2014 14:32: disk ready after 0 seconds
548:21/01/2014 14:32: /dev/sdb3 is formatted as ext2/3
548:21/01/2014 14:32: /dev/sdb3 is NOT removable.
548:21/01/2014 14:32: Waiting for the disk to mount...
550:21/01/2014 14:32: Waiting for the disk to mount...
554:21/01/2014 14:32: disk ready after 0 seconds
554:21/01/2014 14:32: /dev/sdb2 is formatted as ext2/3
554:21/01/2014 14:32: /dev/sdb2 is NOT removable.
554:21/01/2014 14:32: Waiting for the disk to mount...
367:21/01/2014 14:32: /dev/sda1 mounted on /media/drive1
367:21/01/2014 14:32: mounted after 3 seconds.
548:21/01/2014 14:32: /dev/sdb3 mounted on /mnt/hd3
554:21/01/2014 14:32: /dev/sdb2 mounted on /mnt/hd2
550:21/01/2014 14:32: /dev/sdb1 mounted on /mnt/hd1
554:21/01/2014 14:32: mounted after 0 seconds.
548:21/01/2014 14:32: mounted after 0 seconds.
554:21/01/2014 14:32: +++ Running modinit +++
367:21/01/2014 14:32: Waiting for modinit to complete.
550:21/01/2014 14:32: mounted after 0 seconds.
548:21/01/2014 14:32: Waiting for modinit to complete.
548:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: Waiting for modinit to complete.
367:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
548:21/01/2014 14:32: still waiting...
550:21/01/2014 14:32: still waiting...
367:21/01/2014 14:32: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
--------- Info for Modinit PID 1473 ---------
1473: Date: Tue Jan 21 14:33:08 GMT 2014
1473: MDEV: sdc1
1473: ACTION: add
1473: Model: HDR
1473: Device: /dev/sdc1
1473: Disk: sdc
1473:21/01/2014
14:33: Waiting for disk to become ready...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
1473:21/01/2014 14:33: disk ready after 0 seconds
1473:21/01/2014 14:33: /dev/sdc1 is formatted as ext2/3
1473:21/01/2014 14:33: /dev/sdc1 is NOT removable.
1473:21/01/2014 14:33: Waiting for the disk to mount...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
367:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
550:21/01/2014 14:33: still waiting...
1473:21/01/2014 14:33: /dev/sdc1 mounted on /media/drive2
367:21/01/2014 14:33: still waiting...
1473:21/01/2014 14:33: mounted after 0 seconds.
1473:21/01/2014 14:33: Waiting for modinit to complete.
1473:21/01/2014 14:33: still waiting...
548:21/01/2014 14:33: still waiting...
554:21/01/2014 14:33: +++ Modinit complete +++
554:21/01/2014 14:33: sdb: Non-removable disk, skipping package scan.
550:21/01/2014 14:33: completed after 22 seconds.
367:21/01/2014 14:33: completed after 22 seconds.
550:21/01/2014 14:33: sdb: Non-removable disk, skipping package scan.
1473:21/01/2014 14:33: completed after 1 second.
1863:21/01/2014 14:33: ntfs-3g: fuse filesystem is available
1863:21/01/2014 14:33: ntfs-3g: Attempting to remount /dev/sda1...
1473:21/01/2014 14:33: sdc: Non-removable disk, skipping package scan.
1863:21/01/2014 14:33: ntfs-3g: Unmounted /media/drive1
548:21/01/2014 14:33: completed after 23 seconds.
1863:21/01/2014 14:33: ntfs-3g: Mounting /media/usb-drive1 on /dev/sda1
548:21/01/2014 14:33: sdb: Non-removable disk, skipping package scan.
367:21/01/2014 14:33: sda: Non-removable disk, skipping package scan.

(Dear Brian and BH ... to make it readable I added in ALL the line breaks ;) )
 
Your problem on Box A seems to be the time it takes for the modinit process to run - can you post the contents of the modinit.log too? That might provide some clues.. maybe the modinit timeout needs to be greater but it will be helpful to see what's going on.
 
ntfs-3g does now work on "BoxB" at boot-up .... hooray! :D It re-tries a few times and then clicks in. (rag.log below)
In that listing it seems to have successfully unmounted the disk first time without any retries.
ntfs-3g still doesn't work on "BoxA" at boot-up, though as before, it will work if the external NTFS drive is removed and reconnected. (see rag.log below)

I don't know where to look for a partition table, please explain where to find it and I will check.

To double check that it's not the external drive that is causing problems, I tried the external NTFS drive that was connected to "BoxA" on "BoxB" - where it did mount successfully at boot-up using ntfs-3g - so it seems like it's a difference in the 'boxes' and not the drives thats causing problems? ...
Don't worry about the partition table, if you have successfully mounted it on the other box then it must be OK.
BoxA has original virtual-disk, and mvdisks installed, but does not have ntfsprogs installed.

BoxB has virtual-disk2, and ntfsprogs installed, but does not have mvdisks installed.

Is this relevant in any way? (What does ntfsprogs do exactly?)
The ntfsprogs package is not used or necessary for mounting disks. It just contains diagnostic tools and a utililty to format a partition as NTFS.

I think af123 is onto the right track with your Box B problem, it is likely to be CF related.
 
Your problem on Box A seems to be the time it takes for the modinit process to run - can you post the contents of the modinit.log too? That might provide some clues.. maybe the modinit timeout needs to be greater but it will be helpful to see what's going on.

Thank you again af123 and xyz321 for your help and expertise. To have BoxB working like it used to, is great ... very nice not to have to crawl on the floor plugging and unplugging the USB cable at regular intervals :)

I have tried to set up BoxA to be as like BoxB as possible, so it now has virtual-disk2 installed, and original virtual-disk and mvdisks removed. I also did a cold boot using the on/off switch. However it has made no difference.

Herewith modinit.log for BoxA
(MAC and IPs redacted and I have tried to add line breaks to make sense of it, but its not entirely clear to me where they should go. Apologies)

Code:
>>> Contents of /var/log/modinit.log 5.15 KiB
=== Tue Jan 21 21:23:09 GMT 2014 - modinit - ===========================
=== Tue Jan 21 21:23:09 GMT 2014 - diag ===========================
Filesystem Size Used Available Use%
Mounted on /dev/root 17.2M 17.2M 0 100% /
tmpfs 61.0M 32.0K 61.0M 0%
/tmp tmpfs 61.0M 0 61.0M 0%
/media /dev/mtdblock1 2.0M 508.0K 1.5M 25%
/var/lib/humaxtv /dev/mtdblock2 2.0M 1.3M 700.0K 66%
/var/lib/humaxtv_backup /dev/sda1 931.5G 918.0G 13.5G 99%
/media/drive1 /dev/sdb1 1011.4M 38.2M 921.8M 4%
/mnt/hd1 /dev/sdb2 906.1G 532.7G 327.3G 62%
/mnt/hd2 /dev/sdb3 9.8G 754.2M 8.6G 8%
/mnt/hd3 eth0 Link encap:Ethernet
HWaddr XX:XX:XX:XX:XX:XX
inet addr:XXX.XXX.X.XX
Bcast:XXX.XXX.X.XXX
Mask:255.255.255.0
UP BROADCAST RUNNING MULTICAST MTU:1500
Metric:1 RX packets:1 errors:0 dropped:0 overruns:0
frame:0 TX packets:9 errors:0 dropped:0 overruns:0
carrier:0 collisions:0 txqueuelen:1000
RX bytes:66 (66.0 B) TX bytes:576 (576.0 B)
Interrupt:16 lo Link encap:Local
Loopback inet addr:127.0.0.1 Mask:255.0.0.0
UP LOOPBACK RUNNING MTU:16436
Metric:1 RX packets:3 errors:0 dropped:0 overruns:0
frame:0 TX packets:3 errors:0 dropped:0 overruns:0
carrier:0 collisions:0 txqueuelen:0
RX bytes:258 (258.0 B) TX bytes:258 (258.0 B)
PID USER VSZ STAT COMMAND 1 root 1244 S init 2 root 0
SW [migration/0] 3 root 0 SWN [ksoftirqd/0] 4 root 0
SW [migration/1] 5 root 0 SWN [ksoftirqd/1] 6 root 0 SW<
[events/0] 7 root 0 SW< [events/1] 8 root 0 SW< [khelper]
9 root 0 SW< [kthread] 10 root 0 SW< [kblockd/0] 11 root 0
SW< [kblockd/1] 12 root 0 SW< [khubd] 13 root 0
SW< [kseriod] 14 root 0 SW [pdflush] 15 root 0
SW [pdflush] 16 root 0 SW< [kswapd0] 17 root 0
SW< [aio/0] 18 root 0 SW< [aio/1] 19 root 0
SW< [ata/0] 20 root 0 SW< [ata/1] 21 root 0
SW< [ata_aux] 22 root 0 SW< [scsi_eh_0] 23 root 0
SW< [scsi_eh_1] 24 root 0 SW [mtdblockd] 25 root 0
SW< [scsi_eh_2] 26 root 0 SW< [usb-storage] 28 root 0
SW< [kpsmoused] 29 root 1240 S {rcS} /bin/sh /etc/init.d/rcS 74
root 0 SWN [jffs2_gcd_mtd1] 75 root 0 SWN [jffs2_gcd_mtd2]
129 nobody 932 S /usr/bin/dnsmasq 135 root 812 S N /sbin/utelnetd -l
/bin/tmenu -p 23 -d -B 165 root 1252 S {S90settop}
/bin/sh /etc/init.d/S90settop start 180 root 361m S
/usr/bin/humaxtv 417 root 1264 S < {run-and-gun}
/bin/sh /etc/mdev/run-and-gun 564 root 1260 S < {run-and-gun}
/bin/sh /etc/mdev/run-and-gun 570 root 0
SW< [kjournald] 587 root 1260 S < {run-and-gun}
/bin/sh /etc/mdev/run-and-gun 597 root 1260 S < {run-and-gun}
/bin/sh /etc/mdev/run-and-gun 868 root 0
SW< [kjournald] 870 root 0 SW< [kjournald] 980
root 1252 S < {modinit} /bin/sh
/sbin/modinit 1019 root 1228 S < sleep 1 1026
root 1228 S < sleep 1 1031 root 1228 S <
sleep 1 1035 root 1236 R < ps -w Kernel
IP routing table Destination Gateway Genmask Flags
Metric Ref Use Iface XXX.XXX.X.X * 255.255.255.0 U 0 0 0 eth0
default XXX.XXX.X.X 0.0.0.0 UG 0 0 0 eth0
=== Tue Jan 21 21:23:12 GMT 2014 - reset all ===========================
=== Tue Jan 21 21:23:12 GMT 2014 - rma ===========================
=== Tue Jan 21 21:23:12 GMT 2014 - ugprade bootmod ===========================
=== Tue Jan 21 21:23:12 GMT 2014 - setup directories ===========================
=== Tue Jan 21 21:23:13 GMT 2014 - create modenv ===========================
=== Tue Jan 21 21:23:13 GMT 2014 - create rcS ===========================
=== Tue Jan 21 21:23:13 GMT 2014 - migrate bootmod ===========================
=== Tue Jan 21 21:23:13 GMT 2014 - setup hostname ===========================
=== Tue Jan 21 21:23:13 GMT 2014 - setup hosts ===========================
Kernel IP routing table Destination Gateway Genmask Flags
Metric Ref Use Iface XXX.XXX.X.X * 255.255.255.0 U 0 0 0 eth0
default XXX.XXX.X.X 0.0.0.0 UG 0 0 0 eth0
Interface: [eth0] IP: [XXX.XXX.X.XX]
=== Tue Jan 21 21:23:13 GMT 2014 - run init ===========================
killall: bootstrapd: no process killed
Loaded CIFS module into kernel.
Loaded FUSE module into kernel.
Anacron 2.3 started on 2014-01-21
Loading config file /mod/etc/mongoose.conf
Normal exit (0 jobs run) rs: starting recmon...
=== Tue Jan 21 21:24:27 GMT 2014 - complete ===========================
Error: 6 - Couldn't resolve host name rs: offering EPG... No EPG data to send.
 
Yes - the init phase is taking too long because your Humax box can't properly communicate with the RS service.. Does this box have Internet access? Is the DNS server set to the same on both boxes?
 
@DelftBlue - I've just sent you a message with a link to a new custom firmware version that should resolve this. Could you give it a go?
Ignore - please see below.
 
Right, as I said, the problem with Box A is that it is taking too long to start up the custom firmware services, which causes the disk initialisation code to time out.

One of the services must be causing the delay but I can't tell which from the logs so far. I originally suspected rs but that actually runs in background so shouldn't hold things up.

So, can you run the update-rcS diagnostic from the web interface or telnet menu? This will replace the service initialisation code with the version which will be in CFW 2.22 - it will produce more detail in modinit.log so I can work out which service is causing the delay. Once we know that, we can work out why and fix it.

The only bit of the modinit.log that's needed is the bit between
Code:
=== Tue Jan 21 21:23:13 GMT 2014 - run init ===========================
...
=== Tue Jan 21 21:24:27 GMT 2014 - complete ===========================
In this excerpt from your previous post you can see that this phase took 74 seconds (and disk initialisation gives up at 60).

btw, copying and pasting parts of logs from the web interface diagnostics screen should preserve newlines for you.
 
Many, many thanks af123.

One of the services must be causing the delay but I can't tell which from the logs so far. I originally suspected rs but that actually runs in background so shouldn't hold things up.

Both HDRs are connected to the internet. I think rs is working OK. The remote site shows both boxes appearing, with up-to-date info. (It was a blip in the internet connection that caused the report in yesterday's modinit.log)

Maybe it's Network-Shares-Automount?

So, can you run the update-rcS diagnostic from the web interface or telnet menu?

Ran update-rcS diagnostic, and re-booted. (I hope that was the right order to do it in)

Excerpt from new modinit.log below.

btw, copying and pasting parts of logs from the web interface diagnostics screen should preserve newlines for you.

That is the method I have been using but I am using a borrowed laptop with IE8. I am not at liberty to tinker with the software running on it, so have to use it 'as is' - and in IE8 the logs do not display with any line breaks, so I add them one by one :) :(.
Code:
=== Wed Jan 22 14:06:15 GMT 2014 - run init ===========================
killall: bootstrapd: no process killed
Wed Jan 22 14:06:15 GMT 2014 - /mod/etc/init.d/S01cifs Loaded CIFS module into kernel.
Wed Jan 22 14:06:15 GMT 2014 - /mod/etc/init.d/S01crond
Wed Jan 22 14:06:15 GMT 2014 - /mod/etc/init.d/S01fuse Loaded FUSE module into kernel.
Wed Jan 22 14:06:16 GMT 2014 - /mod/etc/init.d/S01mongoose
Wed Jan 22 14:06:16 GMT 2014 - /mod/etc/init.d/S02anacron
Loading config file /mod/etc/mongoose.conf Anacron 2.3 started on 2014-01-22
Wed Jan 22 14:06:16 GMT 2014 - /mod/etc/init.d/S02portmap
Normal exit (0 jobs run)
Wed Jan 22 14:06:16 GMT 2014 - /mod/etc/init.d/S30posttvcrash
Wed Jan 22 14:06:16 GMT 2014 - /mod/etc/init.d/S40ir
Wed Jan 22 14:06:17 GMT 2014 - /mod/etc/init.d/S55rs rs: starting recmon...
Wed Jan 22 14:06:17 GMT 2014 - /mod/etc/init.d/S60parseepg
Wed Jan 22 14:06:17 GMT 2014 - /mod/etc/init.d/S70editmonitor
Wed Jan 22 14:06:17 GMT 2014 - /mod/etc/init.d/S89betaftpd
Wed Jan 22 14:06:17 GMT 2014 - /mod/etc/init.d/S90epgpatch
Wed Jan 22 14:06:18 GMT 2014 - /mod/etc/init.d/S90netshares
Wed Jan 22 14:06:18 GMT 2014 - /mod/etc/init.d/S91samba
Wed Jan 22 14:06:18 GMT 2014 - /mod/etc/init.d/S99auto-unprotect
=== Wed Jan 22 14:07:27 GMT 2014 - complete ===========================
 
Interesting - it looks like it's the last job, auto-unprotect, that's taking the time. Here too although not as long as yours:

Code:
=== Wed Jan 22 14:35:35 UTC 2014 - run init ===========================
    Wed Jan 22 14:35:42 UTC 2014 - /mod/etc/init.d/S99auto-unprotect
=== Wed Jan 22 14:35:53 UTC 2014 - complete ===========================

Maybe xyz321 could look at an update to that package to make it do its initial scan as a background process?
 
Interesting - it looks like it's the last job, auto-unprotect, that's taking the time. Here too although not as long as yours:

=== Wed Jan 22 14:35:35 UTC 2014 - run init ===========================
Wed Jan 22 14:35:42 UTC 2014 - /mod/etc/init.d/S99auto-unprotect
=== Wed Jan 22 14:35:53 UTC 2014 - complete ===========================
I ran the update-rcS diagnostic on BoxB (where ntfs-3g does now work), and re-booted.
I got a similar time-delay to the one you just posted.

Excerpt from modinit.log for BoxB.

Code:
=== Wed Jan 22 15:45:23 GMT 2014 - run init ===========================
killall: bootstrapd: no process killed
Wed Jan 22 15:45:23 GMT 2014 - /mod/etc/init.d/S00wlan
Wed Jan 22 15:45:23 GMT 2014 - /mod/etc/init.d/S01cifs
Loaded CIFS module into kernel.
Wed Jan 22 15:45:23 GMT 2014 - /mod/etc/init.d/S01crond
Wed Jan 22 15:45:23 GMT 2014 - /mod/etc/init.d/S01fuse
Loaded FUSE module into kernel.
Wed Jan 22 15:45:23 GMT 2014 - /mod/etc/init.d/S01mongoose
Loading config file /mod/etc/mongoose.conf
Wed Jan 22 15:45:24 GMT 2014 - /mod/etc/init.d/S02anacron
Wed Jan 22 15:45:24 GMT 2014 - /mod/etc/init.d/S02portmap
Anacron 2.3 started on 2014-01-22 Normal exit (0 jobs run)
Wed Jan 22 15:45:24 GMT 2014 - /mod/etc/init.d/S30posttvcrash
Wed Jan 22 15:45:24 GMT 2014 - /mod/etc/init.d/S40ir
Wed Jan 22 15:45:25 GMT 2014 - /mod/etc/init.d/S55rs rs: starting recmon...
Wed Jan 22 15:45:25 GMT 2014 - /mod/etc/init.d/S60parseepg
Wed Jan 22 15:45:25 GMT 2014 - /mod/etc/init.d/S70editmonitor
Wed Jan 22 15:45:25 GMT 2014 - /mod/etc/init.d/S89betaftpd
Wed Jan 22 15:45:26 GMT 2014 - /mod/etc/init.d/S89virtual-disk
Wed Jan 22 15:45:26 GMT 2014 - /mod/etc/init.d/S90epgpatch #
Wed Jan 22 15:45:26 GMT 2014 - /mod/etc/init.d/S90netshares
Wed Jan 22 15:45:26 GMT 2014 - /mod/etc/init.d/S90ntpclient
Wed Jan 22 15:45:26 GMT 2014 - /mod/etc/init.d/S91samba
Wed Jan 22 15:45:31 GMT 2014 - /mod/etc/init.d/S99auto-unprotect
=== Wed Jan 22 15:45:46 GMT 2014 - complete =========================

As a test I thought I would remove auto-unprotect from BoxA to see what difference it made to the results (There isn't any HD content on BoxA, so auto-unprotect isn't strictly necessary) However it is unremovable (click remove, goes grey but then reappears again as installed, I guess it's a default essential app) ... so that test was a dead-end.
 
Back
Top