Buil 2225 refresh not working

[Aziraphale]

Sometimes the content in folders is not updated, or at least it takes several minutes for the folder to refresh. Happens when adding or deleting files or subfolders. The bug is not consistent Most of the folders updates instantaneously, but some not. Very strange… BTW, Hitting F5 does not help.

[Alanrf]

Yes,I noticed something similar yesterday, but today, all seemed normal.

[Mathias (Author)]

I have not notice anything like that yet.

is it the FolderTree that is not updating or the filelist. ? (Is FolderTree active ? It have caused issue with the filelist before.. but should not now. )

It is strange that F5 do not help, because it forces a rescan of the drive,
If you activate the FileSystem Log in core settings> log  (set max debug level) and then you can see if reports some error. (It report a lot of stuff.. But real error are often marked out as "ERROR" or "Failed"
And then let me know..

Also is it local HD, USB , network location  ( server path like \\server\share\folder or mounted to device ? ) ?
I know some network servers/NAS sometimes do not send notification as they should that the file system is changed.
eg some version of Samba running on Linux sends the wrong file change notification in some situations.

In the FileSystem log you will also see if MC is receiving notification that the file system have changed. Maybe you do not get that on some special location / server.

[Aziraphale]

The folder tree is not active. It's a folder in a locked tab on local drive. (C:\FolderName\)
I did not see any warnings in the file system log.

Anyway, I closed the locked tab and made a new locked tab with the same folder, now it's working properly. Maybe something got stuck when I updated to the last build?

Thank you for the fast respond, and for creating the best file manager in the universe!

[Mathias (Author)]

No MC do not have any external files. everything is built into MC. only extension are external and they will not load if version do not match .exe so there cant be conflict there.

I thinking if it can have anything to do with that the tab is locked. Hmm if it happen again try to unlock tab and press Refresh..

Okey so no warning.  But when doing refresh and it don't work, is there anything in the log that say it aborts the scan ?
I know the log is very scary and lots of text, but most of the log is is just "text" explaining with it does,
The log should say exactly what it does when pressing refresh, and if something stops if from scanning or updating it should be in the log

When you do a refresh, the log would look something like ..
The scan start with the BrowsePath part.
And you see that it will Init FileSystem Scanning after a while.
And then when it is done it will update the UI


2016-08-03 13:21:18.211 [12312] (view=000001B26DFB23C0) BrowsePath requested : 000001B26A41FFB0 = R:\folder
2016-08-03 13:21:18.211 [12312] Option 'Scan and Rebuild' is set : 'R:\folder'
2016-08-03 13:21:18.211 [8648] ThreadWork::ProcessWork - Starting (work name : ColorOverrider)
2016-08-03 13:21:18.211 [8648] ThreadWork::ProcessWork - Work finished or stopped (Signaled : 0)
2016-08-03 13:21:18.211 [8648] _WorkerThreadProc - Jobb Name : ColorOverrider , Thread Jobb State : Stopped
2016-08-03 13:21:18.211 [12312] Unregister all FileSystemChange events - View : 000001B26DFB23C0 , FileItem : 000001B26A41FFB0
2016-08-03 13:21:18.211 [12312] FileItem - UpdateLock ON : 000001B26A41FFB0 = 'R:\folder'
2016-08-03 13:21:18.211 [12312] ThreadWorkManager [Icon] - Request STOP - Current state : Stopped
2016-08-03 13:21:18.211 [12312] Main Async FileSystem Scanner thread is not busy, So use it.
2016-08-03 13:21:18.211 [12312] File System Scanning Thread signaled to resume, so it can be closed (ID: 2840)!
2016-08-03 13:21:18.211 [12312] Leaving BrowsePath - Will await completion if filesystem scan is started
2016-08-03 13:21:18.211 [2840] Init FileSystem Scanning of path : R:\folder
2016-08-03 13:21:18.211 [2840] FileSystem Volume Scanning started : 000001B26A41FFB0 = R:\folder
2016-08-03 13:21:18.211 [2840] FileSystem Volume Scanning completed
2016-08-03 13:21:18.211 [2840] FileSystem scannig process completed
2016-08-03 13:21:18.211 [2840] File System Scanning Thread Suspended (2840)
2016-08-03 13:21:18.216 [12312] Update of BrowseCache started ( parent : R:\folder , Valid : 2 , Removed : 0 , New 0 )
2016-08-03 13:21:18.216 [12312] Force Rebuild of Browse cache
2016-08-03 13:21:18.216 [12312] Removes cache : "R:\folder" for Owner window : 000001B26DFB23C0
2016-08-03 13:21:18.216 [12312] Removes cache : "R:\folder" for Owner window : 000001B26DFE28E0
2016-08-03 13:21:18.216 [12312] Update BrowseCache in all views that caches path : (000001B26A41FFB0) = R:\folder
2016-08-03 13:21:18.216 [12312] (view=000001B26DFB23C0) Insert items into View BrowseCache : Items in Cache : 0, Items to Add : 2, Cache for path : R:\folder
2016-08-03 13:21:18.216 [12312] (view=000001B26DFE28E0) Insert items into View BrowseCache : Items in Cache : 0, Items to Add : 2, Cache for path : R:\folder
2016-08-03 13:21:18.216 [12312] (view=000001B26DFB23C0) Insert into view from BrowseCache - Existing Items : 0 , Items to Add : 2, Cache for path : R:\folder


Or if possible you can send me the log. at http://multicommander.com/upload , But then I also need to know what folder and at what timestamp I should be looking at.

[Aziraphale]

Ok. This is what I've found out. If I use "Lock Tab - Allow subpath change", the folder refreshes correctly.
With "Locked Tab" the folder will not refresh.

Here is the logfile, first I created a new folder and it did not refresh, then I tried to refresh manually, but that didn't work either.

2016-08-05 11:11:14.202 Show context menu for a single item : C:\0_Tegningsutsendelse
2016-08-05 11:11:14.202 Show context menu - Work Folder : C:
2016-08-05 11:11:16.572 [2140] Create New Folder in : "C:\0_Tegningsutsendelse" - VolID : 1
2016-08-05 11:11:16.572 [2140] Create folder : "C:\0_Tegningsutsendelse\" - VolID : 1
2016-08-05 11:11:16.572 [6352] ChangeNotifications - FILE ADDED : C:\0_Tegningsutsendelse\New Folder
2016-08-05 11:11:16.572 [2140] (view=0000000005A86C50) BrowsePath requested : 00000000059EE450 = C:\0_Tegningsutsendelse
2016-08-05 11:11:16.572 [2140] (view=0000000005A86C50) (GUI) Path Locked. can not browse new path : C:\0_Tegningsutsendelse\
2016-08-05 11:11:16.572 [2140] Leaving BrowsePath - Will wait for scan to complete (If scan was started)
2016-08-05 11:11:16.572 [2140] FSMonitor : File Added [Ignore] - C:\0_Tegningsutsendelse\New Folder
2016-08-05 11:11:22.235 [2140] (view=0000000005A86C50) BrowsePath requested : 00000000059EE450 = C:\0_Tegningsutsendelse
2016-08-05 11:11:22.235 [2140] (view=0000000005A86C50) (GUI) Path Locked. can not browse new path : C:\0_Tegningsutsendelse\
2016-08-05 11:11:22.251 [2140] Leaving BrowsePath - Will wait for scan to complete (If scan was started)
2016-08-05 11:12:44.377 [2140] OnChangeEvent - View : 0000000005A86C50 - Event : 'Path Updated' - FileItem 0000000000000000
2016-08-05 11:12:44.377 [2140] (view=0000000005A86C50) BrowsePath requested : 00000000059EE450 = C:\0_Tegningsutsendelse
2016-08-05 11:12:44.377 [2140] (view=0000000005A86C50) (GUI) Path Locked. can not browse new path : C:\0_Tegningsutsendelse\
2016-08-05 11:12:44.377 [2140] Leaving BrowsePath - Will wait for scan to complete (If scan was started)
 

[Mathias (Author)]

Ahh there now I was able to recreate it.
Thanks for the log output.. that really help

Looks like MC have to be started with the tab locked.
And the settings "Open new tab when ...." needed to be OFF

The problem is that the locked tab after restart have \ at the end.. (as you see in log text) so "C:\0_Tegningsutsendels" and "C:\0_Tegningsutsendelse\" do not match.
(Edit : I was wrong it did not have to do with the slash.. But I think I found why..)

So because of that it prevents the rescan of the folder

I will fix that