05:56:18: -> Client trace started, level=3. 05:56:18: -> Tracelevel set to 4. PID is 1636 05:56:18: -> Session ID is 66450128ff22e01 05:56:18: -> Session time is Fri Jul 27 12:56:18 2012 05:56:18: -> Client was compiled Jul 17 2012 10:40:37 05:56:18: -> Client was compiled with MSVC C/C++ 1310 05:56:18: -> Client build platform is microsoft-windows-x86 05:56:18: -> Build version is CVSNT 2.8.01 (Soolin) Build 4588 05:56:18: -> User name is admin 05:56:18: -> Client running on "Windows 7 x64 Service Pack 1 (Build 7601) [6.1.7601] (Service Pack 1)" 05:56:18: -> /3GB is enabled, highest user address is fffeffff 05:56:18: -> Client has 30 percent of memory in use. 05:56:18: -> Client has 2096696 total Kbytes of physical memory*. 05:56:18: -> Client has 1458436 free Kbytes of physical memory*. 05:56:18: -> Client has 2096696 total Kbytes of physical memory. 05:56:18: -> Client has 1458436 free Kbytes of physical memory. 05:56:18: -> Client has 4193392 total Kbytes of paging file. 05:56:18: -> Client has 3373660 free Kbytes of paging file. 05:56:18: -> Client has 4194176 total Kbytes of virtual memory. 05:56:18: -> Client has 4114120 free Kbytes of virtual memory. 05:56:18: -> Client has 0 free Kbytes of extended memory. 05:56:18: -> The custom heap has the low-fragmentation heap enabled. 05:56:18: -> The minimum large page size is 2097152. 05:56:18: -> Walking heap 0X024B0000... 05:56:18: -> Region 05:56:18: -> 49152 bytes committed 05:56:18: -> 212992 bytes uncommitted 05:56:18: -> First block address: 0X024B0590 05:56:18: -> Last block address: 0X024F0000 05:56:18: -> Data portion begins at: 0X024B0000 05:56:18: -> Size: 1416 bytes 05:56:18: -> Overhead: 0 bytes 05:56:18: -> Region index: 0 05:56:18: -> Block 05:56:18: -> Data portion begins at: 0X024BB040 05:56:18: -> Size: 4000 bytes 05:56:18: -> Overhead: 16 bytes 05:56:18: -> Region index: 0 05:56:18: -> Uncommitted range 05:56:18: -> Data portion begins at: 0X024BC000 05:56:18: -> Size: 212992 bytes 05:56:18: -> Overhead: 0 bytes 05:56:18: -> Region index: 0 05:56:18: -> Client Hardware information (Wow64): 05:56:18: -> Number of processors: 1 05:56:18: -> Processor type: 8664 05:56:18: -> processor architecture: x64 05:56:18: -> processor type 8664/Xeon model: unknown 05:56:18: -> Free space in C:\Users\admin\AppData\Local\Temp 1974177792 05:56:18: -> Total space in C:\Users\admin\AppData\Local\Temp 4292866048 05:56:18: -> Total Free space in C:\Users\admin\AppData\Local\Temp 1974177792 05:56:18: -> Loading protocol sspi as sspi.dll 05:56:18: -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/protocols/sspi.dll 05:56:18: -> main loop with CVSROOT=:sspi:localhost:/myrepo 05:56:18: -> main - about to start the server yes 05:56:18: -> start_server(verify_only=0) 05:56:18: -> client start - client_protocol->connect 05:56:18: -> Buffer size is 5120 05:56:18: -> sspi_connect 05:56:18: -> tcp_write(26,496) 05:56:18: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> SSPI:ClientAuthenticate(Negotiate,*NONE*,*NONE*) !Schannel - AcquireCredentialsHandle 05:56:19: -> SSPI:ClientAuthenticate() !Schannel - AcquireCredentialsHandle result = OK 05:56:19: -> SSPI:ClientAuthenticate() InitializeSecurityContext 05:56:19: -> tcp_write(2,496) 05:56:19: -> tcp_write(122,496) 05:56:19: -> tcp_read(2) using WSAEventSelect 05:56:19: -> tcp_read(2) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(169) using WSAEventSelect 05:56:19: -> tcp_read(169) immediate timeout previous thread count -1/0 05:56:19: -> SSPI:ClientAuthenticate() InitializeSecurityContext 05:56:19: -> tcp_write(2,496) 05:56:19: -> tcp_write(121,496) 05:56:19: -> tcp_read(2) using WSAEventSelect 05:56:19: -> tcp_read(2) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(29) using WSAEventSelect 05:56:19: -> tcp_read(29) immediate timeout previous thread count -1/0 05:56:19: -> SSPI:ClientAuthenticate() InitializeSecurityContext 05:56:19: -> tcp_write(8,496) 05:56:19: -> client start - Loop, getting responses from the server. 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 73(I), index=0 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 32( ), index=1 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 76(L), index=2 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 79(O), index=3 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 86(V), index=4 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 69(E), index=5 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 32( ), index=6 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 89(Y), index=7 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 79(O), index=8 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 85(U), index=9 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> recv_line - got 1 chars, first is 10( ), index=10 05:56:19: -> client start - got "I LOVE YOU" 05:56:19: -> client start - continue login. 05:56:19: -> client start - server started. 05:56:19: -> client start - send Valid-responses to server. 05:56:19: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=0 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=15 05:56:19: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=16 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=18 05:56:19: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=19 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=24 05:56:19: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=25 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=39 05:56:19: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=40 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=50 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=51 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=60 05:56:19: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=61 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=69 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=70 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=79 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=80 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=87 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=88 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=95 05:56:19: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=96 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=111 05:56:19: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=112 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=118 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=119 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=126 05:56:19: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=127 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=135 05:56:19: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=136 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=150 05:56:19: -> send_to_server_untranslated() calls buf_output() len=4 nbytes=151 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=155 05:56:19: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=156 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=164 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=165 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=172 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=173 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=180 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=181 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=193 05:56:19: -> send_to_server_untranslated() calls buf_output() len=20 nbytes=194 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=214 05:56:19: -> send_to_server_untranslated() calls buf_output() len=22 nbytes=215 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=237 05:56:19: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=238 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=248 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=249 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=261 05:56:19: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=262 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=270 05:56:19: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=271 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=279 05:56:19: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=280 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=296 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=297 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=314 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=315 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=327 05:56:19: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=328 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=334 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=335 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=347 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=348 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=349 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=350 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=357 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=358 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=359 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=360 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=361 05:56:19: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=362 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=364 05:56:19: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=365 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=381 05:56:19: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=382 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=396 05:56:19: -> client start - send valid-requests to server. 05:56:19: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=397 05:56:19: -> tcp_write(412,496) 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=412 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=424 05:56:19: -> tcp_write(13,496) 05:56:19: -> client start - rootless_encryption. 05:56:19: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=425 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=430 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=437 05:56:19: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=438 05:56:19: -> tcp_write(29,496) 05:56:19: -> tcp_read(4186) using WSAEventSelect 05:56:19: -> tcp_read(4186) immediate timeout previous thread count -1/0 05:56:19: -> Server codepage is CP1252 05:56:19: -> Client codepage is CP1252 05:56:19: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=454 05:56:19: -> send_to_server_untranslated() calls buf_output() len=32 nbytes=469 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=501 05:56:19: -> tcp_write(48,496) 05:56:19: -> tcp_read(4179) using WSAEventSelect 05:56:19: -> tcp_read(4179) immediate timeout previous thread count -1/0 05:56:19: -> Server version is CVSNT 2.8.01 (Soolin) Build 4588 05:56:19: -> Client version is CVSNT 2.8.01 (Soolin) Build 4588 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=502 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=519 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=536 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=553 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=570 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=587 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=588 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=589 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=590 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=591 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=592 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=593 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=594 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=595 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=596 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=597 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=598 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=599 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=600 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=601 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=602 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=603 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=604 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=605 05:56:19: -> Requesting server cvsignore 05:56:19: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=606 05:56:19: -> tcp_write(119,496) 05:56:19: -> tcp_read(4146) using WSAEventSelect 05:56:19: -> tcp_read(4146) immediate timeout previous thread count -1/0 05:56:19: -> wrap_add(*.a -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.a" 05:56:19: -> Call CGetOptions::getOpt: "*.a -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ai -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ai" 05:56:19: -> Call CGetOptions::getOpt: "*.ai -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.avi -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.avi" 05:56:19: -> Call CGetOptions::getOpt: "*.avi -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.bin -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.bin" 05:56:19: -> Call CGetOptions::getOpt: "*.bin -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.bmp -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.bmp" 05:56:19: -> Call CGetOptions::getOpt: "*.bmp -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.bz2 -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.bz2" 05:56:19: -> Call CGetOptions::getOpt: "*.bz2 -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.cdd -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.cdd" 05:56:19: -> Call CGetOptions::getOpt: "*.cdd -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.cdmz -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.cdmz" 05:56:19: -> Call CGetOptions::getOpt: "*.cdmz -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.chm -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.chm" 05:56:19: -> Call CGetOptions::getOpt: "*.chm -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.class -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.class" 05:56:19: -> Call CGetOptions::getOpt: "*.class -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.cur -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.cur" 05:56:19: -> Call CGetOptions::getOpt: "*.cur -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.dll -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.dll" 05:56:19: -> Call CGetOptions::getOpt: "*.dll -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.doc -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.doc" 05:56:19: -> Call CGetOptions::getOpt: "*.doc -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.docx -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.docx" 05:56:19: -> Call CGetOptions::getOpt: "*.docx -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.docm -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.docm" 05:56:19: -> Call CGetOptions::getOpt: "*.docm -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.fp -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.fp" 05:56:19: -> Call CGetOptions::getOpt: "*.fp -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.pptx -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.pptx" 05:56:19: -> Call CGetOptions::getOpt: "*.pptx -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.pptm -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.pptm" 05:56:19: -> Call CGetOptions::getOpt: "*.pptm -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.xlsx -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.xlsx" 05:56:19: -> Call CGetOptions::getOpt: "*.xlsx -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.xlsm -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.xlsm" 05:56:19: -> Call CGetOptions::getOpt: "*.xlsm -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.odt -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.odt" 05:56:19: -> Call CGetOptions::getOpt: "*.odt -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ods -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ods" 05:56:19: -> Call CGetOptions::getOpt: "*.ods -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.odp -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.odp" 05:56:19: -> Call CGetOptions::getOpt: "*.odp -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.odb -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.odb" 05:56:19: -> Call CGetOptions::getOpt: "*.odb -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.odg -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.odg" 05:56:19: -> Call CGetOptions::getOpt: "*.odg -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.odf -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.odf" 05:56:19: -> Call CGetOptions::getOpt: "*.odf -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.mpp -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.mpp" 05:56:19: -> Call CGetOptions::getOpt: "*.mpp -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.dvi -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.dvi" 05:56:19: -> Call CGetOptions::getOpt: "*.dvi -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.exe -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.exe" 05:56:19: -> Call CGetOptions::getOpt: "*.exe -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.gif -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.gif" 05:56:19: -> Call CGetOptions::getOpt: "*.gif -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.gz -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.gz" 05:56:19: -> Call CGetOptions::getOpt: "*.gz -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.hqx -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.hqx" 05:56:19: -> Call CGetOptions::getOpt: "*.hqx -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ico -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ico" 05:56:19: -> Call CGetOptions::getOpt: "*.ico -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ilk -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ilk" 05:56:19: -> Call CGetOptions::getOpt: "*.ilk -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.indd -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.indd" 05:56:19: -> Call CGetOptions::getOpt: "*.indd -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.iso -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.iso" 05:56:19: -> Call CGetOptions::getOpt: "*.iso -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.lib -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.lib" 05:56:19: -> Call CGetOptions::getOpt: "*.lib -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.jar -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.jar" 05:56:19: -> Call CGetOptions::getOpt: "*.jar -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.jpg -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.jpg" 05:56:19: -> Call CGetOptions::getOpt: "*.jpg -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.jpeg -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.jpeg" 05:56:19: -> Call CGetOptions::getOpt: "*.jpeg -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.lnk -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.lnk" 05:56:19: -> Call CGetOptions::getOpt: "*.lnk -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.mpg -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.mpg" 05:56:19: -> Call CGetOptions::getOpt: "*.mpg -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.mpeg -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.mpeg" 05:56:19: -> Call CGetOptions::getOpt: "*.mpeg -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.mov -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.mov" 05:56:19: -> Call CGetOptions::getOpt: "*.mov -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.mp3 -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.mp3" 05:56:19: -> Call CGetOptions::getOpt: "*.mp3 -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ncb -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ncb" 05:56:19: -> Call CGetOptions::getOpt: "*.ncb -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.o -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.o" 05:56:19: -> Call CGetOptions::getOpt: "*.o -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ogg -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ogg" 05:56:19: -> Call CGetOptions::getOpt: "*.ogg -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ocx -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ocx" 05:56:19: -> Call CGetOptions::getOpt: "*.ocx -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.obj -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.obj" 05:56:19: -> Call CGetOptions::getOpt: "*.obj -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.pdb -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.pdb" 05:56:19: -> Call CGetOptions::getOpt: "*.pdb -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.pdf -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.pdf" 05:56:19: -> Call CGetOptions::getOpt: "*.pdf -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.png -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.png" 05:56:19: -> Call CGetOptions::getOpt: "*.png -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ppt -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ppt" 05:56:19: -> Call CGetOptions::getOpt: "*.ppt -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.psd -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.psd" 05:56:19: -> Call CGetOptions::getOpt: "*.psd -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.res -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.res" 05:56:19: -> Call CGetOptions::getOpt: "*.res -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.rpm -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.rpm" 05:56:19: -> Call CGetOptions::getOpt: "*.rpm -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.sit -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.sit" 05:56:19: -> Call CGetOptions::getOpt: "*.sit -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.so -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.so" 05:56:19: -> Call CGetOptions::getOpt: "*.so -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.tar -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.tar" 05:56:19: -> Call CGetOptions::getOpt: "*.tar -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.tga -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.tga" 05:56:19: -> Call CGetOptions::getOpt: "*.tga -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.tgz -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.tgz" 05:56:19: -> Call CGetOptions::getOpt: "*.tgz -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.tif -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.tif" 05:56:19: -> Call CGetOptions::getOpt: "*.tif -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.tiff -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.tiff" 05:56:19: -> Call CGetOptions::getOpt: "*.tiff -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.ttf -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.ttf" 05:56:19: -> Call CGetOptions::getOpt: "*.ttf -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.uir -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.uir" 05:56:19: -> Call CGetOptions::getOpt: "*.uir -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.wav -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.wav" 05:56:19: -> Call CGetOptions::getOpt: "*.wav -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.wmv -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.wmv" 05:56:19: -> Call CGetOptions::getOpt: "*.wmv -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.xls -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.xls" 05:56:19: -> Call CGetOptions::getOpt: "*.xls -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.zip -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.zip" 05:56:19: -> Call CGetOptions::getOpt: "*.zip -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> wrap_add(*.Z -kb, 0, 0, 0, 0) 05:56:19: -> Call CFileAccess::mimetype: "*.Z" 05:56:19: -> Call CGetOptions::getOpt: "*.Z -kb" "+k:x:m:t:" 05:56:19: -> wrap_add() Sort. 05:56:19: -> Requesting server cvswrappers 05:56:19: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=621 05:56:19: -> tcp_write(17,496) 05:56:19: -> tcp_read(4144) using WSAEventSelect 05:56:19: -> tcp_read(4144) immediate timeout previous thread count -1/0 05:56:19: -> Got the server cvswrappers 05:56:19: -> The first item in the sorted list is "b" with wildcard "*.a" 05:56:19: -> Requesting server cvsrc (read-cvsrc2) 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=638 05:56:19: -> tcp_write(12,496) 05:56:19: -> tcp_read(4140) using WSAEventSelect 05:56:19: -> tcp_read(4140) immediate timeout previous thread count -1/0 05:56:19: -> Parsing global cvsrc started 05:56:19: -> Parsing global cvsrc finished 0 lines. 05:56:19: -> No local C:\Users\admin/.cvsrc to parse 05:56:19: -> Server: call the requested function commit 05:56:19: -> Commit start_recursion parsed root is remote argc=0 05:56:19: -> start_recursion((null),(null)) 05:56:19: -> start_recursion() dirleaveproc is NULL 05:56:19: -> start_recursion() update_dir set to "" 05:56:19: -> start_recursion - argument count (argc) == 0 05:56:19: -> start_recursion() Name_Root("") 05:56:19: -> start_recursion() Name_Root("") returned 05:56:19: -> * There were no arguments, so we'll probably just recurse. The 05:56:19: -> * exception to the rule is when we are called from a directory 05:56:19: -> * without any CVS administration files. That has always meant to 05:56:19: -> * process each of the sub-directories, so we pretend like we were 05:56:19: -> * called with the list of sub-dirs of the current dir as args 05:56:19: -> start_recursion: !just_subdirs - call addlist(dirlist==NULL, ".") 05:56:19: -> start_recursion: addlist() returns and now dirlist!=NULL 05:56:19: -> start_recursion/do_the_work: do_recursion() on the frame (dirlist != NULL). 05:56:19: -> do_recursion: begin 05:56:19: -> do_recursion: Check the value in CVSADM_ROOT 05:56:19: -> do_recursion: server does not appear to be active, update_dir= 05:56:19: -> do_recursion: Fill in repository with the current repository 05:56:19: -> do_recursion: update_dir= 05:56:19: -> Name_Repository((null),) 05:56:19: -> Name_Repository dup tmp is len 05:56:19: -> Name_Repository dup tmp is now len 05:56:19: -> Name_Repository open CVS/Repository 05:56:19: -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: -> Name_Repository closed CVS/Repository 05:56:19: -> Name_Repository read 1 junk 05:56:19: -> Name_Repository (stripped the newline) read 2 junk 05:56:19: -> Name_Repository isabsolute( junk )? 05:56:19: -> Name_Repository isabsolute( junk )! 05:56:19: -> Name_Repository Sanitize_Repository_Name( /myrepo/junk )! 05:56:19: -> Name_Repository return ( /myrepo/junk )! 05:56:19: -> do_recursion: update_dir= repository=/myrepo/junk 05:56:19: -> !(repository && (server_active || !current_parsed_root->isremote)) - so just strdup 05:56:19: -> Repository = /myrepo/junk 05:56:19: -> Mapped repository = /myrepo/junk 05:56:19: -> * The filesdoneproc needs to be called for each directory where files 05:56:19: -> * processed, or each directory that is processed by a call where no 05:56:19: -> * directories were passed in. In fact, the only time we don't want to 05:56:19: -> * call back the filesdoneproc is when we are processing directories that 05:56:19: -> * were passed in on the command line (or in the special case of `.' when 05:56:19: -> * we were called with no args 05:56:19: -> do_recursion: dirlist != NULL && filelist == NULL 05:56:19: -> do_recursion: dodoneproc=0 05:56:19: -> * If filelist or dirlist is already set, we don't look again. Otherwise, 05:56:19: -> * find the files and directories 05:56:19: -> do_recursion: something was passed on the command line 05:56:19: -> do_recursion: process the files (if any) 05:56:19: -> do_recursion: call-back files done proc (if any) 05:56:19: -> do_recursion: no: don't call-back files done proc (process_this_directory!dodoneprocframe->filesdoneproc != NULL) 05:56:19: -> do_recursion: at skip_directory 05:56:19: -> do_recursion: process the directories (if necessary) 05:56:19: -> do_recursion: really process the directories (if necessary) 05:56:19: -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent) 05:56:19: -> do_dir_proc() 05:56:19: -> do_dir_proc() update_dir= 05:56:19: -> do_dir_proc() -- set up update_dir() - no dots ? 05:56:19: -> do_dir_proc() -- set up update_dir is now dirp "." - no dots ? 05:56:19: -> do_dir_proc() -- frame->which & W_LOCAL 05:56:19: -> do_dir_proc() -- !(frame->which&W_FAKE) && CVSroot_cmdline == NULL && !server_active 05:56:19: -> do_dir_proc() -- dir_return!=R_SKIP_ALL 05:56:19: -> do_dir_proc() -- process_this_directory 05:56:19: -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 05:56:19: -> do_dir_proc() after predirent call ParseTag_Dir() 05:56:19: -> ParseTag_Dir(.,,,0,) 05:56:19: -> open_directory(/myrepo/junk,.,(null),(null),0,(null),1) 05:56:19: -> open_directory() directory_stack_size increased by one to 1 05:56:19: -> Look for rename script file CVS/Rename 05:56:19: -> Rename script file does not exist 05:56:19: -> directory opened 05:56:19: -> do_dir_proc() -- call-back dir entry proc (if any) 05:56:19: -> do_dir_proc() -- If we're doing the actual processing, call direntproc. 05:56:19: -> find_dirent_proc 05:56:19: -> find_dirent_proc hint==R_PROCESS cvs commit: Examining . 05:56:19: -> find_dirent_proc - did not find ./CVS/Rename 05:56:19: -> find_dirent_proc - return R_PROCESS - renames=0 05:56:19: -> do_dir_proc() -- only process the dir if the return code was 0. 05:56:19: -> do_dir_proc() -- (frame->which&(W_LOCAL|W_FAKE)) 05:56:19: -> debug: 18 05:56:19: -> debug: 19 05:56:19: -> debug: 20 05:56:19: -> do_recursion: begin 05:56:19: -> do_recursion: Check the value in CVSADM_ROOT 05:56:19: -> do_recursion: server does not appear to be active, update_dir= 05:56:19: -> do_recursion: Fill in repository with the current repository 05:56:19: -> do_recursion: update_dir= 05:56:19: -> Name_Repository((null),) 05:56:19: -> Name_Repository dup tmp is len 05:56:19: -> Name_Repository dup tmp is now len 05:56:19: -> Name_Repository open CVS/Repository 05:56:19: -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: -> Name_Repository closed CVS/Repository 05:56:19: -> Name_Repository read 1 junk 05:56:19: -> Name_Repository (stripped the newline) read 2 junk 05:56:19: -> Name_Repository isabsolute( junk )? 05:56:19: -> Name_Repository isabsolute( junk )! 05:56:19: -> Name_Repository Sanitize_Repository_Name( /myrepo/junk )! 05:56:19: -> Name_Repository return ( /myrepo/junk )! 05:56:19: -> do_recursion: update_dir= repository=/myrepo/junk 05:56:19: -> !(repository && (server_active || !current_parsed_root->isremote)) - so just strdup 05:56:19: -> Repository = /myrepo/junk 05:56:19: -> Mapped repository = /myrepo/junk 05:56:19: -> * The filesdoneproc needs to be called for each directory where files 05:56:19: -> * processed, or each directory that is processed by a call where no 05:56:19: -> * directories were passed in. In fact, the only time we don't want to 05:56:19: -> * call back the filesdoneproc is when we are processing directories that 05:56:19: -> * were passed in on the command line (or in the special case of `.' when 05:56:19: -> * we were called with no args 05:56:19: -> do_recursion: dirlist == NULL && filelist == NULL 05:56:19: -> do_recursion: dodoneproc=1 05:56:19: -> * If filelist or dirlist is already set, we don't look again. Otherwise, 05:56:19: -> * find the files and directories 05:56:19: -> * both lists were NULL, so start from scratch 05:56:19: -> * In the !(which & W_LOCAL) case, we filled in repository 05:56:19: -> earlier in the function. In the (which & W_LOCAL) case, 05:56:19: -> the Find_Names function is going to look through the 05:56:19: -> Entries file. If we do not have a repository, that 05:56:19: -> does not make sense, so we insist upon having a 05:56:19: -> repository at this point. Name_Repository will give a 05:56:19: -> reasonable error message. 05:56:19: -> do_recursion: repository != NULL 05:56:19: -> do_recursion: mapped_repository != NULL 05:56:19: -> do_recursion: find the files and fill in entries if appropriate 05:56:19: -> do_recursion: process_this_directory, call Find_Names() 05:56:19: -> Entries_Open() 05:56:19: -> Entries_Open ParseTag() 05:56:19: -> ParseTag() 05:56:19: -> ParseTag_Dir((null),,,0,) 05:56:19: -> Entries_Open aflag==0 && dirtag==0 && dirdate==0 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entries_Open fclose() CVSADM_ENT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle 05:56:19: -> Entries_Open fclose() CVSADM_ENTEXT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r") 05:56:19: -> Entries_Open clean up and return 05:56:19: -> Entries_Open done 05:56:19: -> do_recursion: Find_Names got a filelist 05:56:19: -> do_recursion: process the files (if any) 05:56:19: -> do_recursion: read lock it if necessary 05:56:19: -> * For the server, we handle notifications in a completely different 05:56:19: -> place (server_notify). For local, we can't do them here--we don't 05:56:19: -> have writelocks in place, and there is no way to get writelocks here 05:56:19: -> do_recursion: process the files by calling walklist() 05:56:19: -> do_file_proc: process the files "a.txt" 05:56:19: -> !(server_active || !current_parsed_root->isremote) - so just strdup 05:56:19: -> do_file_proc: mapped name of file "a.txt" 05:56:19: -> do_file_proc: call findnode_fn() 05:56:19: -> do_file_proc: got a result from findnode_fn() 05:56:19: -> do_file_proc: verify_access() returned ok 05:56:19: -> find_fileproc(a.txt) 05:56:19: -> Version_TS((null),(null),0) 05:56:19: -> Version_TS -- findnode_fn() not force_case_match 05:56:19: -> Version_TS -- findnode() found something that wasn't a subdir. 05:56:19: -> wnt_stat(a.txt) 05:56:19: -> _statcore(00000000,a.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(a,0000001C) returns 0400 05:56:19: -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> do_file_proc: finished fileproc() 05:56:19: -> do_file_proc: return 05:56:19: -> do_file_proc: process the files "b.txt" 05:56:19: -> !(server_active || !current_parsed_root->isremote) - so just strdup 05:56:19: -> do_file_proc: mapped name of file "b.txt" 05:56:19: -> do_file_proc: call findnode_fn() 05:56:19: -> do_file_proc: got a result from findnode_fn() 05:56:19: -> do_file_proc: verify_access() returned ok 05:56:19: -> find_fileproc(b.txt) 05:56:19: -> Version_TS((null),(null),0) 05:56:19: -> Version_TS -- findnode_fn() not force_case_match 05:56:19: -> Version_TS -- findnode() found something that wasn't a subdir. 05:56:19: -> wnt_stat(b.txt) 05:56:19: -> _statcore(00000000,b.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(b,0000001C) returns 0600 05:56:19: -> _statcore(b.txt) returns S_IWRITE 05:56:19: -> do_file_proc: finished fileproc() 05:56:19: -> do_file_proc: return 05:56:19: -> do_recursion: unlock it 05:56:19: -> do_recursion: clean up by calling dellist(filelist) 05:56:19: -> do_recursion: call-back files done proc (if any) 05:56:19: -> do_recursion: yes: call-back files done proc (if any) 05:56:19: -> find_filesdoneproc 05:56:19: -> wnt_stat(ci.txt) 05:56:19: -> _statcore(00000000,ci.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(c,0000001C) returns 0400 05:56:19: -> _statcore(ci.txt) returns S_IWRITE 05:56:19: -> do_recursion: at skip_directory 05:56:19: -> do_recursion: process the directories (if necessary) 05:56:19: -> do_recursion: really process the directories (if necessary) 05:56:19: -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent) 05:56:19: -> do_recursion() -- walklist() returns and now dirlist!=NULL 05:56:19: -> Entries_Close - isfile ("CVS/Entries.Log") 05:56:19: -> Entries_Close - dellist (list) 05:56:19: -> do_recursion: free the saved copy of the pointer if necessary 05:56:19: -> debug: 21 05:56:19: -> debug: 22 05:56:19: -> do_dir_proc() -- call-back dir leave proc (if any) process_this_directory 05:56:19: -> debug: 23 05:56:19: -> close_directory() 05:56:19: -> close_directory() directory_stack_size decreased by one to 0 05:56:19: -> do_recursion() -- walklist() returns and now dirlist!=NULL 05:56:19: -> do_recursion: free the saved copy of the pointer if necessary 05:56:19: -> start_recursion: Free the data which expand_wild allocated. 05:56:19: -> start_recursion: Free the data - update_dir 05:56:19: -> start_recursion: Free the data - update_repos 05:56:19: -> start_recursion: (args_to_send_when_finished == NULL) 05:56:19: -> start_recursion: return 0. 05:56:19: -> Commit after start_recursion() find_args.argc=1 and find_args.renames=0 05:56:19: -> * Now we keep track of which files we actually are going to 05:56:19: -> operate on, and only work with those files in the future. 05:56:19: -> This saves time--we don't want to search the file system 05:56:19: -> of the working directory twice. 05:56:19: -> copy_ulist( ) create the argv[0] list "a.txt". 05:56:19: -> Commit created the argv[] list with find_args.argc=1 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=650 05:56:19: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=659 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=661 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=662 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=671 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=678 05:56:19: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=679 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=689 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=690 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=691 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=703 05:56:19: -> send_to_server_untranslated() calls buf_output() len=13 nbytes=704 05:56:19: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=717 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=723 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=724 05:56:19: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=733 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=735 05:56:19: -> client (1) calls start_recursion() with 1 args 05:56:19: -> start_recursion((null),(null)) 05:56:19: -> start_recursion() dirleaveproc is OK 05:56:19: -> start_recursion() update_dir set to "" 05:56:19: -> start_recursion - argument count (argc) == 1 05:56:19: -> * There were arguments, so we have to handle them by hand. To do 05:56:19: -> * that, we set up the filelist and dirlist with the arguments and 05:56:19: -> * call do_recursion. do_recursion recognizes the fact that the 05:56:19: -> * lists are non-null when it starts and doesn't update them. 05:56:19: -> 05:56:19: -> * explicitly named directories are stored in dirlist. 05:56:19: -> * explicitly named files are stored in filelist. 05:56:19: -> * other possibility is named entities whicha are not currently in 05:56:19: -> * the working directory. 05:56:19: -> * if this argument is a directory, then add it to the list of directories. 05:56:19: -> * otherwise, split argument into directory and component names. 05:56:19: -> * Now break out argv[i] into directory part (DIR) and file part (COMP). 05:56:19: -> DIR and COMP will each point to a newly malloc'd string. 05:56:19: -> ParseTag_Dir(.,,,0,) 05:56:19: -> start_recursion: (1) addfile(files_by_dir,".","a.txt") 05:56:19: -> * At this point we have looped over all named arguments and built 05:56:19: -> a coupla lists. Now we unroll the lists, setting up and 05:56:19: -> calling do_recursion. 05:56:19: -> call walklist() with unroll_files_proc and files_by_dir 05:56:19: -> unroll_files_proc() begin 05:56:19: -> * if this dir was also an explicitly named argument, then skip 05:56:19: -> it. We'll catch it later when we do dirs. 05:56:19: -> * otherwise, call dorecusion for this list of files. 05:56:19: -> Name_Repository((null),) 05:56:19: -> Name_Repository dup tmp is len 05:56:19: -> Name_Repository dup tmp is now len 05:56:19: -> Name_Repository open CVS/Repository 05:56:19: -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: -> Name_Repository closed CVS/Repository 05:56:19: -> Name_Repository read 1 junk 05:56:19: -> Name_Repository (stripped the newline) read 2 junk 05:56:19: -> Name_Repository isabsolute( junk )? 05:56:19: -> Name_Repository isabsolute( junk )! 05:56:19: -> Name_Repository Sanitize_Repository_Name( /myrepo/junk )! 05:56:19: -> Name_Repository return ( /myrepo/junk )! 05:56:19: -> ParseTag() 05:56:19: -> ParseTag_Dir((null),,,0,) 05:56:19: -> open_directory(/myrepo/junk,.,(null),(null),0,(null),1) 05:56:19: -> open_directory() directory_stack_size increased by one to 1 05:56:19: -> Look for rename script file CVS/Rename 05:56:19: -> Rename script file does not exist 05:56:19: -> directory opened 05:56:19: -> do_recursion: begin 05:56:19: -> do_recursion: Check the value in CVSADM_ROOT 05:56:19: -> do_recursion: server does not appear to be active, update_dir= 05:56:19: -> do_recursion: Fill in repository with the current repository 05:56:19: -> do_recursion: update_dir= 05:56:19: -> Name_Repository((null),) 05:56:19: -> Name_Repository dup tmp is len 05:56:19: -> Name_Repository dup tmp is now len 05:56:19: -> Name_Repository open CVS/Repository 05:56:19: -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: -> Name_Repository closed CVS/Repository 05:56:19: -> Name_Repository read 1 junk 05:56:19: -> Name_Repository (stripped the newline) read 2 junk 05:56:19: -> Name_Repository isabsolute( junk )? 05:56:19: -> Name_Repository isabsolute( junk )! 05:56:19: -> Name_Repository Sanitize_Repository_Name( /myrepo/junk )! 05:56:19: -> Name_Repository return ( /myrepo/junk )! 05:56:19: -> do_recursion: update_dir= repository=/myrepo/junk 05:56:19: -> !(repository && (server_active || !current_parsed_root->isremote)) - so just strdup 05:56:19: -> Repository = /myrepo/junk 05:56:19: -> Mapped repository = /myrepo/junk 05:56:19: -> * The filesdoneproc needs to be called for each directory where files 05:56:19: -> * processed, or each directory that is processed by a call where no 05:56:19: -> * directories were passed in. In fact, the only time we don't want to 05:56:19: -> * call back the filesdoneproc is when we are processing directories that 05:56:19: -> * were passed in on the command line (or in the special case of `.' when 05:56:19: -> * we were called with no args 05:56:19: -> do_recursion: dirlist == NULL && filelist == NULL 05:56:19: -> do_recursion: dodoneproc=1 05:56:19: -> * If filelist or dirlist is already set, we don't look again. Otherwise, 05:56:19: -> * find the files and directories 05:56:19: -> do_recursion: something was passed on the command line 05:56:19: -> do_recursion: we will process files, so pre-parse entries 05:56:19: -> Entries_Open() 05:56:19: -> Entries_Open ParseTag() 05:56:19: -> ParseTag() 05:56:19: -> ParseTag_Dir((null),,,0,) 05:56:19: -> Entries_Open aflag==0 && dirtag==0 && dirdate==0 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entries_Open fclose() CVSADM_ENT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle 05:56:19: -> Entries_Open fclose() CVSADM_ENTEXT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r") 05:56:19: -> Entries_Open clean up and return 05:56:19: -> Entries_Open done 05:56:19: -> do_recursion: process the files (if any) 05:56:19: -> do_recursion: read lock it if necessary 05:56:19: -> * For the server, we handle notifications in a completely different 05:56:19: -> place (server_notify). For local, we can't do them here--we don't 05:56:19: -> have writelocks in place, and there is no way to get writelocks here 05:56:19: -> do_recursion: process the files by calling walklist() 05:56:19: -> do_file_proc: process the files "a.txt" 05:56:19: -> !(server_active || !current_parsed_root->isremote) - so just strdup 05:56:19: -> do_file_proc: mapped name of file "a.txt" 05:56:19: -> do_file_proc: call findnode_fn() 05:56:19: -> do_file_proc: got a result from findnode_fn() 05:56:19: -> do_file_proc: verify_access() returned ok 05:56:19: -> send_fileproc (1) 05:56:19: -> send_a_repository(,/myrepo/junk,) 05:56:19: -> send_repository(,/myrepo/junk,) 05:56:19: -> allocate adm_name * 80 is large enough for any of CVSADM_*. 05:56:19: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=736 05:56:19: -> * Send the directory name. 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=746 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=747 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=748 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=760 05:56:19: -> Version_TS((null),(null),0) 05:56:19: -> Version_TS -- findnode_fn() not force_case_match 05:56:19: -> Version_TS -- findnode() found something that wasn't a subdir. 05:56:19: -> wnt_stat(a.txt) 05:56:19: -> _statcore(00000000,a.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(a,0000001C) returns 0400 05:56:19: -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=761 05:56:19: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=768 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=773 05:56:19: -> send_to_server_untranslated() calls buf_output() len=3 nbytes=774 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=777 05:56:19: -> send_to_server_untranslated() calls buf_output() len=24 nbytes=778 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=802 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=803 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=804 05:56:19: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=805 05:56:19: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=817 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=822 05:56:19: -> send_to_server_untranslated() calls buf_output() len=0 nbytes=823 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=823 05:56:19: -> send_to_server_untranslated() calls buf_output() len=0 nbytes=824 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=824 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=825 05:56:19: -> send_to_server_untranslated() calls buf_output() len=0 nbytes=826 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=826 05:56:19: -> send_to_server_untranslated() calls buf_output() len=0 nbytes=827 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=827 05:56:19: -> send_to_server_untranslated() calls buf_output() len=0 nbytes=828 05:56:19: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=828 05:56:19: -> Sending file 'a.txt' to server 05:56:19: -> wnt_stat(a.txt) 05:56:19: -> _statcore(00000000,a.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(a,0000001C) returns 0400 05:56:19: -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> If the file is unicode (and the remote server supports it), translate it to utf8 before transmitting. 05:56:19: -> instantiate cdp1. 05:56:19: -> !open_binary - will begin. 05:56:19: -> encode is NOT set. 05:56:19: -> !open_binary - will StripCrLf (again). 05:56:19: -> StripCrLf -- appears to be a simple file (no carriage returns) 05:56:19: -> StripCrLfSmall begin 05:56:19: -> StripCrLfSmall return true 05:56:19: -> wnt_stat(a.txt) 05:56:19: -> _statcore(00000000,a.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(a,0000001C) returns 0400 05:56:19: -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> send_to_server_untranslated() calls buf_output() len=13 nbytes=830 05:56:19: -> send_to_server_untranslated() calls buf_output() len=26 nbytes=843 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=869 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=870 05:56:19: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=879 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=884 05:56:19: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=885 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=895 05:56:19: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=896 05:56:19: -> Sending ascii file of size '8' to server unencoded without md5. 05:56:19: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=898 05:56:19: -> Sending completed, count=0, avg size=0. 05:56:19: -> do_file_proc: finished fileproc() 05:56:19: -> do_file_proc: return 05:56:19: -> do_recursion: unlock it 05:56:19: -> do_recursion: clean up by calling dellist(filelist) 05:56:19: -> do_recursion: call-back files done proc (if any) 05:56:19: -> do_recursion: yes: call-back files done proc (if any) 05:56:19: -> do_recursion: at skip_directory 05:56:19: -> do_recursion: process the directories (if necessary) 05:56:19: -> do_recursion: cannot process the directories (dirlist == NULL) 05:56:19: -> Entries_Close - isfile ("CVS/Entries.Log") 05:56:19: -> Entries_Close - dellist (list) 05:56:19: -> do_recursion: free the saved copy of the pointer if necessary 05:56:19: -> more efficient - close directory in unroll_files_proc? 05:56:19: -> close_directory() 05:56:19: -> close_directory() directory_stack_size decreased by one to 0 05:56:19: -> unroll_files_proc() end 05:56:19: -> walklist call with unroll_files_proc done. err=0 05:56:19: -> freenode() free the badbad DIRS 05:56:19: -> dellist after unroll_files_proc done. 05:56:19: -> start_recursion: cannot do_recursion on the dirlist. 05:56:19: -> start_recursion: Free the data which expand_wild allocated. 05:56:19: -> start_recursion: Free the data - update_dir 05:56:19: -> start_recursion: Free the data - update_repos 05:56:19: -> start_recursion: (args_to_send_when_finished == NULL) 05:56:19: -> start_recursion: return 0. 05:56:19: -> send_repository(,/myrepo/junk,.) 05:56:19: -> send_files (1) -- Send Max-dotdot if needed. 05:56:19: -> Entries_Open() 05:56:19: -> Entries_Open ParseTag() 05:56:19: -> ParseTag() 05:56:19: -> ParseTag_Dir((null),,,2008094204,) 05:56:19: -> Entries_Open aflag==0 && dirtag==0 && dirdate==0 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entries_Open fclose() CVSADM_ENT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle 05:56:19: -> Entries_Open fclose() CVSADM_ENTEXT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r") 05:56:19: -> Entries_Open clean up and return 05:56:19: -> Entries_Open done 05:56:19: -> Entries_Close - isfile ("CVS/Entries.Log") 05:56:19: -> Entries_Close - dellist (list) 05:56:19: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=906 05:56:19: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=915 05:56:19: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=920 05:56:19: -> send_to_server_untranslated() calls buf_output() len=3 nbytes=921 05:56:19: -> tcp_write(274,496) 05:56:19: -> tcp_read(4138) using WSAEventSelect 05:56:19: -> tcp_read(4138) immediate timeout previous thread count -1/0 ? ci.txt 05:56:19: S -> change_mode (a.txt,u=rw,g=,o=,0) 05:56:19: S -> wnt_chmod(a.txt,0600) 05:56:19: S -> SetUnixFileModeNtEA(a,0600) 05:56:19: S -> server_main started 05:56:19: S -> server_main process id is 1928 05:56:19: S -> server_main CVS2_SERVER_SLEEP not set. 05:56:19: S -> run plugintools proc server 05:56:19: S -> run_trigger() 05:56:19: S -> LoadTrigger(audit.dll) 05:56:19: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/audit.dll 05:56:19: S -> Audit trigger not enabled. 05:56:19: S -> Couldn't load trigger audit.dll 05:56:19: S -> LoadTrigger(bugzilla.dll) 05:56:19: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/bugzilla.dll 05:56:19: S -> Defect Tracking trigger not enabled. 05:56:19: S -> Couldn't load trigger bugzilla.dll 05:56:19: S -> Checking admin file C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/admin for user admin 05:56:19: S -> Checked admin file (null) for user admin they are not an admin. 05:56:19: S -> Check windows admin for user. 05:56:19: S -> Determine if the current thread is running as a user that is a member of the local admins group. 05:56:19: S -> No - is NOT admin (WIN32). 05:56:19: S -> Check windows admin for user completed. 05:56:19: S -> InitTrigger(checkout.dll) 05:56:19: S -> Checkout trigger not enabled. 05:56:19: S -> Couldn't load trigger checkout.dll 05:56:19: S -> InitTrigger(email.dll) 05:56:19: S -> InitTrigger(info.dll) 05:56:19: S -> InitTrigger(make.dll) 05:56:19: S -> Make trigger not enabled. 05:56:19: S -> Couldn't load trigger make.dll 05:56:19: S -> InitTrigger(script.dll) 05:56:19: S -> Script trigger not enabled. 05:56:19: S -> Couldn't load trigger script.dll 05:56:19: S -> InitTrigger(sync.dll) 05:56:19: S -> Sync trigger not enabled. 05:56:19: S -> Couldn't load trigger sync.dll 05:56:19: S -> Call pre-loaded 'email.dll' 05:56:19: S -> plugintools_proc() 05:56:19: S -> Call pre-loaded 'email.dll' returned 0, total ret 0 05:56:19: S -> Call pre-loaded 'info.dll' 05:56:19: S -> plugintools_proc() 05:56:19: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 05:56:19: S -> run_trigger returning 0 05:56:19: S -> run precommand proc server 05:56:19: S -> run_trigger() 05:56:19: S -> Call pre-loaded 'email.dll' 05:56:19: S -> precommand_proc() 05:56:19: S -> Call pre-loaded 'email.dll' returned 0, total ret 0 05:56:19: S -> Call pre-loaded 'info.dll' 05:56:19: S -> precommand_proc() 05:56:19: S -> default_trigger: parse_info(CVSROOT/precommand,%r %c,%) 05:56:19: S -> CFileAccess::open('C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/precommand','rb') 05:56:19: S -> CFileAccess::open() setvbuf(_IONBF) 05:56:19: S -> CFileAccess::close() 05:56:19: S -> CFileAccess::close() 05:56:19: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 05:56:19: S -> run_trigger returning 0 05:56:19: S -> Commit start_recursion "Run the recursion processor to verify the files are all up-to-date" argc=1 05:56:19: S -> start_recursion((null),(null)) 05:56:19: S -> start_recursion() dirleaveproc is NULL 05:56:19: S -> start_recursion() update_dir set to "" 05:56:19: S -> start_recursion - argument count (argc) == 1 05:56:19: S -> * There were arguments, so we have to handle them by hand. To do 05:56:19: S -> * that, we set up the filelist and dirlist with the arguments and 05:56:19: S -> * call do_recursion. do_recursion recognizes the fact that the 05:56:19: S -> * lists are non-null when it starts and doesn't update them. 05:56:19: S -> 05:56:19: S -> * explicitly named directories are stored in dirlist. 05:56:19: S -> * explicitly named files are stored in filelist. 05:56:19: S -> * other possibility is named entities whicha are not currently in 05:56:19: S -> * the working directory. 05:56:19: S -> * if this argument is a directory, then add it to the list of directories. 05:56:19: S -> * otherwise, split argument into directory and component names. 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> * Now break out argv[i] into directory part (DIR) and file part (COMP). 05:56:19: S -> DIR and COMP will each point to a newly malloc'd string. 05:56:19: S -> ParseTag_Dir(.,,,0,) 05:56:19: S -> verify_access .,,(null) 05:56:19: S -> Name_Repository(.,) 05:56:19: S -> Name_Repository allocate tmp of size 26, len1=24, len2=16 05:56:19: S -> Name_Repository open ./CVS/Repository 05:56:19: S -> Name_Repository opened ./CVS/Repository ok so read a line 05:56:19: S -> Name_Repository closed ./CVS/Repository 05:56:19: S -> Name_Repository read 1 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Name_Repository (stripped the newline) read 2 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Name_Repository isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk )? 05:56:19: S -> Name_Repository not isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk ) 05:56:19: S -> Name_Repository Sanitize_Repository_Name( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: S -> Name_Repository return ( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/junk,a.txt,write,(null),(null)) 05:56:19: S -> verify_perm: verify_owner_dir() 05:56:19: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/junk)=]32+[sizeof(CVS/fileattr.xml)=]4+20=56 05:56:19: S -> fileattr_read() xmalloc fname OK 05:56:19: S -> _fileattr_read() allocated memory for fname - will now sprintf() 05:56:19: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/junk/CVS/fileattr.xml") 05:56:19: S -> fileattr_read() ReadXmlFile() 05:56:19: S -> fileattr_read() close 05:56:19: S -> fileattr_read() xfree fname 05:56:19: S -> fileattr_read() OK 05:56:19: S -> cache_directory_permissions() - cache directory(fileattr,) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo)=]27+[sizeof(CVS/fileattr.xml)=]4+20=51 05:56:19: S -> fileattr_read() xmalloc fname OK 05:56:19: S -> _fileattr_read() allocated memory for fname - will now sprintf() 05:56:19: S -> _fileattr_read() no file "C:/PROGRA~3/MARCHH~1/myrepo/CVS/fileattr.xml", so allocate ofname of 60 bytes. 05:56:19: S -> fileattr_read() xmalloc ofname OK 05:56:19: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo/CVS/fileattr". 05:56:19: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo//.owner". 05:56:19: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo//.perms". 05:56:19: S -> _fileattr_read() CXmlNode("fileattr") 05:56:19: S -> fileattr_read() xfree ofname and fname OK 05:56:19: S -> cache_directory_permissions() - cache directory(fileattr,) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_valid_name(lusera) 05:56:19: S -> Find all the groups the user is in and add them to the list of valid names. 05:56:19: S -> Open the admin group file CVSROOT/group 05:56:19: S -> Finished reading the admin group file CVSROOT/group 05:56:19: S -> Get the groups for windows 05:56:19: S -> Checking local access token for groups 05:56:19: -> tcp_read(1399) using WSAEventSelect 05:56:19: -> tcp_read(1399) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(None) 05:56:19: -> tcp_read(1360) using WSAEventSelect 05:56:19: -> tcp_read(1360) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(Everyone) 05:56:19: -> tcp_read(1317) using WSAEventSelect 05:56:19: -> tcp_read(1317) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(Administrators) 05:56:19: -> tcp_read(1268) using WSAEventSelect 05:56:19: -> tcp_read(1268) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(Users) 05:56:19: -> tcp_read(1228) using WSAEventSelect 05:56:19: -> tcp_read(1228) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(INTERACTIVE) 05:56:19: -> tcp_read(1182) using WSAEventSelect 05:56:19: -> tcp_read(1182) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(CONSOLE LOGON) 05:56:19: -> tcp_read(1134) using WSAEventSelect 05:56:19: -> tcp_read(1134) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(Authenticated Users) 05:56:19: -> tcp_read(1080) using WSAEventSelect 05:56:19: -> tcp_read(1080) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(This Organization) 05:56:19: -> tcp_read(1028) using WSAEventSelect 05:56:19: -> tcp_read(1028) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(This Organization) 05:56:19: -> tcp_read(976) using WSAEventSelect 05:56:19: -> tcp_read(976) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(LOCAL) 05:56:19: -> tcp_read(936) using WSAEventSelect 05:56:19: -> tcp_read(936) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(NTLM Authentication) 05:56:19: -> tcp_read(882) using WSAEventSelect 05:56:19: -> tcp_read(882) immediate timeout previous thread count -1/0 05:56:19: S -> add_valid_group(Medium Mandatory Level) 05:56:19: S -> Got the groups for windows 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_perm() Read directory permission cache OK 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> Match found. user_state = 0, group_state = 0 05:56:19: S -> verify_perm() return 05:56:19: S -> start_recursion: (1) addfile(files_by_dir,".","a.txt") 05:56:19: S -> * At this point we have looped over all named arguments and built 05:56:19: S -> a coupla lists. Now we unroll the lists, setting up and 05:56:19: S -> calling do_recursion. 05:56:19: S -> call walklist() with unroll_files_proc and files_by_dir 05:56:19: S -> unroll_files_proc() begin 05:56:19: S -> * if this dir was also an explicitly named argument, then skip 05:56:19: S -> it. We'll catch it later when we do dirs. 05:56:19: S -> * otherwise, call dorecusion for this list of files. 05:56:19: S -> Name_Repository((null),) 05:56:19: S -> Name_Repository dup tmp is len 05:56:19: S -> Name_Repository dup tmp is now len 05:56:19: S -> Name_Repository open CVS/Repository 05:56:19: S -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: S -> Name_Repository closed CVS/Repository 05:56:19: S -> Name_Repository read 1 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Name_Repository (stripped the newline) read 2 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Name_Repository isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk )? 05:56:19: S -> Name_Repository not isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk ) 05:56:19: S -> Name_Repository Sanitize_Repository_Name( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: S -> Name_Repository return ( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: S -> ParseTag() 05:56:19: S -> ParseTag_Dir((null),,,0,) 05:56:19: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/junk,.,(null),(null),0,(null),0) 05:56:19: S -> open_directory() directory_stack_size increased by one to 1 05:56:19: S -> open_directory, (!remote) try and find a mapping file in this directory . 05:56:19: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/.directory_history,v) 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/.directory_history,v) failed to open file 1 times 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/.directory_history,v) file does not exist, so return 0 05:56:19: S -> rcsbuf_open failed 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/Attic/.directory_history,v) 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/Attic/.directory_history,v) failed to open file 1 times 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/Attic/.directory_history,v) file does not exist, so return 0 05:56:19: S -> rcsbuf_open failed 05:56:19: S -> No mapping file in this directory. 05:56:19: S -> open_directory copy the tag and date. 05:56:19: S -> open_directory copied the tag and date. 05:56:19: S -> Look for rename script file CVS/Rename 05:56:19: S -> Rename script file does not exist 05:56:19: S -> directory opened 05:56:19: S -> do_recursion: begin 05:56:19: S -> do_recursion: Check the value in CVSADM_ROOT 05:56:19: S -> do_recursion: Fill in repository with the current repository 05:56:19: S -> do_recursion: update_dir= 05:56:19: S -> Name_Repository((null),) 05:56:19: S -> Name_Repository dup tmp is len 05:56:19: S -> Name_Repository dup tmp is now len 05:56:19: S -> Name_Repository open CVS/Repository 05:56:19: S -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: S -> Name_Repository closed CVS/Repository 05:56:19: S -> Name_Repository read 1 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Name_Repository (stripped the newline) read 2 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Name_Repository isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk )? 05:56:19: S -> Name_Repository not isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk ) 05:56:19: S -> Name_Repository Sanitize_Repository_Name( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: S -> Name_Repository return ( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: S -> do_recursion: update_dir= repository=C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> (repository && (server_active || !current_parsed_root->isremote)) 05:56:19: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> lookup_module2() 05:56:19: S -> lookup_module2(junk) after rename? 05:56:19: S -> lookup_module2() call _lookup_module2() 05:56:19: S -> Loading modules2 from C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/modules2 05:56:19: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 05:56:19: S -> lookup_module2(junk,1) 05:56:19: S -> _lookup_module2 !p lookup_repository_module(junk) 05:56:19: S -> lookup_repository_module(junk) 05:56:19: S -> lookup_module2() calls to lookup_repository_module() returned nothing 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 !mod return 0 left,right(,junk) 05:56:19: S -> lookup_module2() call _lookup_module2 returned 0 05:56:19: S -> lookup_module2() no recursion permitted so give up now 05:56:19: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 05:56:19: S -> lookup_module2() return ret=0 renamed=0 05:56:19: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/junk" 05:56:19: S -> Repository = C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> Mapped repository = C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> (repository && (server_active || !current_parsed_root->isremote)) 05:56:19: S -> ParseTag() 05:56:19: S -> ParseTag_Dir((null),,,0,) 05:56:19: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/junk,,(null) 05:56:19: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/junk,(null),write,(null),(null)) 05:56:19: S -> verify_perm: verify_owner_dir() 05:56:19: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_valid_name(lusera) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_perm() Read directory permission cache OK 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> Match found. user_state = 0, group_state = 0 05:56:19: S -> verify_perm() return 05:56:19: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> * The filesdoneproc needs to be called for each directory where files 05:56:19: S -> * processed, or each directory that is processed by a call where no 05:56:19: S -> * directories were passed in. In fact, the only time we don't want to 05:56:19: S -> * call back the filesdoneproc is when we are processing directories that 05:56:19: S -> * were passed in on the command line (or in the special case of `.' when 05:56:19: S -> * we were called with no args 05:56:19: S -> do_recursion: dirlist == NULL && filelist == NULL 05:56:19: S -> do_recursion: dodoneproc=1 05:56:19: S -> * If filelist or dirlist is already set, we don't look again. Otherwise, 05:56:19: S -> * find the files and directories 05:56:19: S -> do_recursion: something was passed on the command line 05:56:19: S -> do_recursion: we will process files, so pre-parse entries 05:56:19: S -> Entries_Open() 05:56:19: S -> Entries_Open ParseTag() 05:56:19: S -> ParseTag() 05:56:19: S -> ParseTag_Dir((null),,,14352820,) 05:56:19: S -> Entries_Open aflag==0 && dirtag==0 && dirdate==0 05:56:19: S -> Entries_Open CVS_FOPEN("CVS/Entries","r") 05:56:19: S -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode 05:56:19: S -> Entnode_Create() 05:56:19: S -> Entnode_Create() finished 05:56:19: S -> AddEntryNode() 05:56:19: S -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: S -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: S -> AddEntryNode() put the node into the list 05:56:19: S -> AddEntryNode() finished 05:56:19: S -> Entries_Open fclose() CVSADM_ENT 05:56:19: S -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r") 05:56:19: S -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle 05:56:19: S -> Entries_Open fclose() CVSADM_ENTEXT 05:56:19: S -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r") 05:56:19: S -> Entries_Open (! sawdir) - so xmalloc() 05:56:19: S -> Entries_Open entrieslist is not NULL, add some sticky data... 05:56:19: S -> Entries_Open clean up and return 05:56:19: S -> Entries_Open done 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: process the files (if any) 05:56:19: S -> do_recursion: read lock it if necessary 05:56:19: S -> * For the server, we handle notifications in a completely different 05:56:19: S -> place (server_notify). For local, we can't do them here--we don't 05:56:19: S -> have writelocks in place, and there is no way to get writelocks here 05:56:19: S -> do_recursion: process the files by calling walklist() 05:56:19: S -> do_file_proc: process the files "a.txt" 05:56:19: S -> (server_active || !current_parsed_root->isremote) 05:56:19: S -> map_filename(C:/PROGRA~3/MARCHH~1/myrepo/junk,a.txt) 05:56:19: S -> map_filename - call map_repository(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt) 05:56:19: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt) 05:56:19: S -> lookup_module2() 05:56:19: S -> lookup_module2(junk/a.txt) after rename? 05:56:19: S -> lookup_module2() call _lookup_module2() 05:56:19: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 05:56:19: S -> lookup_module2(junk/a.txt,2) 05:56:19: S -> _lookup_module2 p!=NULL lookup_repository_module(junk) 05:56:19: S -> lookup_repository_module(junk) 05:56:19: S -> lookup_module2() calls to lookup_repository_module() returned nothing 05:56:19: S -> _lookup_module2 !mod return 0 left,right(,junk/a.txt) 05:56:19: S -> lookup_module2() call _lookup_module2 returned 0 05:56:19: S -> lookup_module2() no recursion permitted so give up now 05:56:19: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 05:56:19: S -> lookup_module2() return ret=0 renamed=0 05:56:19: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt" 05:56:19: S -> map_filename - map_repository() returns C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt 05:56:19: S -> do_file_proc: mapped name of file "a.txt" 05:56:19: S -> do_file_proc: frfile->frame->dosrcs && mapped_file_repository 05:56:19: S -> RCS_parse(a.txt,C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v) 05:56:19: S -> normalize_path(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v) 05:56:19: S -> ...returns C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v 05:56:19: S -> Lock server command sent: Lock Read Full|C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v 05:56:19: -> tcp_read(2808) using WSAEventSelect 05:56:19: -> tcp_read(2808) immediate timeout previous thread count -1/0 05:56:19: S -> Lock server command received: 000 OK Lock granted (251) 05:56:19: S -> do_lock_server: [05:56:19] obtained lock 251 in a.txt,v 05:56:19: S -> do_lock_file: lock server returns 251 05:56:19: S -> rcsbuf_open(/myrepo/junk/a.txt,v) -- obtained a new lock 05:56:19: S -> rcsbuf_fill(windows,BigMemory=0) 05:56:19: S -> rcsbuf_fill(0): expanded to 8192 (was: 0, req: 5120, inc: 5120, RCSBUF_BUFSIZE: 5120). 05:56:19: S -> do_file_proc: call findnode_fn() 05:56:19: S -> do_file_proc: got a result from findnode_fn() 05:56:19: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/junk,,(null) 05:56:19: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/junk,a.txt,write,(null),(null)) 05:56:19: S -> verify_perm: verify_owner_dir() 05:56:19: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_valid_name(lusera) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_perm() Read directory permission cache OK 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> Match found. user_state = 0, group_state = 0 05:56:19: S -> verify_perm() return 05:56:19: S -> do_file_proc: verify_access() returned ok 05:56:19: S -> Call Classify_File from classify_file_internal / commit 05:56:19: S -> Classify_File (a.txt) 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> Version_TS((null),(null),1) 05:56:19: S -> Version_TS -- findnode_fn() not force_case_match 05:56:19: S -> Version_TS -- findnode() found something that wasn't a subdir. 05:56:19: S -> Version_TS - call RCS_getversion("C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v",(null),(null),1,0) 05:56:19: S -> *RCS_head(HEAD) 05:56:19: S -> wnt_stat(a.txt) 05:56:19: S -> _statcore(00000000,a.txt) 05:56:19: S -> Trying GetFileAttributesEx.... 05:56:19: S -> File attributes = 00000020 05:56:19: S -> - read/write file 05:56:19: S -> GetUnixFileModeNtEA(a,00000288) returns 0600 05:56:19: S -> _statcore(a.txt) returns S_IWRITE 05:56:19: S -> Classify_File vn_rcs=1.4 05:56:19: S -> Classify_File options=kv 05:56:19: S -> Classify_File A normal entry, TS_Rcs is valid 05:56:19: S -> Classify_File The RCS file is the same version as the user file 05:56:19: S -> RCS_cmp_file() called in No_Difference() 05:56:19: S -> RCS_checkin(2) calls RCS_checkout(RCSCHECKOUTPROC=cmp_file_buffer) 05:56:19: S -> RCS_checkout (/myrepo/junk/a.txt,v, 1.4, , (function)) 05:56:19: S -> RCS_checkout() calls rcsbuf_setpos_to_delta_base() 05:56:19: S -> RCS_checkout() - We want the head revision. Try to read it directly. 05:56:19: S -> RCS_checkout() calls rcsbuf_valpolish() 05:56:19: S -> RCS_checkout() -- Handle zip expansion of head 05:56:19: S -> RCS_checkout options = "kv" 05:56:19: S -> got rcs_mode = 0600 from rcs-permissions-tag 05:56:19: S -> RCS_checkout() calls expand_keywords() 05:56:19: S -> keywords trigger got a real username 05:56:19: S -> RCS_checkout() returned from call to expand_keywords() 05:56:19: S -> RCS_checkout() rcsbuf_valfree 05:56:19: S -> RCS_checkout() rcsbuf_valfree() done 05:56:19: S -> RCS_checkout() free_rev 05:56:19: S -> RCS_checkout() call pfn() 05:56:19: S -> checkout pfn completed 05:56:19: S -> RCS_checkout() now if (free_value)... 05:56:19: S -> RCS_checkout() now xfree(zbuf); 05:56:19: S -> RCS_checkout() calls modify_mode() 05:56:19: S -> RCS_checkout -> return ok 05:56:19: S -> wnt_stat(a.txt) 05:56:19: S -> _statcore(00000000,a.txt) 05:56:19: S -> Trying GetFileAttributesEx.... 05:56:19: S -> File attributes = 00000020 05:56:19: S -> - read/write file 05:56:19: S -> GetUnixFileModeNtEA(a,00000288) returns 0600 05:56:19: S -> _statcore(a.txt) returns S_IWRITE 05:56:19: S -> server_register(a.txt, 1.4, Fri Jul 27 12:56:01 2012, , , , , , , , ) 05:56:19: S -> Register(a.txt, 1.4, Fri Jul 27 12:56:01 2012, , , , , , ) 05:56:19: S -> Entnode_Create() 05:56:19: S -> Entnode_Create() finished 05:56:19: S -> AddEntryNode() 05:56:19: S -> AddEntryNode() was it already there? -- apparently yes 05:56:19: S -> AddEntryNode() take it out 05:56:19: S -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: S -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: S -> AddEntryNode() put the node into the list 05:56:19: S -> AddEntryNode() finished 05:56:19: S -> Register(): finished 05:56:19: S -> Classify_File else -> note that No_Difference will Register the file already for us, using the new tag/date. This is the desired behaviour 05:56:19: S -> Classify_File free up the vers struct, or just return it 05:56:19: S -> Classify_File return the status of the file 05:56:19: S -> Classify_File returned 9 05:56:19: S -> check_fileproc. classify_file_internal returned 9 05:56:19: S -> check_fileproc. Bug id in: (0), edit bug id: (0/+), server: active 05:56:19: S -> do_bug_msg bugmap=0 bug=0. 05:56:19: S -> do_bug_msg is now "". 05:56:19: S -> Begin Implement -b (do_bug_final) 05:56:19: S -> only_bug==0 05:56:19: S -> bugidsz.size()==0 05:56:19: S -> vers->edit_bugid REAL 05:56:19: S -> Since there is no bugidsz then there is no bugid_in_res (ie: NOT) 05:56:19: S -> End Implement -b (do_bug_final) 05:56:19: S -> All finished the bug number regular expression stuff for commit, continue with status stuff...! 05:56:19: S -> status == T_UPTODATE 05:56:19: S -> server_register(a.txt, 1.4, Fri Jul 27 12:56:01 2012, kv, , , , , , , ) 05:56:19: S -> Register(a.txt, 1.4, Fri Jul 27 12:56:01 2012, kv, , , , , ) 05:56:19: S -> Entnode_Create() 05:56:19: S -> Entnode_Create() finished 05:56:19: S -> AddEntryNode() 05:56:19: S -> AddEntryNode() was it already there? -- apparently yes 05:56:19: S -> AddEntryNode() take it out 05:56:19: S -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> tcp_read(586) using WSAEventSelect 05:56:19: -> tcp_read(586) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> tcp_read(509) using WSAEventSelect 05:56:19: -> tcp_read(509) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() put the node into the list 05:56:19: -> tcp_read(449) using WSAEventSelect 05:56:19: -> tcp_read(449) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() finished 05:56:19: -> tcp_read(408) using WSAEventSelect 05:56:19: -> tcp_read(408) immediate timeout previous thread count -1/0 05:56:19: S -> Register(): !noexec 05:56:19: -> tcp_read(371) using WSAEventSelect 05:56:19: -> tcp_read(371) immediate timeout previous thread count -1/0 05:56:19: S -> Register(): finished 05:56:19: -> tcp_read(333) using WSAEventSelect 05:56:19: -> tcp_read(333) immediate timeout previous thread count -1/0 05:56:19: S -> check_fileproc() server_active so server_updated () 05:56:19: -> tcp_read(264) using WSAEventSelect 05:56:19: -> tcp_read(264) immediate timeout previous thread count -1/0 05:56:19: S -> wnt_stat(a.txt) 05:56:19: -> tcp_read(231) using WSAEventSelect 05:56:19: -> tcp_read(231) immediate timeout previous thread count -1/0 05:56:19: S -> _statcore(00000000,a.txt) 05:56:19: -> tcp_read(188) using WSAEventSelect 05:56:19: -> tcp_read(188) immediate timeout previous thread count -1/0 05:56:19: S -> Trying GetFileAttributesEx.... 05:56:19: -> tcp_read(140) using WSAEventSelect 05:56:19: -> tcp_read(140) immediate timeout previous thread count -1/0 05:56:19: S -> File attributes = 00000020 05:56:19: -> tcp_read(96) using WSAEventSelect 05:56:19: -> tcp_read(96) immediate timeout previous thread count -1/0 05:56:19: S -> - read/write file 05:56:19: -> tcp_read(60) using WSAEventSelect 05:56:19: -> tcp_read(60) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> GetUnixFileModeNtEA(a,00000288) returns 0600 05:56:19: -> tcp_read(5118) using WSAEventSelect 05:56:19: -> tcp_read(5118) immediate timeout previous thread count -1/0 05:56:19: S -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> tcp_read(5066) using WSAEventSelect 05:56:19: -> tcp_read(5066) immediate timeout previous thread count -1/0 05:56:19: -> handle_update_existing(update_entries) 05:56:19: -> call_in_directory /myrepo/junk/a.txt,./ 05:56:19: -> call_in_directory -- handle "." /myrepo/junk/a.txt,./ 05:56:19: -> call_in_directory dir_name(.) created from pathname(./) no prune 05:56:19: -> call_in_directory compare last_dir_name((null)) dir_name(.) 05:56:19: -> call_in_directory dir_name!=last_dir_name, or no last_dir_name 05:56:19: -> call_in_directory chdir(toplevel_wd=C:\Users\admin\Documents\junk) 05:56:19: -> call_in_directory chdir(dir_name=.) 05:56:19: -> Entries_Open() 05:56:19: -> Entries_Open ParseTag() 05:56:19: -> ParseTag() 05:56:19: -> ParseTag_Dir((null),,,2,) 05:56:19: -> Entries_Open aflag==0 && dirtag==0 && dirdate==0 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Entries_Open fclose() CVSADM_ENT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r") 05:56:19: -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle 05:56:19: -> Entries_Open fclose() CVSADM_ENTEXT 05:56:19: -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r") 05:56:19: -> Entries_Open clean up and return 05:56:19: -> Entries_Open done 05:56:19: -> call_in_directory - call the function with (./a.txt,a.txt) 05:56:19: -> update_entries(./a.txt,a.txt,insensitive=1,overwrite=0) 05:56:19: -> mkdir CVS/Base is not needed 05:56:19: -> *********************************************************** 05:56:19: -> Antivirus update entries in progress... 05:56:19: -> update_entries(temp=CVS/Base/_new_a.txt) 05:56:19: -> update_entries(!encode open text) 05:56:19: -> update_entries() !UPDATE_ENTRIES_RCS_DIFF so work with the file 05:56:19: -> update_entries() !UPDATE_ENTRIES_RCS_DIFF call read_from_server(8) 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: -> update_entries() call to read_from_server() done. no md5. size=8 05:56:19: -> update_entries() write(). 05:56:19: -> update_entries() write() done ok. 05:56:19: -> update_entries() should now have a file locally 05:56:19: -> update_entries() UPDATE_ENTRIES_UPDATE so rename(CVS/Base/_new_a.txt,a.txt) 05:56:19: -> rename(CVS/Base/_new_a.txt,a.txt) 05:56:19: -> wnt_rename(CVS/Base/_new_a.txt,a.txt) 05:56:19: -> rename file CVS/Base/_new_a.txt to a.txt ... 05:56:19: -> update_entries() change_mode(a.txt,mode="u=rw,g=,o=") 05:56:19: -> change_mode (a.txt,u=rw,g=,o=,1) 05:56:19: -> wnt_chmod(a.txt,0600) 05:56:19: -> SetUnixFileModeNtEA(a,0600) 05:56:19: -> wnt_stat(a.txt) 05:56:19: -> _statcore(00000000,a.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(a,00000268) returns 0600 05:56:19: -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> wnt_stat(a.txt) 05:56:19: -> _statcore(00000000,a.txt) 05:56:19: -> Trying GetFileAttributesEx.... 05:56:19: -> File attributes = 00000020 05:56:19: -> - read/write file 05:56:19: -> GetUnixFileModeNtEA(a,00000268) returns 0600 05:56:19: -> _statcore(a.txt) returns S_IWRITE 05:56:19: -> update_entries() mark committed file up to date 05:56:19: -> mark_up_to_date() mark "a.txt" up to date 05:56:19: -> unlink_file(CVS/Base/a.txt) 05:56:19: -> unlink_file(CVS/Base/a.txt.gz) 05:56:19: -> mark_up_to_date() done 05:56:19: -> try_copy_back_and_forth(a.txt,antivirus=on=2) 05:56:19: -> Register(a.txt, 1.4, Fri Jul 27 12:56:19 2012, kv, , , , , ) 05:56:19: -> Entnode_Create() 05:56:19: -> Entnode_Create() finished 05:56:19: -> AddEntryNode() 05:56:19: -> AddEntryNode() was it already there? -- apparently yes 05:56:19: -> AddEntryNode() take it out 05:56:19: -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> AddEntryNode() put the node into the list 05:56:19: -> AddEntryNode() finished 05:56:19: -> Register(): !noexec 05:56:19: -> Register(): finished 05:56:19: -> update_entries() done. 05:56:19: -> handle_update_existing(update_entries) done 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> case T_UPTODATE 05:56:19: -> tcp_read(5087) using WSAEventSelect 05:56:19: -> tcp_read(5087) immediate timeout previous thread count -1/0 05:56:19: S -> finished check_fileproc - free vers_ts 05:56:19: -> tcp_read(5031) using WSAEventSelect 05:56:19: -> tcp_read(5031) immediate timeout previous thread count -1/0 05:56:19: S -> finished check_fileproc - return 05:56:19: -> tcp_read(4981) using WSAEventSelect 05:56:19: -> tcp_read(4981) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: finished fileproc() 05:56:19: -> tcp_read(4930) using WSAEventSelect 05:56:19: -> tcp_read(4930) immediate timeout previous thread count -1/0 05:56:19: S -> Lock server command sent: Unlock 251 05:56:19: -> tcp_read(4873) using WSAEventSelect 05:56:19: -> tcp_read(4873) immediate timeout previous thread count -1/0 05:56:19: S -> Lock server command received: 000 OK Unlocked 05:56:19: -> tcp_read(4807) using WSAEventSelect 05:56:19: -> tcp_read(4807) immediate timeout previous thread count -1/0 05:56:19: S -> do_unlock_file: (lock 251) lock server returns 0 05:56:19: -> tcp_read(4741) using WSAEventSelect 05:56:19: -> tcp_read(4741) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: return 05:56:19: -> tcp_read(4703) using WSAEventSelect 05:56:19: -> tcp_read(4703) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: unlock it 05:56:19: -> tcp_read(4661) using WSAEventSelect 05:56:19: -> tcp_read(4661) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: clean up by calling dellist(filelist) 05:56:19: -> tcp_read(4592) using WSAEventSelect 05:56:19: -> tcp_read(4592) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: call-back files done proc (if any) 05:56:19: -> tcp_read(4525) using WSAEventSelect 05:56:19: -> tcp_read(4525) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: yes: call-back files done proc (if any) 05:56:19: -> tcp_read(4453) using WSAEventSelect 05:56:19: -> tcp_read(4453) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: at skip_directory 05:56:19: -> tcp_read(4403) using WSAEventSelect 05:56:19: -> tcp_read(4403) immediate timeout previous thread count -1/0 05:56:19: S -> (repository && (server_active || !current_parsed_root->isremote)) 05:56:19: -> tcp_read(4320) using WSAEventSelect 05:56:19: -> tcp_read(4320) immediate timeout previous thread count -1/0 05:56:19: S -> fileattr_write() 05:56:19: -> tcp_read(4286) using WSAEventSelect 05:56:19: -> tcp_read(4286) immediate timeout previous thread count -1/0 05:56:19: S -> fileattr_free() 05:56:19: -> tcp_read(4253) using WSAEventSelect 05:56:19: -> tcp_read(4253) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: process the directories (if necessary) 05:56:19: -> tcp_read(4182) using WSAEventSelect 05:56:19: -> tcp_read(4182) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: cannot process the directories (dirlist == NULL) 05:56:19: -> tcp_read(4101) using WSAEventSelect 05:56:19: -> tcp_read(4101) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Close - isfile ("CVS/Entries.Log") 05:56:19: -> tcp_read(4041) using WSAEventSelect 05:56:19: -> tcp_read(4041) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Close - write_entries (list) 05:56:19: -> tcp_read(3987) using WSAEventSelect 05:56:19: -> tcp_read(3987) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() CVSADM_ENTBAK entfilename="CVS/Entries.Log" 05:56:19: -> tcp_read(3910) using WSAEventSelect 05:56:19: -> tcp_read(3910) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() CVSADM_ENTBAK ok 05:56:19: -> tcp_read(3860) using WSAEventSelect 05:56:19: -> tcp_read(3860) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() CVSADM_ENTEXBAK ok 05:56:19: -> tcp_read(3808) using WSAEventSelect 05:56:19: -> tcp_read(3808) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() !sawdir 05:56:19: -> tcp_read(3767) using WSAEventSelect 05:56:19: -> tcp_read(3767) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() now, atomically (on systems that support it) rename it 05:56:19: -> tcp_read(3678) using WSAEventSelect 05:56:19: -> tcp_read(3678) immediate timeout previous thread count -1/0 05:56:19: S -> rename(CVS/Entries.Backup,CVS/Entries) 05:56:19: -> tcp_read(3622) using WSAEventSelect 05:56:19: -> tcp_read(3622) immediate timeout previous thread count -1/0 05:56:19: S -> wnt_rename(CVS/Entries.Backup,CVS/Entries) 05:56:19: -> tcp_read(3562) using WSAEventSelect 05:56:19: -> tcp_read(3562) immediate timeout previous thread count -1/0 05:56:19: S -> rename file CVS/Entries.Backup to CVS/Entries ... 05:56:19: -> tcp_read(3495) using WSAEventSelect 05:56:19: -> tcp_read(3495) immediate timeout previous thread count -1/0 05:56:19: S -> rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra) 05:56:19: -> tcp_read(3427) using WSAEventSelect 05:56:19: -> tcp_read(3427) immediate timeout previous thread count -1/0 05:56:19: S -> wnt_rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra) 05:56:19: -> tcp_read(3355) using WSAEventSelect 05:56:19: -> tcp_read(3355) immediate timeout previous thread count -1/0 05:56:19: S -> rename file CVS/Entries.Extra.Backup to CVS/Entries.Extra ... 05:56:19: -> tcp_read(3276) using WSAEventSelect 05:56:19: -> tcp_read(3276) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() now, remove the log file 05:56:19: -> tcp_read(3217) using WSAEventSelect 05:56:19: -> tcp_read(3217) immediate timeout previous thread count -1/0 05:56:19: S -> unlink_file(CVS/Entries.Log) 05:56:19: -> tcp_read(3171) using WSAEventSelect 05:56:19: -> tcp_read(3171) immediate timeout previous thread count -1/0 05:56:19: S -> unlink_file(CVS/Entries.Extra.Log) 05:56:19: -> tcp_read(3119) using WSAEventSelect 05:56:19: -> tcp_read(3119) immediate timeout previous thread count -1/0 05:56:19: S -> write_entries() done 05:56:19: -> tcp_read(3081) using WSAEventSelect 05:56:19: -> tcp_read(3081) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Close - dellist (list) 05:56:19: -> tcp_read(3033) using WSAEventSelect 05:56:19: -> tcp_read(3033) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: free the saved copy of the pointer if necessary 05:56:19: -> tcp_read(2953) using WSAEventSelect 05:56:19: -> tcp_read(2953) immediate timeout previous thread count -1/0 05:56:19: S -> more efficient - close directory in unroll_files_proc? 05:56:19: -> tcp_read(2881) using WSAEventSelect 05:56:19: -> tcp_read(2881) immediate timeout previous thread count -1/0 05:56:19: S -> close_directory() 05:56:19: -> tcp_read(2846) using WSAEventSelect 05:56:19: -> tcp_read(2846) immediate timeout previous thread count -1/0 05:56:19: S -> close_directory() directory_stack_size decreased by one to 0 05:56:19: -> tcp_read(2767) using WSAEventSelect 05:56:19: -> tcp_read(2767) immediate timeout previous thread count -1/0 05:56:19: S -> unroll_files_proc() end 05:56:19: -> tcp_read(2726) using WSAEventSelect 05:56:19: -> tcp_read(2726) immediate timeout previous thread count -1/0 05:56:19: S -> walklist call with unroll_files_proc done. err=0 05:56:19: -> tcp_read(2658) using WSAEventSelect 05:56:19: -> tcp_read(2658) immediate timeout previous thread count -1/0 05:56:19: S -> freenode() free the badbad DIRS 05:56:19: -> tcp_read(2609) using WSAEventSelect 05:56:19: -> tcp_read(2609) immediate timeout previous thread count -1/0 05:56:19: S -> dellist after unroll_files_proc done. 05:56:19: -> tcp_read(2552) using WSAEventSelect 05:56:19: -> tcp_read(2552) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: cannot do_recursion on the dirlist. 05:56:19: -> tcp_read(2482) using WSAEventSelect 05:56:19: -> tcp_read(2482) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: Free the data which expand_wild allocated. 05:56:19: -> tcp_read(2405) using WSAEventSelect 05:56:19: -> tcp_read(2405) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: Free the data - update_dir 05:56:19: -> tcp_read(2344) using WSAEventSelect 05:56:19: -> tcp_read(2344) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: Free the data - update_repos 05:56:19: -> tcp_read(2281) using WSAEventSelect 05:56:19: -> tcp_read(2281) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: (args_to_send_when_finished == NULL) 05:56:19: -> tcp_read(2210) using WSAEventSelect 05:56:19: -> tcp_read(2210) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: return 0. 05:56:19: -> tcp_read(2166) using WSAEventSelect 05:56:19: -> tcp_read(2166) immediate timeout previous thread count -1/0 05:56:19: S -> Commit start_recursion "Run the recursion processor to commit the files" argc=1 05:56:19: -> tcp_read(2069) using WSAEventSelect 05:56:19: -> tcp_read(2069) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion((null),(null)) 05:56:19: -> tcp_read(2021) using WSAEventSelect 05:56:19: -> tcp_read(2021) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion() dirleaveproc is OK 05:56:19: -> tcp_read(1967) using WSAEventSelect 05:56:19: -> tcp_read(1967) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion() update_dir set to "" 05:56:19: -> tcp_read(1911) using WSAEventSelect 05:56:19: -> tcp_read(1911) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion - argument count (argc) == 1 05:56:19: -> tcp_read(1849) using WSAEventSelect 05:56:19: -> tcp_read(1849) immediate timeout previous thread count -1/0 05:56:19: S -> * There were arguments, so we have to handle them by hand. To do 05:56:19: -> tcp_read(1767) using WSAEventSelect 05:56:19: -> tcp_read(1767) immediate timeout previous thread count -1/0 05:56:19: S -> * that, we set up the filelist and dirlist with the arguments and 05:56:19: -> tcp_read(1684) using WSAEventSelect 05:56:19: -> tcp_read(1684) immediate timeout previous thread count -1/0 05:56:19: S -> * call do_recursion. do_recursion recognizes the fact that the 05:56:19: -> tcp_read(1603) using WSAEventSelect 05:56:19: -> tcp_read(1603) immediate timeout previous thread count -1/0 05:56:19: S -> * lists are non-null when it starts and doesn't update them. 05:56:19: -> tcp_read(1525) using WSAEventSelect 05:56:19: -> tcp_read(1525) immediate timeout previous thread count -1/0 05:56:19: S -> 05:56:19: -> tcp_read(1507) using WSAEventSelect 05:56:19: -> tcp_read(1507) immediate timeout previous thread count -1/0 05:56:19: S -> * explicitly named directories are stored in dirlist. 05:56:19: -> tcp_read(1436) using WSAEventSelect 05:56:19: -> tcp_read(1436) immediate timeout previous thread count -1/0 05:56:19: S -> * explicitly named files are stored in filelist. 05:56:19: -> tcp_read(1370) using WSAEventSelect 05:56:19: -> tcp_read(1370) immediate timeout previous thread count -1/0 05:56:19: S -> * other possibility is named entities whicha are not currently in 05:56:19: -> tcp_read(1287) using WSAEventSelect 05:56:19: -> tcp_read(1287) immediate timeout previous thread count -1/0 05:56:19: S -> * the working directory. 05:56:19: -> tcp_read(1245) using WSAEventSelect 05:56:19: -> tcp_read(1245) immediate timeout previous thread count -1/0 05:56:19: S -> * if this argument is a directory, then add it to the list of directories. 05:56:19: -> tcp_read(1153) using WSAEventSelect 05:56:19: -> tcp_read(1153) immediate timeout previous thread count -1/0 05:56:19: S -> * otherwise, split argument into directory and component names. 05:56:19: -> tcp_read(1072) using WSAEventSelect 05:56:19: -> tcp_read(1072) immediate timeout previous thread count -1/0 05:56:19: S -> * Now break out argv[i] into directory part (DIR) and file part (COMP). 05:56:19: -> tcp_read(983) using WSAEventSelect 05:56:19: -> tcp_read(983) immediate timeout previous thread count -1/0 05:56:19: S -> DIR and COMP will each point to a newly malloc'd string. 05:56:19: -> tcp_read(906) using WSAEventSelect 05:56:19: -> tcp_read(906) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion() Name_Repository(tmp_update_dir".") 05:56:19: -> tcp_read(836) using WSAEventSelect 05:56:19: -> tcp_read(836) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository(.,.) 05:56:19: -> tcp_read(798) using WSAEventSelect 05:56:19: -> tcp_read(798) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository allocate tmp of size 26, len1=24, len2=16 05:56:19: -> tcp_read(723) using WSAEventSelect 05:56:19: -> tcp_read(723) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository open ./CVS/Repository 05:56:19: -> tcp_read(668) using WSAEventSelect 05:56:19: -> tcp_read(668) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository opened ./CVS/Repository ok so read a line 05:56:19: -> tcp_read(593) using WSAEventSelect 05:56:19: -> tcp_read(593) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository closed ./CVS/Repository 05:56:19: -> tcp_read(536) using WSAEventSelect 05:56:19: -> tcp_read(536) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository read 1 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(460) using WSAEventSelect 05:56:19: -> tcp_read(460) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository (stripped the newline) read 2 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(364) using WSAEventSelect 05:56:19: -> tcp_read(364) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk )? 05:56:19: -> tcp_read(283) using WSAEventSelect 05:56:19: -> tcp_read(283) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository not isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk ) 05:56:19: -> tcp_read(199) using WSAEventSelect 05:56:19: -> tcp_read(199) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository Sanitize_Repository_Name( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: -> tcp_read(104) using WSAEventSelect 05:56:19: -> tcp_read(104) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository return ( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: -> tcp_read(26) using WSAEventSelect 05:56:19: -> tcp_read(26) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> start_recursion: (2) addfile(files_by_dir,".","a.txt") 05:56:19: -> tcp_read(5074) using WSAEventSelect 05:56:19: -> tcp_read(5074) immediate timeout previous thread count -1/0 05:56:19: S -> * At this point we have looped over all named arguments and built 05:56:19: -> tcp_read(4991) using WSAEventSelect 05:56:19: -> tcp_read(4991) immediate timeout previous thread count -1/0 05:56:19: S -> a coupla lists. Now we unroll the lists, setting up and 05:56:19: -> tcp_read(4915) using WSAEventSelect 05:56:19: -> tcp_read(4915) immediate timeout previous thread count -1/0 05:56:19: S -> calling do_recursion. 05:56:19: -> tcp_read(4874) using WSAEventSelect 05:56:19: -> tcp_read(4874) immediate timeout previous thread count -1/0 05:56:19: S -> call walklist() with unroll_files_proc and files_by_dir 05:56:19: -> tcp_read(4801) using WSAEventSelect 05:56:19: -> tcp_read(4801) immediate timeout previous thread count -1/0 05:56:19: S -> unroll_files_proc() begin 05:56:19: -> tcp_read(4758) using WSAEventSelect 05:56:19: -> tcp_read(4758) immediate timeout previous thread count -1/0 05:56:19: S -> * if this dir was also an explicitly named argument, then skip 05:56:19: -> tcp_read(4678) using WSAEventSelect 05:56:19: -> tcp_read(4678) immediate timeout previous thread count -1/0 05:56:19: S -> it. We'll catch it later when we do dirs. 05:56:19: -> tcp_read(4616) using WSAEventSelect 05:56:19: -> tcp_read(4616) immediate timeout previous thread count -1/0 05:56:19: S -> * otherwise, call dorecusion for this list of files. 05:56:19: -> tcp_read(4546) using WSAEventSelect 05:56:19: -> tcp_read(4546) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository((null),) 05:56:19: -> tcp_read(4504) using WSAEventSelect 05:56:19: -> tcp_read(4504) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository dup tmp is len 05:56:19: -> tcp_read(4456) using WSAEventSelect 05:56:19: -> tcp_read(4456) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository dup tmp is now len 05:56:19: -> tcp_read(4404) using WSAEventSelect 05:56:19: -> tcp_read(4404) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository open CVS/Repository 05:56:19: -> tcp_read(4351) using WSAEventSelect 05:56:19: -> tcp_read(4351) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: -> tcp_read(4278) using WSAEventSelect 05:56:19: -> tcp_read(4278) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository closed CVS/Repository 05:56:19: -> tcp_read(4223) using WSAEventSelect 05:56:19: -> tcp_read(4223) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository read 1 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(4147) using WSAEventSelect 05:56:19: -> tcp_read(4147) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository (stripped the newline) read 2 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(4051) using WSAEventSelect 05:56:19: -> tcp_read(4051) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk )? 05:56:19: -> tcp_read(3970) using WSAEventSelect 05:56:19: -> tcp_read(3970) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository not isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk ) 05:56:19: -> tcp_read(3886) using WSAEventSelect 05:56:19: -> tcp_read(3886) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository Sanitize_Repository_Name( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: -> tcp_read(3791) using WSAEventSelect 05:56:19: -> tcp_read(3791) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository return ( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: -> tcp_read(3713) using WSAEventSelect 05:56:19: -> tcp_read(3713) immediate timeout previous thread count -1/0 05:56:19: S -> ParseTag() 05:56:19: -> tcp_read(3685) using WSAEventSelect 05:56:19: -> tcp_read(3685) immediate timeout previous thread count -1/0 05:56:19: S -> ParseTag_Dir((null),,,0,) 05:56:19: -> tcp_read(3609) using WSAEventSelect 05:56:19: -> tcp_read(3609) immediate timeout previous thread count -1/0 05:56:19: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/junk,.,(null),(null),0,(null),0) 05:56:19: -> tcp_read(3516) using WSAEventSelect 05:56:19: -> tcp_read(3516) immediate timeout previous thread count -1/0 05:56:19: S -> open_directory() directory_stack_size increased by one to 1 05:56:19: -> tcp_read(3439) using WSAEventSelect 05:56:19: -> tcp_read(3439) immediate timeout previous thread count -1/0 05:56:19: S -> open_directory, (!remote) try and find a mapping file in this directory . 05:56:19: -> tcp_read(3348) using WSAEventSelect 05:56:19: -> tcp_read(3348) immediate timeout previous thread count -1/0 05:56:19: S -> The tests about to be executed are pointless since we know the result already (No mapping File in this directory.) 05:56:19: -> tcp_read(3216) using WSAEventSelect 05:56:19: -> tcp_read(3216) immediate timeout previous thread count -1/0 05:56:19: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(3136) using WSAEventSelect 05:56:19: -> tcp_read(3136) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/.directory_history,v) 05:56:19: -> tcp_read(3052) using WSAEventSelect 05:56:19: -> tcp_read(3052) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/.directory_history,v) failed to open file 1 times 05:56:19: -> tcp_read(2940) using WSAEventSelect 05:56:19: -> tcp_read(2940) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/.directory_history,v) file does not exist, so return 0 05:56:19: -> tcp_read(2823) using WSAEventSelect 05:56:19: -> tcp_read(2823) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open failed 05:56:19: -> tcp_read(2787) using WSAEventSelect 05:56:19: -> tcp_read(2787) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/Attic/.directory_history,v) 05:56:19: -> tcp_read(2697) using WSAEventSelect 05:56:19: -> tcp_read(2697) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/Attic/.directory_history,v) failed to open file 1 times 05:56:19: -> tcp_read(2579) using WSAEventSelect 05:56:19: -> tcp_read(2579) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/Attic/.directory_history,v) file does not exist, so return 0 05:56:19: -> tcp_read(2456) using WSAEventSelect 05:56:19: -> tcp_read(2456) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open failed 05:56:19: -> tcp_read(2420) using WSAEventSelect 05:56:19: -> tcp_read(2420) immediate timeout previous thread count -1/0 05:56:19: S -> Duh! We already new this was rubbish... 05:56:19: -> tcp_read(2362) using WSAEventSelect 05:56:19: -> tcp_read(2362) immediate timeout previous thread count -1/0 05:56:19: S -> No mapping file in this directory. 05:56:19: -> tcp_read(2310) using WSAEventSelect 05:56:19: -> tcp_read(2310) immediate timeout previous thread count -1/0 05:56:19: S -> open_directory copy the tag and date. 05:56:19: -> tcp_read(2255) using WSAEventSelect 05:56:19: -> tcp_read(2255) immediate timeout previous thread count -1/0 05:56:19: S -> open_directory copied the tag and date. 05:56:19: -> tcp_read(2198) using WSAEventSelect 05:56:19: -> tcp_read(2198) immediate timeout previous thread count -1/0 05:56:19: S -> Look for rename script file CVS/Rename 05:56:19: -> tcp_read(2142) using WSAEventSelect 05:56:19: -> tcp_read(2142) immediate timeout previous thread count -1/0 05:56:19: S -> Rename script file does not exist 05:56:19: -> tcp_read(2091) using WSAEventSelect 05:56:19: -> tcp_read(2091) immediate timeout previous thread count -1/0 05:56:19: S -> directory opened 05:56:19: -> tcp_read(2057) using WSAEventSelect 05:56:19: -> tcp_read(2057) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: begin 05:56:19: -> tcp_read(2020) using WSAEventSelect 05:56:19: -> tcp_read(2020) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: Check the value in CVSADM_ROOT 05:56:19: -> tcp_read(1957) using WSAEventSelect 05:56:19: -> tcp_read(1957) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: Fill in repository with the current repository 05:56:19: -> tcp_read(1879) using WSAEventSelect 05:56:19: -> tcp_read(1879) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: update_dir= 05:56:19: -> tcp_read(1836) using WSAEventSelect 05:56:19: -> tcp_read(1836) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository((null),) 05:56:19: -> tcp_read(1794) using WSAEventSelect 05:56:19: -> tcp_read(1794) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository dup tmp is len 05:56:19: -> tcp_read(1746) using WSAEventSelect 05:56:19: -> tcp_read(1746) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository dup tmp is now len 05:56:19: -> tcp_read(1694) using WSAEventSelect 05:56:19: -> tcp_read(1694) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository open CVS/Repository 05:56:19: -> tcp_read(1641) using WSAEventSelect 05:56:19: -> tcp_read(1641) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository opened CVS/Repository ok so read a line 05:56:19: -> tcp_read(1568) using WSAEventSelect 05:56:19: -> tcp_read(1568) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository closed CVS/Repository 05:56:19: -> tcp_read(1513) using WSAEventSelect 05:56:19: -> tcp_read(1513) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository read 1 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(1437) using WSAEventSelect 05:56:19: -> tcp_read(1437) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository (stripped the newline) read 2 C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(1341) using WSAEventSelect 05:56:19: -> tcp_read(1341) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk )? 05:56:19: -> tcp_read(1260) using WSAEventSelect 05:56:19: -> tcp_read(1260) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository not isabsolute( C:/PROGRA~3/MARCHH~1/myrepo/junk ) 05:56:19: -> tcp_read(1176) using WSAEventSelect 05:56:19: -> tcp_read(1176) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository Sanitize_Repository_Name( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: -> tcp_read(1081) using WSAEventSelect 05:56:19: -> tcp_read(1081) immediate timeout previous thread count -1/0 05:56:19: S -> Name_Repository return ( C:/PROGRA~3/MARCHH~1/myrepo/junk )! 05:56:19: -> tcp_read(1003) using WSAEventSelect 05:56:19: -> tcp_read(1003) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: update_dir= repository=C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(916) using WSAEventSelect 05:56:19: -> tcp_read(916) immediate timeout previous thread count -1/0 05:56:19: S -> (repository && (server_active || !current_parsed_root->isremote)) 05:56:19: -> tcp_read(833) using WSAEventSelect 05:56:19: -> tcp_read(833) immediate timeout previous thread count -1/0 05:56:19: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(767) using WSAEventSelect 05:56:19: -> tcp_read(767) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() 05:56:19: -> tcp_read(733) using WSAEventSelect 05:56:19: -> tcp_read(733) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2(junk) after rename? 05:56:19: -> tcp_read(681) using WSAEventSelect 05:56:19: -> tcp_read(681) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() call _lookup_module2() 05:56:19: -> tcp_read(624) using WSAEventSelect 05:56:19: -> tcp_read(624) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 05:56:19: -> tcp_read(557) using WSAEventSelect 05:56:19: -> tcp_read(557) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2(junk,3) 05:56:19: -> tcp_read(517) using WSAEventSelect 05:56:19: -> tcp_read(517) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 !p lookup_repository_module(junk) 05:56:19: -> tcp_read(450) using WSAEventSelect 05:56:19: -> tcp_read(450) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_repository_module(junk) 05:56:19: -> tcp_read(402) using WSAEventSelect 05:56:19: -> tcp_read(402) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() calls to lookup_repository_module() returned nothing 05:56:19: -> tcp_read(315) using WSAEventSelect 05:56:19: -> tcp_read(315) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 !mod return 0 left,right(,junk) 05:56:19: -> tcp_read(250) using WSAEventSelect 05:56:19: -> tcp_read(250) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() call _lookup_module2 returned 0 05:56:19: -> tcp_read(184) using WSAEventSelect 05:56:19: -> tcp_read(184) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() no recursion permitted so give up now 05:56:19: -> tcp_read(112) using WSAEventSelect 05:56:19: -> tcp_read(112) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 05:56:19: -> tcp_read(1) using WSAEventSelect 05:56:19: -> tcp_read(1) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() return ret=0 renamed=0 05:56:19: -> tcp_read(5064) using WSAEventSelect 05:56:19: -> tcp_read(5064) immediate timeout previous thread count -1/0 05:56:19: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/junk" 05:56:19: -> tcp_read(4976) using WSAEventSelect 05:56:19: -> tcp_read(4976) immediate timeout previous thread count -1/0 05:56:19: S -> Repository = C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(4913) using WSAEventSelect 05:56:19: -> tcp_read(4913) immediate timeout previous thread count -1/0 05:56:19: S -> Mapped repository = C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(4843) using WSAEventSelect 05:56:19: -> tcp_read(4843) immediate timeout previous thread count -1/0 05:56:19: S -> (repository && (server_active || !current_parsed_root->isremote)) 05:56:19: -> tcp_read(4760) using WSAEventSelect 05:56:19: -> tcp_read(4760) immediate timeout previous thread count -1/0 05:56:19: S -> ParseTag() 05:56:19: -> tcp_read(4732) using WSAEventSelect 05:56:19: -> tcp_read(4732) immediate timeout previous thread count -1/0 05:56:19: S -> ParseTag_Dir((null),,,0,) 05:56:19: -> tcp_read(4678) using WSAEventSelect 05:56:19: -> tcp_read(4678) immediate timeout previous thread count -1/0 05:56:19: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/junk,,(null) 05:56:19: -> tcp_read(4606) using WSAEventSelect 05:56:19: -> tcp_read(4606) immediate timeout previous thread count -1/0 05:56:19: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/junk,(null),write,(null),(null)) 05:56:19: -> tcp_read(4516) using WSAEventSelect 05:56:19: -> tcp_read(4516) immediate timeout previous thread count -1/0 05:56:19: S -> verify_perm: verify_owner_dir() 05:56:19: -> tcp_read(4467) using WSAEventSelect 05:56:19: -> tcp_read(4467) immediate timeout previous thread count -1/0 05:56:19: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(4403) using WSAEventSelect 05:56:19: -> tcp_read(4403) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(4324) using WSAEventSelect 05:56:19: -> tcp_read(4324) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: -> tcp_read(4172) using WSAEventSelect 05:56:19: -> tcp_read(4172) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: -> tcp_read(4105) using WSAEventSelect 05:56:19: -> tcp_read(4105) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: -> tcp_read(3958) using WSAEventSelect 05:56:19: -> tcp_read(3958) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: -> tcp_read(3877) using WSAEventSelect 05:56:19: -> tcp_read(3877) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() return 05:56:19: -> tcp_read(3823) using WSAEventSelect 05:56:19: -> tcp_read(3823) immediate timeout previous thread count -1/0 05:56:19: S -> verify_valid_name(lusera) 05:56:19: -> tcp_read(3780) using WSAEventSelect 05:56:19: -> tcp_read(3780) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(3701) using WSAEventSelect 05:56:19: -> tcp_read(3701) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: -> tcp_read(3549) using WSAEventSelect 05:56:19: -> tcp_read(3549) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: -> tcp_read(3482) using WSAEventSelect 05:56:19: -> tcp_read(3482) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: -> tcp_read(3335) using WSAEventSelect 05:56:19: -> tcp_read(3335) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: -> tcp_read(3254) using WSAEventSelect 05:56:19: -> tcp_read(3254) immediate timeout previous thread count -1/0 05:56:19: S -> cache_directory_permissions() return 05:56:19: -> tcp_read(3200) using WSAEventSelect 05:56:19: -> tcp_read(3200) immediate timeout previous thread count -1/0 05:56:19: S -> verify_perm() Read directory permission cache OK 05:56:19: -> tcp_read(3134) using WSAEventSelect 05:56:19: -> tcp_read(3134) immediate timeout previous thread count -1/0 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: -> tcp_read(3060) using WSAEventSelect 05:56:19: -> tcp_read(3060) immediate timeout previous thread count -1/0 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: -> tcp_read(3013) using WSAEventSelect 05:56:19: -> tcp_read(3013) immediate timeout previous thread count -1/0 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: -> tcp_read(2964) using WSAEventSelect 05:56:19: -> tcp_read(2964) immediate timeout previous thread count -1/0 05:56:19: S -> no match at this level 05:56:19: -> tcp_read(2924) using WSAEventSelect 05:56:19: -> tcp_read(2924) immediate timeout previous thread count -1/0 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 05:56:19: -> tcp_read(2855) using WSAEventSelect 05:56:19: -> tcp_read(2855) immediate timeout previous thread count -1/0 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: -> tcp_read(2808) using WSAEventSelect 05:56:19: -> tcp_read(2808) immediate timeout previous thread count -1/0 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: -> tcp_read(2759) using WSAEventSelect 05:56:19: -> tcp_read(2759) immediate timeout previous thread count -1/0 05:56:19: S -> no match at this level 05:56:19: -> tcp_read(2719) using WSAEventSelect 05:56:19: -> tcp_read(2719) immediate timeout previous thread count -1/0 05:56:19: S -> Match found. user_state = 0, group_state = 0 05:56:19: -> tcp_read(2657) using WSAEventSelect 05:56:19: -> tcp_read(2657) immediate timeout previous thread count -1/0 05:56:19: S -> verify_perm() return 05:56:19: -> tcp_read(2619) using WSAEventSelect 05:56:19: -> tcp_read(2619) immediate timeout previous thread count -1/0 05:56:19: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(2550) using WSAEventSelect 05:56:19: -> tcp_read(2550) immediate timeout previous thread count -1/0 05:56:19: S -> * The filesdoneproc needs to be called for each directory where files 05:56:19: -> tcp_read(2463) using WSAEventSelect 05:56:19: -> tcp_read(2463) immediate timeout previous thread count -1/0 05:56:19: S -> * processed, or each directory that is processed by a call where no 05:56:19: -> tcp_read(2378) using WSAEventSelect 05:56:19: -> tcp_read(2378) immediate timeout previous thread count -1/0 05:56:19: S -> * directories were passed in. In fact, the only time we don't want to 05:56:19: -> tcp_read(2290) using WSAEventSelect 05:56:19: -> tcp_read(2290) immediate timeout previous thread count -1/0 05:56:19: S -> * call back the filesdoneproc is when we are processing directories that 05:56:19: -> tcp_read(2200) using WSAEventSelect 05:56:19: -> tcp_read(2200) immediate timeout previous thread count -1/0 05:56:19: S -> * were passed in on the command line (or in the special case of `.' when 05:56:19: -> tcp_read(2110) using WSAEventSelect 05:56:19: -> tcp_read(2110) immediate timeout previous thread count -1/0 05:56:19: S -> * we were called with no args 05:56:19: -> tcp_read(2063) using WSAEventSelect 05:56:19: -> tcp_read(2063) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: dirlist == NULL && filelist == NULL 05:56:19: -> tcp_read(1996) using WSAEventSelect 05:56:19: -> tcp_read(1996) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: dodoneproc=1 05:56:19: -> tcp_read(1952) using WSAEventSelect 05:56:19: -> tcp_read(1952) immediate timeout previous thread count -1/0 05:56:19: S -> * If filelist or dirlist is already set, we don't look again. Otherwise, 05:56:19: -> tcp_read(1862) using WSAEventSelect 05:56:19: -> tcp_read(1862) immediate timeout previous thread count -1/0 05:56:19: S -> * find the files and directories 05:56:19: -> tcp_read(1812) using WSAEventSelect 05:56:19: -> tcp_read(1812) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: something was passed on the command line 05:56:19: -> tcp_read(1739) using WSAEventSelect 05:56:19: -> tcp_read(1739) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: we will process files, so pre-parse entries 05:56:19: -> tcp_read(1663) using WSAEventSelect 05:56:19: -> tcp_read(1663) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open() 05:56:19: -> tcp_read(1631) using WSAEventSelect 05:56:19: -> tcp_read(1631) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open ParseTag() 05:56:19: -> tcp_read(1590) using WSAEventSelect 05:56:19: -> tcp_read(1590) immediate timeout previous thread count -1/0 05:56:19: S -> ParseTag() 05:56:19: -> tcp_read(1562) using WSAEventSelect 05:56:19: -> tcp_read(1562) immediate timeout previous thread count -1/0 05:56:19: S -> ParseTag_Dir((null),,,14352820,) 05:56:19: -> tcp_read(1490) using WSAEventSelect 05:56:19: -> tcp_read(1490) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open aflag==0 && dirtag==0 && dirdate==0 05:56:19: -> tcp_read(1424) using WSAEventSelect 05:56:19: -> tcp_read(1424) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open CVS_FOPEN("CVS/Entries","r") 05:56:19: -> tcp_read(1365) using WSAEventSelect 05:56:19: -> tcp_read(1365) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode 05:56:19: -> tcp_read(1242) using WSAEventSelect 05:56:19: -> tcp_read(1242) immediate timeout previous thread count -1/0 05:56:19: S -> Entnode_Create() 05:56:19: -> tcp_read(1208) using WSAEventSelect 05:56:19: -> tcp_read(1208) immediate timeout previous thread count -1/0 05:56:19: S -> Entnode_Create() finished 05:56:19: -> tcp_read(1165) using WSAEventSelect 05:56:19: -> tcp_read(1165) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() 05:56:19: -> tcp_read(1133) using WSAEventSelect 05:56:19: -> tcp_read(1133) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() get a node and fill in the regular stuff 05:56:19: -> tcp_read(1059) using WSAEventSelect 05:56:19: -> tcp_read(1059) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() this one gets a key of the name for hashing 05:56:19: -> tcp_read(982) using WSAEventSelect 05:56:19: -> tcp_read(982) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() put the node into the list 05:56:19: -> tcp_read(922) using WSAEventSelect 05:56:19: -> tcp_read(922) immediate timeout previous thread count -1/0 05:56:19: S -> AddEntryNode() finished 05:56:19: -> tcp_read(881) using WSAEventSelect 05:56:19: -> tcp_read(881) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open fclose() CVSADM_ENT 05:56:19: -> tcp_read(830) using WSAEventSelect 05:56:19: -> tcp_read(830) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r") 05:56:19: -> tcp_read(765) using WSAEventSelect 05:56:19: -> tcp_read(765) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle 05:56:19: -> tcp_read(688) using WSAEventSelect 05:56:19: -> tcp_read(688) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open fclose() CVSADM_ENTEXT 05:56:19: -> tcp_read(634) using WSAEventSelect 05:56:19: -> tcp_read(634) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r") 05:56:19: -> tcp_read(571) using WSAEventSelect 05:56:19: -> tcp_read(571) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open (! sawdir) - so xmalloc() 05:56:19: -> tcp_read(515) using WSAEventSelect 05:56:19: -> tcp_read(515) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open entrieslist is not NULL, add some sticky data... 05:56:19: -> tcp_read(436) using WSAEventSelect 05:56:19: -> tcp_read(436) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open clean up and return 05:56:19: -> tcp_read(385) using WSAEventSelect 05:56:19: -> tcp_read(385) immediate timeout previous thread count -1/0 05:56:19: S -> Entries_Open done 05:56:19: -> tcp_read(350) using WSAEventSelect 05:56:19: -> tcp_read(350) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: process the files (if any) 05:56:19: -> tcp_read(291) using WSAEventSelect 05:56:19: -> tcp_read(291) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: read lock it if necessary 05:56:19: -> tcp_read(233) using WSAEventSelect 05:56:19: -> tcp_read(233) immediate timeout previous thread count -1/0 05:56:19: S -> * For the server, we handle notifications in a completely different 05:56:19: -> tcp_read(148) using WSAEventSelect 05:56:19: -> tcp_read(148) immediate timeout previous thread count -1/0 05:56:19: S -> place (server_notify). For local, we can't do them here--we don't 05:56:19: -> tcp_read(62) using WSAEventSelect 05:56:19: -> tcp_read(62) immediate timeout previous thread count -1/0 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> have writelocks in place, and there is no way to get writelocks here 05:56:19: -> tcp_read(5094) using WSAEventSelect 05:56:19: -> tcp_read(5094) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: process the files by calling walklist() 05:56:19: -> tcp_read(5023) using WSAEventSelect 05:56:19: -> tcp_read(5023) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: process the files "a.txt" 05:56:19: -> tcp_read(4966) using WSAEventSelect 05:56:19: -> tcp_read(4966) immediate timeout previous thread count -1/0 05:56:19: S -> (server_active || !current_parsed_root->isremote) 05:56:19: -> tcp_read(4899) using WSAEventSelect 05:56:19: -> tcp_read(4899) immediate timeout previous thread count -1/0 05:56:19: S -> map_filename(C:/PROGRA~3/MARCHH~1/myrepo/junk,a.txt) 05:56:19: -> tcp_read(4829) using WSAEventSelect 05:56:19: -> tcp_read(4829) immediate timeout previous thread count -1/0 05:56:19: S -> map_filename - call map_repository(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt) 05:56:19: -> tcp_read(4737) using WSAEventSelect 05:56:19: -> tcp_read(4737) immediate timeout previous thread count -1/0 05:56:19: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt) 05:56:19: -> tcp_read(4665) using WSAEventSelect 05:56:19: -> tcp_read(4665) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() 05:56:19: -> tcp_read(4631) using WSAEventSelect 05:56:19: -> tcp_read(4631) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2(junk/a.txt) after rename? 05:56:19: -> tcp_read(4573) using WSAEventSelect 05:56:19: -> tcp_read(4573) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() call _lookup_module2() 05:56:19: -> tcp_read(4516) using WSAEventSelect 05:56:19: -> tcp_read(4516) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 05:56:19: -> tcp_read(4449) using WSAEventSelect 05:56:19: -> tcp_read(4449) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2(junk/a.txt,4) 05:56:19: -> tcp_read(4403) using WSAEventSelect 05:56:19: -> tcp_read(4403) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 p!=NULL lookup_repository_module(junk) 05:56:19: -> tcp_read(4331) using WSAEventSelect 05:56:19: -> tcp_read(4331) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_repository_module(junk) 05:56:19: -> tcp_read(4283) using WSAEventSelect 05:56:19: -> tcp_read(4283) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() calls to lookup_repository_module() returned nothing 05:56:19: -> tcp_read(4196) using WSAEventSelect 05:56:19: -> tcp_read(4196) immediate timeout previous thread count -1/0 05:56:19: S -> _lookup_module2 !mod return 0 left,right(,junk/a.txt) 05:56:19: -> tcp_read(4125) using WSAEventSelect 05:56:19: -> tcp_read(4125) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() call _lookup_module2 returned 0 05:56:19: -> tcp_read(4059) using WSAEventSelect 05:56:19: -> tcp_read(4059) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() no recursion permitted so give up now 05:56:19: -> tcp_read(3987) using WSAEventSelect 05:56:19: -> tcp_read(3987) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 05:56:19: -> tcp_read(3876) using WSAEventSelect 05:56:19: -> tcp_read(3876) immediate timeout previous thread count -1/0 05:56:19: S -> lookup_module2() return ret=0 renamed=0 05:56:19: -> tcp_read(3819) using WSAEventSelect 05:56:19: -> tcp_read(3819) immediate timeout previous thread count -1/0 05:56:19: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt" 05:56:19: -> tcp_read(3725) using WSAEventSelect 05:56:19: -> tcp_read(3725) immediate timeout previous thread count -1/0 05:56:19: S -> map_filename - map_repository() returns C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt 05:56:19: -> tcp_read(3629) using WSAEventSelect 05:56:19: -> tcp_read(3629) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: mapped name of file "a.txt" 05:56:19: -> tcp_read(3570) using WSAEventSelect 05:56:19: -> tcp_read(3570) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: frfile->frame->dosrcs && mapped_file_repository 05:56:19: -> tcp_read(3491) using WSAEventSelect 05:56:19: -> tcp_read(3491) immediate timeout previous thread count -1/0 05:56:19: S -> RCS_parse(a.txt,C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: -> tcp_read(3424) using WSAEventSelect 05:56:19: -> tcp_read(3424) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v) 05:56:19: -> tcp_read(3353) using WSAEventSelect 05:56:19: -> tcp_read(3353) immediate timeout previous thread count -1/0 05:56:19: S -> normalize_path(C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v) 05:56:19: -> tcp_read(3279) using WSAEventSelect 05:56:19: -> tcp_read(3279) immediate timeout previous thread count -1/0 05:56:19: S -> ...returns C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v 05:56:19: -> tcp_read(3210) using WSAEventSelect 05:56:19: -> tcp_read(3210) immediate timeout previous thread count -1/0 05:56:19: S -> Lock server command sent: Lock Read Full|C:/PROGRA~3/MARCHH~1/myrepo/junk/a.txt,v 05:56:19: -> tcp_read(3108) using WSAEventSelect 05:56:19: -> tcp_read(3108) immediate timeout previous thread count -1/0 05:56:19: S -> Lock server command received: 000 OK Lock granted (252) 05:56:19: -> tcp_read(3032) using WSAEventSelect 05:56:19: -> tcp_read(3032) immediate timeout previous thread count -1/0 05:56:19: S -> do_lock_server: [05:56:19] obtained lock 252 in a.txt,v 05:56:19: -> tcp_read(2959) using WSAEventSelect 05:56:19: -> tcp_read(2959) immediate timeout previous thread count -1/0 05:56:19: S -> do_lock_file: lock server returns 252 05:56:19: -> tcp_read(2904) using WSAEventSelect 05:56:19: -> tcp_read(2904) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_open(/myrepo/junk/a.txt,v) -- obtained a new lock 05:56:19: -> tcp_read(2830) using WSAEventSelect 05:56:19: -> tcp_read(2830) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_fill(windows,BigMemory=0) 05:56:19: -> tcp_read(2780) using WSAEventSelect 05:56:19: -> tcp_read(2780) immediate timeout previous thread count -1/0 05:56:19: S -> rcsbuf_fill(0): expanded to 8192 (was: 0, req: 5120, inc: 5120, RCSBUF_BUFSIZE: 5120). 05:56:19: -> tcp_read(2676) using WSAEventSelect 05:56:19: -> tcp_read(2676) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: call findnode_fn() 05:56:19: -> tcp_read(2626) using WSAEventSelect 05:56:19: -> tcp_read(2626) immediate timeout previous thread count -1/0 05:56:19: S -> do_file_proc: got a result from findnode_fn() 05:56:19: -> tcp_read(2563) using WSAEventSelect 05:56:19: -> tcp_read(2563) immediate timeout previous thread count -1/0 05:56:19: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/junk,,(null) 05:56:19: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/junk,a.txt,write,(null),(null)) 05:56:19: S -> verify_perm: verify_owner_dir() 05:56:19: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_valid_name(lusera) 05:56:19: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/junk) 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/junk",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() last_component=junk 05:56:19: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 05:56:19: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> cache_directory_permissions() return 05:56:19: S -> verify_perm() Read directory permission cache OK 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/junk 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 05:56:19: S -> verify_acl(write,HEAD,(null)) 05:56:19: S -> user_state = 0, group_state = 0 05:56:19: S -> no match at this level 05:56:19: S -> Match found. user_state = 0, group_state = 0 05:56:19: S -> verify_perm() return 05:56:19: S -> do_file_proc: verify_access() returned ok 05:56:19: S -> do_file_proc: finished fileproc() 05:56:19: S -> Lock server command sent: Unlock 252 05:56:19: S -> Lock server command received: 000 OK Unlocked 05:56:19: S -> do_unlock_file: (lock 252) lock server returns 0 05:56:19: S -> do_file_proc: return 05:56:19: S -> do_recursion: unlock it 05:56:19: S -> do_recursion: clean up by calling dellist(filelist) 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> do_recursion: call-back files done proc (if any) 05:56:19: S -> do_recursion: yes: call-back files done proc (if any) 05:56:19: S -> do_recursion: at skip_directory 05:56:19: S -> (repository && (server_active || !current_parsed_root->isremote)) 05:56:19: S -> fileattr_write() 05:56:19: S -> fileattr_free() 05:56:19: S -> do_recursion: process the directories (if necessary) 05:56:19: S -> do_recursion: cannot process the directories (dirlist == NULL) 05:56:19: S -> Entries_Close - isfile ("CVS/Entries.Log") 05:56:19: S -> Entries_Close - dellist (list) 05:56:19: S -> do_recursion: free the saved copy of the pointer if necessary 05:56:19: S -> more efficient - close directory in unroll_files_proc? 05:56:19: S -> close_directory() 05:56:19: S -> close_directory() directory_stack_size decreased by one to 0 05:56:19: S -> unroll_files_proc() end 05:56:19: S -> walklist call with unroll_files_proc done. err=0 05:56:19: S -> freenode() free the badbad DIRS 05:56:19: S -> dellist after unroll_files_proc done. 05:56:19: S -> start_recursion: cannot do_recursion on the dirlist. 05:56:19: S -> start_recursion: Free the data which expand_wild allocated. 05:56:19: S -> start_recursion: Free the data - update_dir 05:56:19: S -> start_recursion: Free the data - update_repos 05:56:19: S -> start_recursion: (args_to_send_when_finished == NULL) 05:56:19: S -> start_recursion: return 0. 05:56:19: S -> Lock server command sent: Close 05:56:19: S -> Lock server command received: 000 OK 05:56:19: S -> open_module(C:/PROGRA~3/MARCHH~1/myrepo) 05:56:19: S -> Process warnings about client versions out of date... 05:56:19: S -> run postcommand proc server 05:56:19: S -> run_trigger() 05:56:19: S -> Call pre-loaded 'email.dll' 05:56:19: S -> postcommand_proc() 05:56:19: S -> commit server_active=yes 05:56:19: S -> Call pre-loaded 'email.dll' returned 0, total ret 0 05:56:19: S -> Call pre-loaded 'info.dll' 05:56:19: S -> postcommand_proc() 05:56:19: S -> default_trigger: parse_info(CVSROOT/postcommit,%r/%p,,junk) 05:56:19: S -> CFileAccess::open('C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/postcommit','rb') 05:56:19: S -> default_trigger: no file 05:56:19: S -> CFileAccess::close() 05:56:19: S -> commit server_active=yes 05:56:19: S -> default_trigger: parse_info(CVSROOT/postcommand,%r/%p %c,,junk) 05:56:19: S -> CFileAccess::open('C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/postcommand','rb') 05:56:19: S -> CFileAccess::open() setvbuf(_IONBF) 05:56:19: S -> CFileAccess::close() 05:56:19: S -> CFileAccess::close() 05:56:19: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 05:56:19: S -> run_trigger returning 0 05:56:19: S -> Unloading checkout.dll 05:56:19: S -> Unloading - about to destroy 05:56:19: S -> Unloading - destroyed 05:56:19: S -> Unloading - about to get lib 05:56:19: S -> Unloading - about to unload 05:56:19: S -> Unloading - unloaded 05:56:19: S -> Unloading - about to free 05:56:19: S -> Unloading - about to delete trigg 05:56:19: S -> Unloading - about to delete inf 05:56:19: S -> Unloading - complete 05:56:19: S -> Unloading email.dll 05:56:19: S -> Unloading - about to close 05:56:19: S -> Unloading - closed 05:56:19: S -> Unloading - about to destroy 05:56:19: S -> Unloading - destroyed 05:56:19: S -> Unloading - about to get lib 05:56:19: S -> Unloading - about to unload 05:56:19: S -> Unloading - unloaded 05:56:19: S -> Unloading - about to free 05:56:19: S -> Unloading - about to delete trigg 05:56:19: S -> Unloading - about to delete inf 05:56:19: S -> Unloading - complete 05:56:19: S -> Unloading info.dll 05:56:19: S -> Unloading - about to close 05:56:19: S -> Unloading - closed 05:56:19: S -> Unloading - about to destroy 05:56:19: S -> Unloading - destroyed 05:56:19: S -> Unloading - about to get lib 05:56:19: S -> Unloading - about to unload 05:56:19: S -> Unloading - unloaded 05:56:19: S -> Unloading - about to free 05:56:19: S -> Unloading - about to delete trigg 05:56:19: S -> Unloading - about to delete inf 05:56:19: S -> Unloading - complete 05:56:19: S -> Unloading make.dll 05:56:19: S -> Unloading - about to destroy 05:56:19: S -> Unloading - destroyed 05:56:19: S -> Unloading - about to get lib 05:56:19: S -> Unloading - about to unload 05:56:19: S -> Unloading - unloaded 05:56:19: S -> Unloading - about to free 05:56:19: S -> Unloading - about to delete trigg 05:56:19: S -> Unloading - about to delete inf 05:56:19: S -> Unloading - complete 05:56:19: S -> Unloading script.dll 05:56:19: S -> Unloading - about to destroy 05:56:19: S -> Unloading - destroyed 05:56:19: S -> Unloading - about to get lib 05:56:19: S -> Unloading - about to unload 05:56:19: S -> Unloading - unloaded 05:56:19: S -> Unloading - about to free 05:56:19: S -> Unloading - about to delete trigg 05:56:19: S -> Unloading - about to delete inf 05:56:19: S -> Unloading - complete 05:56:19: S -> Unloading sync.dll 05:56:19: S -> Unloading - about to destroy 05:56:19: -> tcp_read(5120) using WSAEventSelect 05:56:19: -> tcp_read(5120) immediate timeout previous thread count -1/0 05:56:19: S -> Unloading - destroyed 05:56:19: S -> Unloading - about to get lib 05:56:19: S -> Unloading - about to unload 05:56:19: S -> Unloading - unloaded 05:56:19: S -> Unloading - about to free 05:56:19: S -> Unloading - about to delete trigg 05:56:19: S -> Unloading - about to delete inf 05:56:19: S -> Unloading - complete 05:56:19: S -> Unloading - about to clear list 05:56:19: S -> Unloading - cleared list 05:56:19: -> Entries_Close - isfile ("CVS/Entries.Log") 05:56:19: -> Entries_Close - write_entries (list) 05:56:19: -> write_entries() CVSADM_ENTBAK entfilename="CVS/Entries.Log" 05:56:19: -> write_entries() CVSADM_ENTBAK ok 05:56:19: -> write_entries() CVSADM_ENTEXBAK ok 05:56:19: -> write_entries() !sawdir 05:56:19: -> write_entries() now, atomically (on systems that support it) rename it 05:56:19: -> rename(CVS/Entries.Backup,CVS/Entries) 05:56:19: -> wnt_rename(CVS/Entries.Backup,CVS/Entries) 05:56:19: -> rename file CVS/Entries.Backup to CVS/Entries ... 05:56:19: -> rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra) 05:56:19: -> wnt_rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra) 05:56:19: -> rename file CVS/Entries.Extra.Backup to CVS/Entries.Extra ... 05:56:19: -> write_entries() now, remove the log file 05:56:19: -> unlink_file(CVS/Entries.Log) 05:56:19: -> unlink_file(CVS/Entries.Extra.Log) 05:56:19: -> write_entries() done 05:56:19: -> Entries_Close - dellist (list) 05:56:20: -> Server: return from the requested function 05:56:20: -> Unloading - about to clear list 05:56:20: -> Unloading - cleared list 05:56:20: -> Unloading sspi 05:56:20: -> Process 1636 exiting 05:56:20: -> free_directory() 05:56:20: -> free_modules2() directory_stack_size and rubbish set to zero 05:56:20: -> Free CVS_Username and cvs_locale 05:56:20: -> Perform SYSTEM_CLEANUP () 05:56:20: -> Free global_session_time and global_real_session_time 05:56:20: -> CCvsgui::Close 05:56:20: -> main return OK 05:56:20: -> Set the terminate semaphore 05:56:20: -> Free trace_file