Skip to content

Commit

Permalink
Eden: Don't expect a raise on file lock
Browse files Browse the repository at this point in the history 
Summary:
Warning: Still unclear this is what we ultimately want until we root cause the change on behavior.

Summary of what's going on, best as I understand it.

This test is failing because it expects an exception to be raised, but isn't.

That exception is only raised when hg returns non zero, as can be seen here:

https://www.internalfb.com/code/fbsource/[1a0ccd34c77c54a4886d46b6058c045109374e09][history]/fbcode/eden/integration/lib/hgrepo.py?lines=176

We know that hg is being executed, as per the output:

```
[2024-03-14T12:16:19.425-07:00] Trying to run ['D:\full-fbsource\buck-out\v2\gen\fbcode\77df878185e61d70\eden\scm\__hg__\static\hg.exe', '--traceback', 'status', '--print0']
[2024-03-14T12:16:19.425-07:00]
[2024-03-14T12:16:19.425-07:00] test_file_locked_change_content (eden.integration.hg.update_test.UpdateCacheInvalidationTestTreeOnlyInMemory) ... \?\D:\D_Zfull-fbsource\edenfs\redirections\buck-out\v2\gen\fbcode\77df878185e61d70\eden\integration\hg\__update__\update#link-tree\eden\integration\lib\testcase.py:91: === temporary directory creation done at 0.000s (+0.000s)
[2024-03-14T12:16:19.425-07:00] \?\D:\D_Zfull-fbsource\edenfs\redirections\buck-out\v2\gen\fbcode\77df878185e61d70\eden\integration\hg\__update__\update#link-tree\eden\integration\lib\edenclient.py:368: Invoking eden daemon: 'D:\full-fbsource\buck-out\v2\gen\fbcode\77df878185e61d70\eden\fs\cli_rs\edenfsctl\__edenfsctl__\static\edenfsctl.exe' --etc-eden-dir 'C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\etc_eden' --home-dir 'C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\home' --config-dir 'C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\eden' daemon --daemon-binary 'D:\full-fbsource\buck-out\v2\gen\fbcode\77df878185e61d70\eden\fs\service\__edenfs-oss__\edenfs-oss.exe' --foreground -- --num_hg_import_threads 2 --local_storage_engine_unsafe memory --allowRoot --logging=eden.fs.fuse.FuseChannel=DBG3,eden.fs.inodes.CheckoutAction=DBG5,eden.fs.inodes.CheckoutContext=DBG5,eden.fs.inodes.TreeInode=DBG5 --enable_fault_injection --edenfsctlPath 'D:\full-fbsource\buck-out\v2\gen\fbcode\77df878185e61d70\eden\fs\cli_rs\edenfsctl\__edenfsctl__\static\edenfsctl.exe'
[2024-03-14T12:16:19.425-07:00] V0314 12:16:01.973447   328 StartupLogger.cpp:110] Starting edenfs (dev build), pid 1984, session_id 3545547132
[2024-03-14T12:16:19.425-07:00] E0314 12:16:01.974107   328 AsyncServerSocket.cpp:898] failed to set SO_KEEPALIVE on async server socket: Unknown error
[2024-03-14T12:16:19.425-07:00] V0314 12:16:01.986509   328 EdenServer.cpp:1089] Creating new memory store.
[2024-03-14T12:16:19.425-07:00] V0314 12:16:02.008845   328 StartupLogger.cpp:110] Opening local store...
[2024-03-14T12:16:19.425-07:00] V0314 12:16:02.008931   328 StartupLogger.cpp:110] Opened local store in 0 seconds.
[2024-03-14T12:16:19.425-07:00] V0314 12:16:02.009265   328 StartupLogger.cpp:110] No mount points currently configured.
[2024-03-14T12:16:19.425-07:00] I0314 12:16:02.009361   328 StartupLogger.cpp:110] Started EdenFS (pid 1984, session_id 3545547132) in 0s
[2024-03-14T12:16:19.425-07:00] \?\D:\D_Zfull-fbsource\edenfs\redirections\buck-out\v2\gen\fbcode\77df878185e61d70\eden\integration\hg\__update__\update#link-tree\eden\integration\lib\testcase.py:91: === eden daemon started at 1.439s (+1.439s)
[2024-03-14T12:16:19.425-07:00] \?\D:\D_Zfull-fbsource\edenfs\redirections\buck-out\v2\gen\fbcode\77df878185e61d70\eden\integration\hg\__update__\update#link-tree\eden\integration\lib\find_executables.py:211: Found hg.real binary: 'D:\full-fbsource\buck-out\v2\gen\fbcode\77df878185e61d70\eden\scm\__hg__\static\hg.exe'
[2024-03-14T12:16:19.425-07:00] \?\D:\D_Zfull-fbsource\edenfs\redirections\buck-out\v2\gen\fbcode\77df878185e61d70\eden\integration\hg\__update__\update#link-tree\eden\integration\lib\find_executables.py:205: Found hg binary: 'D:\full-fbsource\buck-out\v2\gen\fbcode\77df878185e61d70\eden\scm\__hg__\static\hg.exe'
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.521374Z TRACE workingcopy::workingcopy: initializing vfs at "C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\repos\main"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.522535Z TRACE workingcopy::workingcopy: case sensitive: false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.522718Z TRACE workingcopy::workingcopy: reading dirstate file
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.522801Z TRACE workingcopy::workingcopy: deserializing dirstate
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.523028Z TRACE workingcopy::workingcopy: loading treestate af89a000-36b9-4adf-97e5-4d987d729a9c BlockId(82)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.537400Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.537919Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.538327Z DEBUG status:pending_changes: workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.538555Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.538585Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560136Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] warning: watchman has recently started (pid 16708) - operation will be slower than usual
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560341Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=4096 mtime=1710443764 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560386Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir") mode=16895 size=0 mtime=1710443762 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560423Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir/file2") mode=33206 size=3 mtime=1710443762 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560459Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir/file1") mode=33206 size=3 mtime=1710443762 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560500Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560536Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560570Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.560973Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561034Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=4 treestate_needs_check=2
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561071Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561104Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561126Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561157Z  INFO status:pending_changes:detect_changes:fresh_instance work{wm_len=4}: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561189Z  INFO status:pending_changes:detect_changes:fresh_instance work{wm_len=4}: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561217Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561277Z TRACE status:pending_changes:detect_changes:submit wm_need_check: workingcopy::filechangedetector: changed (!in_parent, trackable) path=RepoPathBuf("dir/file1")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561299Z TRACE status:pending_changes:detect_changes:submit wm_need_check: workingcopy::filechangedetector: changed (!in_parent, trackable) path=RepoPathBuf("dir/file2")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561328Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561344Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561371Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.561401Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.563374Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.563487Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.564177Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565462Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565539Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565610Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565643Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565655Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565697Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565741Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565803Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.565836Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577156Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577228Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577270Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577340Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file1"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577385Z TRACE status:compute_status: workingcopy::status: path=dir/file1 is_deleted=false exist_parent=false exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577422Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file2"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.577455Z TRACE status:compute_status: workingcopy::status: path=dir/file2 is_deleted=false exist_parent=false exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.579784Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.579888Z  INFO status{status_len=2}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.589116Z TRACE workingcopy::workingcopy: initializing vfs at "C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\repos\main"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.590255Z TRACE workingcopy::workingcopy: case sensitive: false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.590414Z TRACE workingcopy::workingcopy: reading dirstate file
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.590480Z TRACE workingcopy::workingcopy: deserializing dirstate
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.590682Z TRACE workingcopy::workingcopy: loading treestate af89a000-36b9-4adf-97e5-4d987d729a9c BlockId(98)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.679079Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.679534Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.679959Z DEBUG status:pending_changes: workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.680097Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.680197Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.710809Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.710977Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=4096 mtime=1710443764 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711035Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711083Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711114Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711530Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711597Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=1 treestate_needs_check=2
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711626Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711667Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.711891Z TRACE status:pending_changes:detect_changes:submit ts_need_check: workingcopy::filechangedetector: changed (!in_parent, trackable) path=RepoPathBuf("dir/file1")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712103Z TRACE status:pending_changes:detect_changes:submit ts_need_check: workingcopy::filechangedetector: changed (!in_parent, trackable) path=RepoPathBuf("dir/file2")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712135Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712169Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712199Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712228Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712257Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.712291Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.714475Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.714589Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.715293Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716201Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716303Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716347Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716392Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716427Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716493Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716548Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716597Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716636Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716664Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716698Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716728Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716788Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file1"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716828Z TRACE status:compute_status: workingcopy::status: path=dir/file1 is_deleted=false exist_parent=false exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716864Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file2"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.716895Z TRACE status:compute_status: workingcopy::status: path=dir/file2 is_deleted=false exist_parent=false exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.718630Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:04.718759Z  INFO status{status_len=2}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.842326Z TRACE workingcopy::workingcopy: initializing vfs at "C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\repos\main"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.843466Z TRACE workingcopy::workingcopy: case sensitive: false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.843655Z TRACE workingcopy::workingcopy: reading dirstate file
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.843732Z TRACE workingcopy::workingcopy: deserializing dirstate
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.843957Z TRACE workingcopy::workingcopy: loading treestate af89a000-36b9-4adf-97e5-4d987d729a9c BlockId(343)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.858502Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.859085Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.859496Z DEBUG status:pending_changes: workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.859667Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.859746Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885146Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885526Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=8192 mtime=1710443766 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885577Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir/file1") mode=33206 size=3 mtime=1710443762 exists=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885613Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir") mode=16895 size=0 mtime=1710443766 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885650Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir/file1-654716ab") mode=0 size=0 mtime=0 exists=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885705Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885735Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.885767Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886312Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886397Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=4 treestate_needs_check=1
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886427Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886461Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886488Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886519Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886559Z TRACE status:pending_changes:detect_changes:submit wm_need_check: workingcopy::filechangedetector: not on disk, in P1 path=RepoPathBuf("dir/file1")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886608Z TRACE status:pending_changes:detect_changes:submit wm_need_check: workingcopy::filechangedetector: neither on disk nor in treestate path=RepoPathBuf("dir/file1-654716ab")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886638Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886668Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886704Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.886736Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.888964Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.889095Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.889714Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891044Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891148Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891206Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891236Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891281Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891336Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891386Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891435Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891473Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891506Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891566Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891588Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891642Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Deleted(RepoPathBuf("dir/file1"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.891684Z TRACE status:compute_status: workingcopy::status: path=dir/file1 is_deleted=true exist_parent=true exist_next=false copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.893702Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:06.893789Z  INFO status{status_len=1}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.188421Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.188844Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.188935Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.189586Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.229605Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230000Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=8192 mtime=1710443767 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230042Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir/file1") mode=33206 size=3 mtime=1710443762 exists=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230094Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir") mode=16895 size=0 mtime=1710443766 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230124Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf("dir/file1-654716ab") mode=0 size=0 mtime=0 exists=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230195Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230222Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230247Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230305Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230358Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=4 treestate_needs_check=1
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230381Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230415Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230436Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230465Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230500Z TRACE status:pending_changes:detect_changes:submit wm_need_check: workingcopy::filechangedetector: neither on disk nor in treestate path=RepoPathBuf("dir/file1-654716ab")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230537Z TRACE status:pending_changes:detect_changes:submit wm_need_check: workingcopy::filechangedetector: not on disk, in P1 path=RepoPathBuf("dir/file1")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230561Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230597Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230621Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.230653Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.232814Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.232912Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.233509Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.234750Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.234818Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.234860Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.234911Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.234982Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235007Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235059Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235096Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235130Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235165Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235212Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235245Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235298Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Deleted(RepoPathBuf("dir/file1"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.235351Z TRACE status:compute_status: workingcopy::status: path=dir/file1 is_deleted=true exist_parent=true exist_next=false copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.237175Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:07.237240Z  INFO status{status_len=1}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.920143Z TRACE workingcopy::workingcopy: initializing vfs at "C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\repos\main"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.921232Z TRACE workingcopy::workingcopy: case sensitive: false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.921381Z TRACE workingcopy::workingcopy: reading dirstate file
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.921468Z TRACE workingcopy::workingcopy: deserializing dirstate
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.921677Z TRACE workingcopy::workingcopy: loading treestate af89a000-36b9-4adf-97e5-4d987d729a9c BlockId(759)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.935014Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.935722Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.936115Z DEBUG status:pending_changes: workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.936347Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.936446Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.980159Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.980574Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=8192 mtime=1710443768 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.980675Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.980713Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.980742Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981124Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981193Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=1 treestate_needs_check=1
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981220Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981251Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981413Z TRACE status:pending_changes:detect_changes:submit ts_need_check: workingcopy::filechangedetector: changed (!in_parent, trackable) path=RepoPathBuf("dir/file3")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981443Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981471Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981497Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981533Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981564Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.981596Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.983687Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.983759Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.984371Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985621Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985695Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985731Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985777Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985810Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985843Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985886Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985926Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985968Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.985983Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.986031Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.986066Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.986116Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file3"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.986157Z TRACE status:compute_status: workingcopy::status: path=dir/file3 is_deleted=false exist_parent=false exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.987828Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:08.987980Z  INFO status{status_len=1}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.119023Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.119480Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.119583Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.120106Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.373826Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374214Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=8192 mtime=1710443769 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374282Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374314Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374341Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374393Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374437Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=1 treestate_needs_check=1
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374458Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374486Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374653Z TRACE status:pending_changes:detect_changes:submit ts_need_check: workingcopy::filechangedetector: changed (!in_parent, trackable) path=RepoPathBuf("dir/file3")
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374681Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374710Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374735Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374762Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374793Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.374825Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.376906Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.377002Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.377857Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379080Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379132Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379180Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379217Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379253Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379279Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379319Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379353Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379387Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379411Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379446Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379477Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379522Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file3"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.379556Z TRACE status:compute_status: workingcopy::status: path=dir/file3 is_deleted=false exist_parent=false exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.381455Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:09.381555Z  INFO status{status_len=1}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.647010Z TRACE workingcopy::workingcopy: initializing vfs at "C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\repos\main"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.648187Z TRACE workingcopy::workingcopy: case sensitive: false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.648371Z TRACE workingcopy::workingcopy: reading dirstate file
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.648431Z TRACE workingcopy::workingcopy: deserializing dirstate
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.648656Z TRACE workingcopy::workingcopy: loading treestate af89a000-36b9-4adf-97e5-4d987d729a9c BlockId(1197)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.663350Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.663901Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.664316Z DEBUG status:pending_changes: workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.664510Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.664538Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.686295Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.686637Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=8192 mtime=1710443771 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.686709Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.686753Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.686794Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687296Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687366Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=1 treestate_needs_check=1
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687413Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687451Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687619Z TRACE status:pending_changes:detect_changes:submit ts_need_check: workingcopy::filechangedetector: changed (metadata mismatch) path=RepoPathBuf("dir/file2") size_different=true exec_different=false symlink_different=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687644Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687677Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687715Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687753Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687778Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.687819Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.689833Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.689922Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.690596Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.691920Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.691970Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692038Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692123Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692158Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692218Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692268Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692290Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692318Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692347Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692385Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692414Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692462Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file2"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.692520Z TRACE status:compute_status: workingcopy::status: path=dir/file2 is_deleted=false exist_parent=true exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.694300Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:11.694376Z  INFO status{status_len=1}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.019666Z  INFO status: workingcopy::workingcopy: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.020097Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.020188Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.020622Z DEBUG workingcopy::watchmanfs::watchmanfs: sockpath="\\.\pipe\watchman-facebook"
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.058628Z  INFO status:pending_changes:query_files: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059006Z TRACE status:pending_changes: workingcopy::watchmanfs::watchmanfs: watchman file path=RepoPathBuf(".hg") mode=16895 size=8192 mtime=1710443772 exists=true
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059095Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059137Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059166Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059223Z  INFO status:pending_changes:detect_changes:prepare stuff:list_needs_check: workingcopy::watchmanfs::treestate: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059272Z DEBUG status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: watchman_needs_check=1 treestate_needs_check=1
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059296Z  INFO status:pending_changes:detect_changes:prepare stuff: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059326Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059513Z TRACE status:pending_changes:detect_changes:submit ts_need_check: workingcopy::filechangedetector: changed (metadata mismatch) path=RepoPathBuf("dir/file2") size_different=true exec_different=false symlink_different=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059555Z  INFO status:pending_changes:detect_changes:submit ts_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059585Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059612Z  INFO status:pending_changes:detect_changes:submit wm_need_check: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059641Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059685Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.059722Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.061727Z  INFO status:pending_changes:detect_changes:handle results:into_iter:check manifest{lookups=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.061827Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.062413Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063653Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}:get_content_stream: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063764Z  INFO status:pending_changes:detect_changes:handle results:into_iter:compare contents{keys=0}: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063806Z  INFO status:pending_changes:detect_changes:handle results:into_iter: workingcopy::filechangedetector: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063843Z  INFO status:pending_changes:detect_changes:handle results: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063863Z  INFO status:pending_changes:detect_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063902Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063941Z  INFO status:pending_changes:update_treestate: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.063989Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.064027Z DEBUG status:pending_changes:maybe_flush_treestate: workingcopy::util: pending_change_count=0 timeout_secs=Some(0)
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.064056Z  INFO status:pending_changes:maybe_flush_treestate: workingcopy::util: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.064095Z  INFO status:pending_changes: workingcopy::watchmanfs::watchmanfs: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.064128Z  INFO status:compute_status: workingcopy::status: enter
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.064185Z TRACE status:compute_status: workingcopy::workingcopy: pending change change_type=Changed(RepoPathBuf("dir/file2"))
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.064221Z TRACE status:compute_status: workingcopy::status: path=dir/file2 is_deleted=false exist_parent=true exist_next=true copied=false
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.065985Z  INFO status:compute_status: workingcopy::status: exit
[2024-03-14T12:16:19.425-07:00] 2024-03-14T19:16:12.066100Z  INFO status{status_len=1}: workingcopy::workingcopy: exit
[2024-03-14T12:16:19.425-07:00] I0314 12:16:14.285189 13284 EdenServiceHandler.cpp:607] [2050] semifuture_mount(C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main)
[2024-03-14T12:16:19.425-07:00] V0314 12:16:14.315451 12336 HgQueuedBackingStore.cpp:1341] commit 4aaf73f1a430bdd14eff43374a849e544f9066bb has manifest node 1ff7bbfabfd047a5ea9e0ff690d873327ec548b0
[2024-03-14T12:16:19.425-07:00] V0314 12:16:14.317094 15220 HgQueuedBackingStore.cpp:1300] imported mercurial commit 4aaf73f1a430bdd14eff43374a849e544f9066bb as tree 011ff7bbfabfd047a5ea9e0ff690d873327ec548b0
[2024-03-14T12:16:19.425-07:00] I0314 12:16:14.317684 14204 WindowsFsck.cpp:472] Start scanning \\?\C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main
[2024-03-14T12:16:19.425-07:00] I0314 12:16:14.318870 14204 WindowsFsck.cpp:515] Scanning complete for \\?\C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main
[2024-03-14T12:16:19.425-07:00] V0314 12:16:14.319871 13284 InodeMap.cpp:221] Initializing InodeMap for \\?\C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main
[2024-03-14T12:16:19.425-07:00] V0314 12:16:14.319963 13284 InodeMap.cpp:262] InodeMap initialized mount \\?\C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main from overlay, 0 inodes registered
[2024-03-14T12:16:19.425-07:00] I0314 12:16:14.320872 13284 PrjfsChannel.cpp:1614] Starting PrjfsChannel for: \\?\C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main with GUID: {41B3DF5A-7470-4F43-802B-2112E6B90341}
[2024-03-14T12:16:19.425-07:00] I0314 12:16:14.330961 13284 PrjfsChannel.cpp:1648] Started PrjfsChannel for: \\?\C:\cygwin\data\sandcastle\temp\eden_test.a5_hc41r\mounts\main
[2024-03-14T12:16:19.425-07:00] I0314 12:16:15.260239 15020 EdenServiceHandler.cpp:607] [2050] semifuture_mount() took 975035 µs
[2024-03-14T12:16:19.425-07:00] \?\D:\D_Zfull-fbsource\edenfs\redirection…
  • Loading branch information
@facebook-github-bot
Carlos Fernandez authored and facebook-github-bot committed Mar 15, 2024
1 parent b2822df commit 6d92c52
Showing 1 changed file with 0 additions and 2 deletions.
2 changes: 0 additions & 2 deletions eden/integration/hg/update_test.py
View file
Original file line number Diff line number Diff line change
Expand Up @@ -1239,8 +1239,6 @@ def test_file_locked_change_content(self) -> None:
self.repo.update(self.commit4)

self.assertEqual(self.read_file("dir/file2"), "new")
with self.assertRaises(hgrepo.HgError):
self.repo.status()

def test_file_locked_removal(self) -> None:
# TODO(zhaolong): remove this once this option is enabled everywhere.
Expand Down

0 comments on commit 6d92c52

Please sign in to comment.