12:49:17: -> Client trace started, level=3. 12:49:17: -> Tracelevel set to 4. PID is 9020 12:49:17: -> Session ID is 233c4f95961d0f9c 12:49:17: -> Session time is Mon Apr 23 17:49:17 2012 12:49:17: -> Client was compiled Jan 6 2012 23:38:33 12:49:17: -> Client was compiled with MSVC C/C++ 1310 12:49:17: -> Client build platform is microsoft-windows-x86 12:49:17: -> Build version is CVSNT 2.8.01 (Soolin) Build 4395 12:49:17: -> User name is Glen Starrett 12:49:17: -> Client running on "Windows 7 x64 Service Pack 1 (Build 7601) [6.1.7601] (Service Pack 1)" 12:49:17: -> /3GB is enabled, highest user address is fffeffff 12:49:17: -> Client has 40 percent of memory in use. 12:49:17: -> Client has 4194303 total Kbytes of physical memory*. 12:49:17: -> Client has 4194303 free Kbytes of physical memory*. 12:49:17: -> Client has 12541008 total Kbytes of physical memory. 12:49:17: -> Client has 7435304 free Kbytes of physical memory. 12:49:17: -> Client has 25080168 total Kbytes of paging file. 12:49:17: -> Client has 19922068 free Kbytes of paging file. 12:49:17: -> Client has 4194176 total Kbytes of virtual memory. 12:49:17: -> Client has 4113240 free Kbytes of virtual memory. 12:49:17: -> Client has 0 free Kbytes of extended memory. 12:49:17: -> The custom heap has the low-fragmentation heap enabled. 12:49:17: -> The minimum large page size is 2097152. 12:49:17: -> Walking heap 0X02800000... 12:49:17: -> Region 12:49:17: -> 249856 bytes committed 12:49:17: -> 12288 bytes uncommitted 12:49:17: -> First block address: 0X02800590 12:49:17: -> Last block address: 0X02840000 12:49:17: -> Data portion begins at: 0X02800000 12:49:17: -> Size: 1416 bytes 12:49:17: -> Overhead: 0 bytes 12:49:17: -> Region index: 0 12:49:17: -> Block 12:49:17: -> Data portion begins at: 0X0283BDA8 12:49:17: -> Size: 4664 bytes 12:49:17: -> Overhead: 16 bytes 12:49:17: -> Region index: 0 12:49:17: -> Uncommitted range 12:49:17: -> Data portion begins at: 0X0283D000 12:49:17: -> Size: 12288 bytes 12:49:17: -> Overhead: 0 bytes 12:49:17: -> Region index: 0 12:49:17: -> Client Hardware information (Wow64): 12:49:17: -> Number of processors: 8 12:49:17: -> Processor type: 8664 12:49:17: -> processor architecture: x64 12:49:17: -> processor type 8664/Xeon model: unknown 12:49:17: -> Free space in C:\Users\GLENST~1\AppData\Local\Temp 2493681664 12:49:17: -> Total space in C:\Users\GLENST~1\AppData\Local\Temp 2386554880 12:49:17: -> Total Free space in C:\Users\GLENST~1\AppData\Local\Temp 2493681664 12:49:17: -> Loading protocol sspi as sspi.dll 12:49:17: -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/protocols/sspi.dll 12:49:17: -> main loop with CVSROOT=:sspi:europa:/myrepo 12:49:17: -> main - about to start the server yes 12:49:17: -> start_server(verify_only=0) 12:49:17: -> client start - client_protocol->connect 12:49:17: -> Buffer size is 5120 12:49:17: -> sspi_connect 12:49:17: -> tcp_write(26,572) 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> SSPI:ClientAuthenticate(Negotiate,*NONE*,*NONE*) !Schannel - AcquireCredentialsHandle 12:49:17: -> SSPI:ClientAuthenticate() !Schannel - AcquireCredentialsHandle result = OK 12:49:17: -> SSPI:ClientAuthenticate() InitializeSecurityContext 12:49:17: -> tcp_write(2,572) 12:49:17: -> tcp_write(123,572) 12:49:17: -> tcp_read(2) using WSAEventSelect 12:49:17: -> tcp_read(2) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(179) using WSAEventSelect 12:49:17: -> tcp_read(179) immediate timeout previous thread count -1/0 12:49:17: -> SSPI:ClientAuthenticate() InitializeSecurityContext 12:49:17: -> tcp_write(2,572) 12:49:17: -> tcp_write(121,572) 12:49:17: -> tcp_read(2) using WSAEventSelect 12:49:17: -> tcp_read(2) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(29) using WSAEventSelect 12:49:17: -> tcp_read(29) immediate timeout previous thread count -1/0 12:49:17: -> SSPI:ClientAuthenticate() InitializeSecurityContext 12:49:17: -> tcp_write(8,572) 12:49:17: -> client start - Loop, getting responses from the server. 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 73(I), index=0 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 32( ), index=1 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 76(L), index=2 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 79(O), index=3 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 86(V), index=4 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 69(E), index=5 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 32( ), index=6 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 89(Y), index=7 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 79(O), index=8 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 85(U), index=9 12:49:17: -> tcp_read(1) using WSAEventSelect 12:49:17: -> tcp_read(1) immediate timeout previous thread count -1/0 12:49:17: -> recv_line - got 1 chars, first is 10( ), index=10 12:49:17: -> client start - got "I LOVE YOU" 12:49:17: -> client start - continue login. 12:49:17: -> client start - server started. 12:49:17: -> client start - send Valid-responses to server. 12:49:17: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=0 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=15 12:49:17: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=16 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=18 12:49:17: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=19 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=24 12:49:17: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=25 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=39 12:49:17: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=40 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=50 12:49:17: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=51 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=60 12:49:17: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=61 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=69 12:49:17: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=70 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=79 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=80 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=87 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=88 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=95 12:49:17: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=96 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=111 12:49:17: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=112 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=118 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=119 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=126 12:49:17: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=127 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=135 12:49:17: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=136 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=150 12:49:17: -> send_to_server_untranslated() calls buf_output() len=4 nbytes=151 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=155 12:49:17: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=156 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=164 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=165 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=172 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=173 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=180 12:49:17: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=181 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=193 12:49:17: -> send_to_server_untranslated() calls buf_output() len=20 nbytes=194 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=214 12:49:17: -> send_to_server_untranslated() calls buf_output() len=22 nbytes=215 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=237 12:49:17: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=238 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=248 12:49:17: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=249 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=261 12:49:17: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=262 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=270 12:49:17: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=271 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=279 12:49:17: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=280 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=296 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=297 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=314 12:49:17: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=315 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=327 12:49:17: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=328 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=334 12:49:17: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=335 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=347 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=348 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=349 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=350 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=357 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=358 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=359 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=360 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=361 12:49:17: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=362 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=364 12:49:17: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=365 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=381 12:49:17: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=382 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=396 12:49:17: -> client start - send valid-requests to server. 12:49:17: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=397 12:49:17: -> tcp_write(412,572) 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=412 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=424 12:49:17: -> tcp_write(13,572) 12:49:17: -> client start - rootless_encryption. 12:49:17: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=425 12:49:17: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=430 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=437 12:49:17: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=438 12:49:17: -> tcp_write(29,572) 12:49:17: -> tcp_read(4198) using WSAEventSelect 12:49:17: -> tcp_read(4198) immediate timeout previous thread count -1/0 12:49:17: -> Server codepage is CP1252 12:49:17: -> Client codepage is CP1252 12:49:17: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=454 12:49:17: -> send_to_server_untranslated() calls buf_output() len=32 nbytes=469 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=501 12:49:17: -> tcp_write(48,572) 12:49:17: -> tcp_read(4191) using WSAEventSelect 12:49:17: -> tcp_read(4191) immediate timeout previous thread count -1/0 12:49:17: -> Server version is CVSNT 2.8.01 (Soolin) Build 4395 12:49:17: -> Client version is CVSNT 2.8.01 (Soolin) Build 4395 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=502 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=519 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=536 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=553 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=570 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=587 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=588 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=589 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=590 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=591 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=592 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=593 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=594 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=595 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=596 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=597 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=598 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=599 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=600 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=601 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=602 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=603 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=604 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=605 12:49:17: -> Requesting server cvsignore 12:49:17: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=606 12:49:17: -> tcp_write(119,572) 12:49:17: -> tcp_read(4158) using WSAEventSelect 12:49:17: -> tcp_read(4158) immediate timeout previous thread count -1/0 12:49:17: -> wrap_add(*.a -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.a" 12:49:17: -> Call CGetOptions::getOpt: "*.a -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ai -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ai" 12:49:17: -> Call CGetOptions::getOpt: "*.ai -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.avi -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.avi" 12:49:17: -> Call CGetOptions::getOpt: "*.avi -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.bin -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.bin" 12:49:17: -> Call CGetOptions::getOpt: "*.bin -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.bmp -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.bmp" 12:49:17: -> Call CGetOptions::getOpt: "*.bmp -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.bz2 -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.bz2" 12:49:17: -> Call CGetOptions::getOpt: "*.bz2 -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.cdd -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.cdd" 12:49:17: -> Call CGetOptions::getOpt: "*.cdd -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.cdmz -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.cdmz" 12:49:17: -> Call CGetOptions::getOpt: "*.cdmz -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.chm -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.chm" 12:49:17: -> Call CGetOptions::getOpt: "*.chm -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.class -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.class" 12:49:17: -> Call CGetOptions::getOpt: "*.class -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.cur -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.cur" 12:49:17: -> Call CGetOptions::getOpt: "*.cur -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.dll -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.dll" 12:49:17: -> Call CGetOptions::getOpt: "*.dll -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.doc -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.doc" 12:49:17: -> Call CGetOptions::getOpt: "*.doc -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.docx -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.docx" 12:49:17: -> Call CGetOptions::getOpt: "*.docx -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.docm -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.docm" 12:49:17: -> Call CGetOptions::getOpt: "*.docm -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.fp -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.fp" 12:49:17: -> Call CGetOptions::getOpt: "*.fp -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.pptx -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.pptx" 12:49:17: -> Call CGetOptions::getOpt: "*.pptx -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.pptm -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.pptm" 12:49:17: -> Call CGetOptions::getOpt: "*.pptm -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.xlsx -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.xlsx" 12:49:17: -> Call CGetOptions::getOpt: "*.xlsx -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.xlsm -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.xlsm" 12:49:17: -> Call CGetOptions::getOpt: "*.xlsm -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.odt -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.odt" 12:49:17: -> Call CGetOptions::getOpt: "*.odt -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ods -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ods" 12:49:17: -> Call CGetOptions::getOpt: "*.ods -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.odp -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.odp" 12:49:17: -> Call CGetOptions::getOpt: "*.odp -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.odb -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.odb" 12:49:17: -> Call CGetOptions::getOpt: "*.odb -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.odg -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.odg" 12:49:17: -> Call CGetOptions::getOpt: "*.odg -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.odf -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.odf" 12:49:17: -> Call CGetOptions::getOpt: "*.odf -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.mpp -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.mpp" 12:49:17: -> Call CGetOptions::getOpt: "*.mpp -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.dvi -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.dvi" 12:49:17: -> Call CGetOptions::getOpt: "*.dvi -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.exe -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.exe" 12:49:17: -> Call CGetOptions::getOpt: "*.exe -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.gif -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.gif" 12:49:17: -> Call CGetOptions::getOpt: "*.gif -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.gz -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.gz" 12:49:17: -> Call CGetOptions::getOpt: "*.gz -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.hqx -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.hqx" 12:49:17: -> Call CGetOptions::getOpt: "*.hqx -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ico -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ico" 12:49:17: -> Call CGetOptions::getOpt: "*.ico -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ilk -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ilk" 12:49:17: -> Call CGetOptions::getOpt: "*.ilk -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.indd -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.indd" 12:49:17: -> Call CGetOptions::getOpt: "*.indd -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.iso -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.iso" 12:49:17: -> Call CGetOptions::getOpt: "*.iso -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.lib -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.lib" 12:49:17: -> Call CGetOptions::getOpt: "*.lib -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.jar -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.jar" 12:49:17: -> Call CGetOptions::getOpt: "*.jar -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.jpg -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.jpg" 12:49:17: -> Call CGetOptions::getOpt: "*.jpg -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.jpeg -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.jpeg" 12:49:17: -> Call CGetOptions::getOpt: "*.jpeg -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.lnk -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.lnk" 12:49:17: -> Call CGetOptions::getOpt: "*.lnk -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.mpg -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.mpg" 12:49:17: -> Call CGetOptions::getOpt: "*.mpg -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.mpeg -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.mpeg" 12:49:17: -> Call CGetOptions::getOpt: "*.mpeg -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.mov -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.mov" 12:49:17: -> Call CGetOptions::getOpt: "*.mov -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.mp3 -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.mp3" 12:49:17: -> Call CGetOptions::getOpt: "*.mp3 -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ncb -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ncb" 12:49:17: -> Call CGetOptions::getOpt: "*.ncb -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.o -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.o" 12:49:17: -> Call CGetOptions::getOpt: "*.o -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ogg -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ogg" 12:49:17: -> Call CGetOptions::getOpt: "*.ogg -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ocx -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ocx" 12:49:17: -> Call CGetOptions::getOpt: "*.ocx -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.obj -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.obj" 12:49:17: -> Call CGetOptions::getOpt: "*.obj -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.pdb -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.pdb" 12:49:17: -> Call CGetOptions::getOpt: "*.pdb -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.pdf -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.pdf" 12:49:17: -> Call CGetOptions::getOpt: "*.pdf -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.png -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.png" 12:49:17: -> Call CGetOptions::getOpt: "*.png -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ppt -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ppt" 12:49:17: -> Call CGetOptions::getOpt: "*.ppt -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.psd -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.psd" 12:49:17: -> Call CGetOptions::getOpt: "*.psd -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.res -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.res" 12:49:17: -> Call CGetOptions::getOpt: "*.res -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.rpm -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.rpm" 12:49:17: -> Call CGetOptions::getOpt: "*.rpm -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.sit -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.sit" 12:49:17: -> Call CGetOptions::getOpt: "*.sit -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.so -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.so" 12:49:17: -> Call CGetOptions::getOpt: "*.so -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.tar -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.tar" 12:49:17: -> Call CGetOptions::getOpt: "*.tar -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.tga -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.tga" 12:49:17: -> Call CGetOptions::getOpt: "*.tga -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.tgz -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.tgz" 12:49:17: -> Call CGetOptions::getOpt: "*.tgz -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.tif -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.tif" 12:49:17: -> Call CGetOptions::getOpt: "*.tif -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.tiff -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.tiff" 12:49:17: -> Call CGetOptions::getOpt: "*.tiff -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.ttf -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.ttf" 12:49:17: -> Call CGetOptions::getOpt: "*.ttf -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.uir -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.uir" 12:49:17: -> Call CGetOptions::getOpt: "*.uir -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.wav -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.wav" 12:49:17: -> Call CGetOptions::getOpt: "*.wav -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.wmv -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.wmv" 12:49:17: -> Call CGetOptions::getOpt: "*.wmv -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.xls -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.xls" 12:49:17: -> Call CGetOptions::getOpt: "*.xls -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.zip -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.zip" 12:49:17: -> Call CGetOptions::getOpt: "*.zip -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> wrap_add(*.Z -kb, 0, 0, 0, 0) 12:49:17: -> Call CFileAccess::mimetype: "*.Z" 12:49:17: -> Call CGetOptions::getOpt: "*.Z -kb" "+k:x:m:t:" 12:49:17: -> wrap_add() Sort. 12:49:17: -> Requesting server cvswrappers 12:49:17: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=621 12:49:17: -> tcp_write(17,572) 12:49:17: -> tcp_read(4156) using WSAEventSelect 12:49:17: -> tcp_read(4156) immediate timeout previous thread count -1/0 12:49:17: -> Got the server cvswrappers 12:49:17: -> The first item in the sorted list is "b" with wildcard "*.a" 12:49:17: -> Requesting server cvsrc (read-cvsrc2) 12:49:17: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=638 12:49:17: -> tcp_write(12,572) 12:49:17: -> tcp_read(4152) using WSAEventSelect 12:49:17: -> tcp_read(4152) immediate timeout previous thread count -1/0 12:49:17: -> Parsing global cvsrc started 12:49:17: -> Parsing global cvsrc finished 0 lines. 12:49:17: -> No local C:\Users\Glen Starrett/.cvsrc to parse 12:49:17: -> Server: call the requested function rls 12:49:17: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=650 12:49:17: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=659 12:49:17: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=661 12:49:17: -> send_to_server_untranslated() calls buf_output() len=4 nbytes=662 12:49:17: -> tcp_write(16,572) 12:49:17: -> tcp_read(4150) using WSAEventSelect 12:49:17: -> tcp_read(4150) immediate timeout previous thread count -1/0 12:49:17: S -> server_main started 12:49:17: -> tcp_read(4113) using WSAEventSelect 12:49:17: -> tcp_read(4113) immediate timeout previous thread count -1/0 12:49:17: S -> server_main process id is 3888 12:49:17: S -> server_main CVS2_SERVER_SLEEP not set. 12:49:17: -> tcp_read(4009) using WSAEventSelect 12:49:17: -> tcp_read(4009) immediate timeout previous thread count -1/0 12:49:17: S -> run plugintools proc server 12:49:17: S -> run_trigger() 12:49:17: -> tcp_read(3933) using WSAEventSelect 12:49:17: -> tcp_read(3933) immediate timeout previous thread count -1/0 12:49:17: S -> LoadTrigger(audit.dll) 12:49:17: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/audit.dll 12:49:17: -> tcp_read(3801) using WSAEventSelect 12:49:17: -> tcp_read(3801) immediate timeout previous thread count -1/0 12:49:17: S -> Not loading disabled trigger audit.dll. 12:49:17: -> tcp_read(3744) using WSAEventSelect 12:49:17: -> tcp_read(3744) immediate timeout previous thread count -1/0 12:49:17: S -> Couldn't load trigger audit.dll 12:49:17: -> tcp_read(3695) using WSAEventSelect 12:49:17: -> tcp_read(3695) immediate timeout previous thread count -1/0 12:49:17: S -> LoadTrigger(bugzilla.dll) 12:49:17: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/bugzilla.dll 12:49:17: -> tcp_read(3557) using WSAEventSelect 12:49:17: -> tcp_read(3557) immediate timeout previous thread count -1/0 12:49:17: S -> Defect Tracking trigger not enabled. 12:49:17: -> tcp_read(3500) using WSAEventSelect 12:49:17: -> tcp_read(3500) immediate timeout previous thread count -1/0 12:49:17: S -> Couldn't load trigger bugzilla.dll 12:49:17: S -> Checking admin file C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/admin for user Europa\Glen Starrett 12:49:17: -> tcp_read(3339) using WSAEventSelect 12:49:17: -> tcp_read(3339) immediate timeout previous thread count -1/0 12:49:17: S -> Checking started in admin file C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/admin for user Europa\Glen Starrett 12:49:17: -> tcp_read(3219) using WSAEventSelect 12:49:17: -> tcp_read(3219) immediate timeout previous thread count -1/0 12:49:17: S -> Checked admin file (null) for user Europa\Glen Starrett they are an admin. 12:49:17: -> tcp_read(3127) using WSAEventSelect 12:49:17: -> tcp_read(3127) immediate timeout previous thread count -1/0 12:49:17: S -> InitTrigger(checkout.dll) 12:49:17: -> tcp_read(3084) using WSAEventSelect 12:49:17: -> tcp_read(3084) immediate timeout previous thread count -1/0 12:49:17: S -> Checkout trigger not enabled. 12:49:17: -> tcp_read(3037) using WSAEventSelect 12:49:17: -> tcp_read(3037) immediate timeout previous thread count -1/0 12:49:17: S -> Couldn't load trigger checkout.dll 12:49:17: S -> InitTrigger(email.dll) 12:49:17: -> tcp_read(2945) using WSAEventSelect 12:49:17: -> tcp_read(2945) immediate timeout previous thread count -1/0 12:49:17: S -> Email trigger not enabled. 12:49:17: -> tcp_read(2901) using WSAEventSelect 12:49:17: -> tcp_read(2901) immediate timeout previous thread count -1/0 12:49:17: S -> Couldn't load trigger email.dll 12:49:17: S -> InitTrigger(info.dll) 12:49:17: -> tcp_read(2813) using WSAEventSelect 12:49:17: -> tcp_read(2813) immediate timeout previous thread count -1/0 12:49:17: S -> InitTrigger(make.dll) 12:49:17: -> tcp_read(2774) using WSAEventSelect 12:49:17: -> tcp_read(2774) immediate timeout previous thread count -1/0 12:49:17: S -> Make trigger not enabled. 12:49:17: S -> Couldn't load trigger make.dll 12:49:17: -> tcp_read(2680) using WSAEventSelect 12:49:17: -> tcp_read(2680) immediate timeout previous thread count -1/0 12:49:17: S -> InitTrigger(script.dll) 12:49:17: -> tcp_read(2639) using WSAEventSelect 12:49:17: -> tcp_read(2639) immediate timeout previous thread count -1/0 12:49:17: S -> Script trigger not enabled. 12:49:17: -> tcp_read(2594) using WSAEventSelect 12:49:17: -> tcp_read(2594) immediate timeout previous thread count -1/0 12:49:17: S -> Couldn't load trigger script.dll 12:49:17: S -> InitTrigger(sync.dll) 12:49:17: S -> Sync trigger not enabled. 12:49:17: -> tcp_read(2459) using WSAEventSelect 12:49:17: -> tcp_read(2459) immediate timeout previous thread count -1/0 12:49:17: S -> Couldn't load trigger sync.dll 12:49:17: -> tcp_read(2411) using WSAEventSelect 12:49:17: -> tcp_read(2411) immediate timeout previous thread count -1/0 12:49:17: S -> Call pre-loaded 'info.dll' 12:49:17: S -> plugintools_proc() 12:49:17: -> tcp_read(2331) using WSAEventSelect 12:49:17: -> tcp_read(2331) immediate timeout previous thread count -1/0 12:49:17: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 12:49:17: S -> run_trigger returning 0 12:49:17: -> tcp_read(2222) using WSAEventSelect 12:49:17: -> tcp_read(2222) immediate timeout previous thread count -1/0 12:49:17: S -> run precommand proc server 12:49:17: S -> run_trigger() 12:49:17: -> tcp_read(2147) using WSAEventSelect 12:49:17: -> tcp_read(2147) immediate timeout previous thread count -1/0 12:49:17: S -> Call pre-loaded 'info.dll' 12:49:17: S -> precommand_proc() 12:49:17: -> tcp_read(2068) using WSAEventSelect 12:49:17: -> tcp_read(2068) immediate timeout previous thread count -1/0 12:49:17: S -> default_trigger: parse_info(CVSROOT/precommand,%r %c,%) 12:49:17: S -> CFileAccess::open('C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/precommand','rb') 12:49:17: -> tcp_read(1896) using WSAEventSelect 12:49:17: -> tcp_read(1896) immediate timeout previous thread count -1/0 12:49:17: S -> CFileAccess::open() setvbuf(_IONBF) 12:49:17: -> tcp_read(1843) using WSAEventSelect 12:49:17: -> tcp_read(1843) immediate timeout previous thread count -1/0 12:49:17: S -> CFileAccess::close() 12:49:17: -> tcp_read(1805) using WSAEventSelect 12:49:17: -> tcp_read(1805) immediate timeout previous thread count -1/0 12:49:17: S -> CFileAccess::close() 12:49:17: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 12:49:17: -> tcp_read(1699) using WSAEventSelect 12:49:17: -> tcp_read(1699) immediate timeout previous thread count -1/0 12:49:17: S -> run_trigger returning 0 12:49:17: S -> open_module(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(1600) using WSAEventSelect 12:49:17: -> tcp_read(1600) immediate timeout previous thread count -1/0 12:49:17: S -> do_module(., Listing, , ) 12:49:17: -> tcp_read(1557) using WSAEventSelect 12:49:17: -> tcp_read(1557) immediate timeout previous thread count -1/0 12:49:17: S -> do_module calls map_repository() 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(1446) using WSAEventSelect 12:49:17: -> tcp_read(1446) immediate timeout previous thread count -1/0 12:49:17: S -> do_module call to map_repository() has returned something 12:49:17: S -> * check to see if mname is a directory or file 12:49:17: -> tcp_read(1306) using WSAEventSelect 12:49:17: -> tcp_read(1306) immediate timeout previous thread count -1/0 12:49:17: S -> do_module calls isdir(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(1238) using WSAEventSelect 12:49:17: -> tcp_read(1238) immediate timeout previous thread count -1/0 12:49:17: S -> do_module yes it is a directory ... 12:49:17: -> tcp_read(1185) using WSAEventSelect 12:49:17: -> tcp_read(1185) immediate timeout previous thread count -1/0 12:49:17: S -> * OK, we have now set up modargv with the actual file/directory we want to work on. 12:49:17: S -> run premodule trigger 12:49:17: -> tcp_read(1045) using WSAEventSelect 12:49:17: -> tcp_read(1045) immediate timeout previous thread count -1/0 12:49:17: S -> run_trigger() 12:49:17: S -> Call pre-loaded 'info.dll' 12:49:17: -> tcp_read(970) using WSAEventSelect 12:49:17: -> tcp_read(970) immediate timeout previous thread count -1/0 12:49:17: S -> premodule_proc(.) 12:49:17: -> tcp_read(935) using WSAEventSelect 12:49:17: -> tcp_read(935) immediate timeout previous thread count -1/0 12:49:17: S -> default_trigger: parse_info(CVSROOT/premodule,%r/%p %c %o,,.) 12:49:17: S -> CFileAccess::open('C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/premodule','rb') 12:49:17: -> tcp_read(767) using WSAEventSelect 12:49:17: -> tcp_read(767) immediate timeout previous thread count -1/0 12:49:17: S -> CFileAccess::open() setvbuf(_IONBF) 12:49:17: -> tcp_read(714) using WSAEventSelect 12:49:17: -> tcp_read(714) immediate timeout previous thread count -1/0 12:49:17: S -> CFileAccess::close() 12:49:17: -> tcp_read(676) using WSAEventSelect 12:49:17: -> tcp_read(676) immediate timeout previous thread count -1/0 12:49:17: S -> CFileAccess::close() 12:49:17: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 12:49:17: -> tcp_read(570) using WSAEventSelect 12:49:17: -> tcp_read(570) immediate timeout previous thread count -1/0 12:49:17: S -> run_trigger returning 0 12:49:17: S -> do_modue(1): call the callback_proc((null)) Listing modules on server 12:49:17: -> tcp_read(437) using WSAEventSelect 12:49:17: -> tcp_read(437) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(376) using WSAEventSelect 12:49:17: -> tcp_read(376) immediate timeout previous thread count -1/0 12:49:17: S -> start_recursion(.,(null)) 12:49:17: -> tcp_read(333) using WSAEventSelect 12:49:17: -> tcp_read(333) immediate timeout previous thread count -1/0 12:49:17: S -> start_recursion() dirleaveproc is NULL 12:49:17: S -> start_recursion() update_dir set to "." 12:49:17: -> tcp_read(220) using WSAEventSelect 12:49:17: -> tcp_read(220) immediate timeout previous thread count -1/0 12:49:17: S -> start_recursion - argument count (argc) == 0 12:49:17: S -> * There were no arguments, so we'll probably just recurse. The 12:49:17: -> tcp_read(78) using WSAEventSelect 12:49:17: -> tcp_read(78) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> * exception to the rule is when we are called from a directory 12:49:17: S -> * without any CVS administration files. That has always meant to 12:49:17: S -> * process each of the sub-directories, so we pretend like we were 12:49:17: -> tcp_read(4952) using WSAEventSelect 12:49:17: -> tcp_read(4952) immediate timeout previous thread count -1/0 12:49:17: S -> * called with the list of sub-dirs of the current dir as args 12:49:17: S -> start_recursion: !just_subdirs - call addlist(dirlist==NULL, ".") 12:49:17: -> tcp_read(4790) using WSAEventSelect 12:49:17: -> tcp_read(4790) immediate timeout previous thread count -1/0 12:49:17: S -> start_recursion: addlist() returns and now dirlist!=NULL 12:49:17: S -> start_recursion/do_the_work: do_recursion() on the frame (dirlist != NULL). 12:49:17: -> tcp_read(4622) using WSAEventSelect 12:49:17: -> tcp_read(4622) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: begin 12:49:17: S -> do_recursion: Check the value in CVSADM_ROOT 12:49:17: -> tcp_read(4522) using WSAEventSelect 12:49:17: -> tcp_read(4522) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: Fill in repository with the current repository 12:49:17: S -> (repository && (server_active || !current_parsed_root->isremote)) 12:49:17: -> tcp_read(4361) using WSAEventSelect 12:49:17: -> tcp_read(4361) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> Repository = C:/PROGRA~3/MARCHH~1/myrepo/. 12:49:17: -> tcp_read(4240) using WSAEventSelect 12:49:17: -> tcp_read(4240) immediate timeout previous thread count -1/0 12:49:17: S -> Mapped repository = C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: S -> (repository && (server_active || !current_parsed_root->isremote)) 12:49:17: -> tcp_read(4092) using WSAEventSelect 12:49:17: -> tcp_read(4092) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(4010) using WSAEventSelect 12:49:17: -> tcp_read(4010) immediate timeout previous thread count -1/0 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo,.,(null) 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo,(null),read,(null),(null)) 12:49:17: -> tcp_read(3858) using WSAEventSelect 12:49:17: -> tcp_read(3858) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(3750) using WSAEventSelect 12:49:17: -> tcp_read(3750) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(3616) using WSAEventSelect 12:49:17: -> tcp_read(3616) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo)=]27+[sizeof(CVS/fileattr.xml)=]17+20=64 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: -> tcp_read(3448) using WSAEventSelect 12:49:17: -> tcp_read(3448) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(3366) using WSAEventSelect 12:49:17: -> tcp_read(3366) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no file "C:/PROGRA~3/MARCHH~1/myrepo/CVS/fileattr.xml", so allocate ofname of 60 bytes. 12:49:17: S -> fileattr_read() xmalloc ofname OK 12:49:17: -> tcp_read(3193) using WSAEventSelect 12:49:17: -> tcp_read(3193) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo/CVS/fileattr". 12:49:17: -> tcp_read(3108) using WSAEventSelect 12:49:17: -> tcp_read(3108) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo//.owner". 12:49:17: -> tcp_read(3028) using WSAEventSelect 12:49:17: -> tcp_read(3028) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo//.perms". 12:49:17: -> tcp_read(2948) using WSAEventSelect 12:49:17: -> tcp_read(2948) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() CXmlNode("fileattr") 12:49:17: -> tcp_read(2893) using WSAEventSelect 12:49:17: -> tcp_read(2893) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xfree ofname and fname OK 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: -> tcp_read(2758) using WSAEventSelect 12:49:17: -> tcp_read(2758) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(2530) using WSAEventSelect 12:49:17: -> tcp_read(2530) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() return 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: -> tcp_read(2410) using WSAEventSelect 12:49:17: -> tcp_read(2410) immediate timeout previous thread count -1/0 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: -> tcp_read(2295) using WSAEventSelect 12:49:17: -> tcp_read(2295) immediate timeout previous thread count -1/0 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: S -> no match at this level 12:49:17: -> tcp_read(2206) using WSAEventSelect 12:49:17: -> tcp_read(2206) immediate timeout previous thread count -1/0 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: S -> verify_perm() return 12:49:17: -> tcp_read(2106) using WSAEventSelect 12:49:17: -> tcp_read(2106) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> * The filesdoneproc needs to be called for each directory where files 12:49:17: -> tcp_read(1955) using WSAEventSelect 12:49:17: -> tcp_read(1955) immediate timeout previous thread count -1/0 12:49:17: S -> * processed, or each directory that is processed by a call where no 12:49:17: S -> * directories were passed in. In fact, the only time we don't want to 12:49:17: S -> * call back the filesdoneproc is when we are processing directories that 12:49:17: -> tcp_read(1692) using WSAEventSelect 12:49:17: -> tcp_read(1692) immediate timeout previous thread count -1/0 12:49:17: S -> * were passed in on the command line (or in the special case of `.' when 12:49:17: S -> * we were called with no args 12:49:17: -> tcp_read(1555) using WSAEventSelect 12:49:17: -> tcp_read(1555) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: dirlist != NULL && filelist == NULL 12:49:17: S -> do_recursion: dodoneproc=0 12:49:17: -> tcp_read(1444) using WSAEventSelect 12:49:17: -> tcp_read(1444) immediate timeout previous thread count -1/0 12:49:17: S -> * If filelist or dirlist is already set, we don't look again. Otherwise, 12:49:17: S -> * find the files and directories 12:49:17: -> tcp_read(1304) using WSAEventSelect 12:49:17: -> tcp_read(1304) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: something was passed on the command line 12:49:17: S -> do_recursion: process the files (if any) 12:49:17: -> tcp_read(1172) using WSAEventSelect 12:49:17: -> tcp_read(1172) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: call-back files done proc (if any) 12:49:17: S -> do_recursion: no: don't call-back files done proc (process_this_directory!dodoneprocframe->filesdoneproc == NULL) 12:49:17: S -> do_recursion: at skip_directory 12:49:17: -> tcp_read(923) using WSAEventSelect 12:49:17: -> tcp_read(923) immediate timeout previous thread count -1/0 12:49:17: S -> (repository && (server_active || !current_parsed_root->isremote)) 12:49:17: S -> fileattr_write() 12:49:17: -> tcp_read(806) using WSAEventSelect 12:49:17: -> tcp_read(806) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_free() 12:49:17: S -> do_recursion: process the directories (if necessary) 12:49:17: S -> do_recursion: really process the directories (if necessary) 12:49:17: -> tcp_read(624) using WSAEventSelect 12:49:17: -> tcp_read(624) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent) 12:49:17: S -> do_dir_proc() 12:49:17: -> tcp_read(502) using WSAEventSelect 12:49:17: -> tcp_read(502) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() update_dir=. 12:49:17: S -> do_dir_proc() -- set up update_dir(.) - no dots ? 12:49:17: -> tcp_read(390) using WSAEventSelect 12:49:17: -> tcp_read(390) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- set up update_dir is now dirp "." - no dots ? 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: -> tcp_read(227) using WSAEventSelect 12:49:17: -> tcp_read(227) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(134) using WSAEventSelect 12:49:17: -> tcp_read(134) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: -> tcp_read(85) using WSAEventSelect 12:49:17: -> tcp_read(85) immediate timeout previous thread count -1/0 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo,.,(null) 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo,(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(4945) using WSAEventSelect 12:49:17: -> tcp_read(4945) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(4724) using WSAEventSelect 12:49:17: -> tcp_read(4724) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> cache_directory_permissions() return 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: -> tcp_read(4454) using WSAEventSelect 12:49:17: -> tcp_read(4454) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(4359) using WSAEventSelect 12:49:17: -> tcp_read(4359) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: -> tcp_read(4257) using WSAEventSelect 12:49:17: -> tcp_read(4257) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() return 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(4162) using WSAEventSelect 12:49:17: -> tcp_read(4162) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: -> tcp_read(3995) using WSAEventSelect 12:49:17: -> tcp_read(3995) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(.,,,0,) 12:49:17: -> tcp_read(3856) using WSAEventSelect 12:49:17: -> tcp_read(3856) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo,.,(null),(null),0,(null),0) 12:49:17: S -> open_directory() directory_stack_size increased by one to 1 12:49:17: -> tcp_read(3691) using WSAEventSelect 12:49:17: -> tcp_read(3691) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: -> tcp_read(3600) using WSAEventSelect 12:49:17: -> tcp_read(3600) immediate timeout previous thread count -1/0 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/.directory_history,v) 12:49:17: -> tcp_read(3446) using WSAEventSelect 12:49:17: -> tcp_read(3446) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/.directory_history,v) failed to open file 1 times 12:49:17: -> tcp_read(3339) using WSAEventSelect 12:49:17: -> tcp_read(3339) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/.directory_history,v) file does not exist, so return 0 12:49:17: S -> rcsbuf_open failed 12:49:17: -> tcp_read(3191) using WSAEventSelect 12:49:17: -> tcp_read(3191) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/Attic/.directory_history,v) 12:49:17: -> tcp_read(3106) using WSAEventSelect 12:49:17: -> tcp_read(3106) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/Attic/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(2875) using WSAEventSelect 12:49:17: -> tcp_read(2875) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: -> tcp_read(2839) using WSAEventSelect 12:49:17: -> tcp_read(2839) immediate timeout previous thread count -1/0 12:49:17: S -> No mapping file in this directory. 12:49:17: S -> open_directory copy the tag and date. 12:49:17: -> tcp_read(2732) using WSAEventSelect 12:49:17: -> tcp_read(2732) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copied the tag and date. 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: -> tcp_read(2619) using WSAEventSelect 12:49:17: -> tcp_read(2619) immediate timeout previous thread count -1/0 12:49:17: S -> Rename script file does not exist 12:49:17: -> tcp_read(2568) using WSAEventSelect 12:49:17: -> tcp_read(2568) immediate timeout previous thread count -1/0 12:49:17: S -> directory opened 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(2470) using WSAEventSelect 12:49:17: -> tcp_read(2470) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. 12:49:17: -> tcp_read(2313) using WSAEventSelect 12:49:17: -> tcp_read(2313) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- only process the dir if the return code was 0. 12:49:17: S -> do_dir_proc() -- !(frame->which&(W_LOCAL|W_FAKE)) 12:49:17: -> tcp_read(2165) using WSAEventSelect 12:49:17: -> tcp_read(2165) immediate timeout previous thread count -1/0 12:49:17: S -> debug: 18 12:49:17: S -> debug: 19 12:49:17: -> tcp_read(2111) using WSAEventSelect 12:49:17: -> tcp_read(2111) immediate timeout previous thread count -1/0 12:49:17: S -> debug: 20 12:49:17: S -> do_recursion: begin 12:49:17: -> tcp_read(2047) using WSAEventSelect 12:49:17: -> tcp_read(2047) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: Check the value in CVSADM_ROOT 12:49:17: S -> do_recursion: Fill in repository with the current repository 12:49:17: -> tcp_read(1906) using WSAEventSelect 12:49:17: -> tcp_read(1906) immediate timeout previous thread count -1/0 12:49:17: S -> (repository && (server_active || !current_parsed_root->isremote)) 12:49:17: -> tcp_read(1823) using WSAEventSelect 12:49:17: -> tcp_read(1823) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> lookup_module2() 12:49:17: -> tcp_read(1728) using WSAEventSelect 12:49:17: -> tcp_read(1728) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(1623) using WSAEventSelect 12:49:17: -> tcp_read(1623) immediate timeout previous thread count -1/0 12:49:17: S -> Loading modules2 from C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/modules2 12:49:17: -> tcp_read(1539) using WSAEventSelect 12:49:17: -> tcp_read(1539) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: -> tcp_read(1472) using WSAEventSelect 12:49:17: -> tcp_read(1472) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(,1) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(1370) using WSAEventSelect 12:49:17: -> tcp_read(1370) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(1187) using WSAEventSelect 12:49:17: -> tcp_read(1187) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo" 12:49:17: -> tcp_read(1047) using WSAEventSelect 12:49:17: -> tcp_read(1047) immediate timeout previous thread count -1/0 12:49:17: S -> Repository = C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: S -> Mapped repository = C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: -> tcp_read(924) using WSAEventSelect 12:49:17: -> tcp_read(924) immediate timeout previous thread count -1/0 12:49:17: S -> * The filesdoneproc needs to be called for each directory where files 12:49:17: S -> * processed, or each directory that is processed by a call where no 12:49:17: -> tcp_read(752) using WSAEventSelect 12:49:17: -> tcp_read(752) immediate timeout previous thread count -1/0 12:49:17: S -> * directories were passed in. In fact, the only time we don't want to 12:49:17: S -> * call back the filesdoneproc is when we are processing directories that 12:49:17: S -> * were passed in on the command line (or in the special case of `.' when 12:49:17: -> tcp_read(484) using WSAEventSelect 12:49:17: -> tcp_read(484) immediate timeout previous thread count -1/0 12:49:17: S -> * we were called with no args 12:49:17: S -> do_recursion: dirlist == NULL && filelist == NULL 12:49:17: S -> do_recursion: dodoneproc=1 12:49:17: -> tcp_read(326) using WSAEventSelect 12:49:17: -> tcp_read(326) immediate timeout previous thread count -1/0 12:49:17: S -> * If filelist or dirlist is already set, we don't look again. Otherwise, 12:49:17: S -> * find the files and directories 12:49:17: -> tcp_read(186) using WSAEventSelect 12:49:17: -> tcp_read(186) immediate timeout previous thread count -1/0 12:49:17: S -> * both lists were NULL, so start from scratch 12:49:17: S -> * In the !(which & W_LOCAL) case, we filled in repository 12:49:17: -> tcp_read(47) using WSAEventSelect 12:49:17: -> tcp_read(47) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> earlier in the function. In the (which & W_LOCAL) case, 12:49:17: S -> the Find_Names function is going to look through the 12:49:17: S -> Entries file. If we do not have a repository, that 12:49:17: -> tcp_read(4948) using WSAEventSelect 12:49:17: -> tcp_read(4948) immediate timeout previous thread count -1/0 12:49:17: S -> does not make sense, so we insist upon having a 12:49:17: S -> repository at this point. Name_Repository will give a 12:49:17: -> tcp_read(4807) using WSAEventSelect 12:49:17: -> tcp_read(4807) immediate timeout previous thread count -1/0 12:49:17: S -> reasonable error message. 12:49:17: S -> do_recursion: repository != NULL 12:49:17: -> tcp_read(4711) using WSAEventSelect 12:49:17: -> tcp_read(4711) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: mapped_repository != NULL 12:49:17: S -> do_recursion: find the files and fill in entries if appropriate 12:49:17: -> tcp_read(4573) using WSAEventSelect 12:49:17: -> tcp_read(4573) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: process_this_directory, call Find_Names() 12:49:17: S -> lookup_module2() 12:49:17: S -> lookup_module2() after rename? 12:49:17: -> tcp_read(4418) using WSAEventSelect 12:49:17: -> tcp_read(4418) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(4361) using WSAEventSelect 12:49:17: -> tcp_read(4361) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: -> tcp_read(4294) using WSAEventSelect 12:49:17: -> tcp_read(4294) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(,2) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(4192) using WSAEventSelect 12:49:17: -> tcp_read(4192) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(4009) using WSAEventSelect 12:49:17: -> tcp_read(4009) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: -> tcp_read(3952) using WSAEventSelect 12:49:17: -> tcp_read(3952) immediate timeout previous thread count -1/0 12:49:17: S -> find_virtual_rcs(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> lookup_module2() 12:49:17: -> tcp_read(3855) using WSAEventSelect 12:49:17: -> tcp_read(3855) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: -> tcp_read(3683) using WSAEventSelect 12:49:17: -> tcp_read(3683) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(,3) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(3581) using WSAEventSelect 12:49:17: -> tcp_read(3581) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(3398) using WSAEventSelect 12:49:17: -> tcp_read(3398) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: S -> find_rename_rcs(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(3279) using WSAEventSelect 12:49:17: -> tcp_read(3279) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: Find_Names got a filelist 12:49:17: S -> lookup_module2() 12:49:17: -> tcp_read(3188) using WSAEventSelect 12:49:17: -> tcp_read(3188) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(3083) using WSAEventSelect 12:49:17: -> tcp_read(3083) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: -> tcp_read(3016) using WSAEventSelect 12:49:17: -> tcp_read(3016) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(,4) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(2914) using WSAEventSelect 12:49:17: -> tcp_read(2914) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(2731) using WSAEventSelect 12:49:17: -> tcp_read(2731) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: -> tcp_read(2674) using WSAEventSelect 12:49:17: -> tcp_read(2674) immediate timeout previous thread count -1/0 12:49:17: S -> find_virtual_dirs(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> find_rename_dirs(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(2547) using WSAEventSelect 12:49:17: -> tcp_read(2547) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: process the files (if any) 12:49:17: S -> do_recursion: read lock it if necessary 12:49:17: -> tcp_read(2430) using WSAEventSelect 12:49:17: -> tcp_read(2430) immediate timeout previous thread count -1/0 12:49:17: S -> * For the server, we handle notifications in a completely different 12:49:17: -> tcp_read(2345) using WSAEventSelect 12:49:17: -> tcp_read(2345) immediate timeout previous thread count -1/0 12:49:17: S -> place (server_notify). For local, we can't do them here--we don't 12:49:17: S -> have writelocks in place, and there is no way to get writelocks here 12:49:17: -> tcp_read(2171) using WSAEventSelect 12:49:17: -> tcp_read(2171) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: process the files by calling walklist() 12:49:17: S -> do_recursion: unlock it by calling Lock_Cleanup_Directory() 12:49:17: -> tcp_read(2023) using WSAEventSelect 12:49:17: -> tcp_read(2023) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: clean up by calling dellist(filelist) 12:49:17: S -> do_recursion: call-back files done proc (if any) 12:49:17: -> tcp_read(1887) using WSAEventSelect 12:49:17: -> tcp_read(1887) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: no: don't call-back files done proc (process_this_directorydodoneprocframe->filesdoneproc == NULL) 12:49:17: S -> do_recursion: at skip_directory 12:49:17: -> tcp_read(1706) using WSAEventSelect 12:49:17: -> tcp_read(1706) immediate timeout previous thread count -1/0 12:49:17: S -> (repository && (server_active || !current_parsed_root->isremote)) 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: S -> do_recursion: process the directories (if necessary) 12:49:17: -> tcp_read(1485) using WSAEventSelect 12:49:17: -> tcp_read(1485) immediate timeout previous thread count -1/0 12:49:17: S -> do_recursion: really process the directories (if necessary) 12:49:17: S -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent) 12:49:17: -> tcp_read(1316) using WSAEventSelect 12:49:17: -> tcp_read(1316) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: -> tcp_read(1242) using WSAEventSelect 12:49:17: -> tcp_read(1242) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: -> tcp_read(1086) using WSAEventSelect 12:49:17: -> tcp_read(1086) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after all that now update_dir=CVSROOT 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: -> tcp_read(948) using WSAEventSelect 12:49:17: -> tcp_read(948) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT) 12:49:17: S -> lookup_module2() 12:49:17: -> tcp_read(845) using WSAEventSelect 12:49:17: -> tcp_read(845) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(CVSROOT) after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(733) using WSAEventSelect 12:49:17: -> tcp_read(733) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: -> tcp_read(666) using WSAEventSelect 12:49:17: -> tcp_read(666) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(CVSROOT,5) 12:49:17: S -> _lookup_module2 !p lookup_repository_module(CVSROOT) 12:49:17: -> tcp_read(553) using WSAEventSelect 12:49:17: -> tcp_read(553) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_repository_module(CVSROOT) 12:49:17: S -> modules2_struct_sort(CVSROOT,testfilt) 12:49:17: -> tcp_read(446) using WSAEventSelect 12:49:17: -> tcp_read(446) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,CVSROOT) 12:49:17: -> tcp_read(291) using WSAEventSelect 12:49:17: -> tcp_read(291) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: -> tcp_read(153) using WSAEventSelect 12:49:17: -> tcp_read(153) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT" 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: -> tcp_read(4932) using WSAEventSelect 12:49:17: -> tcp_read(4932) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(4839) using WSAEventSelect 12:49:17: -> tcp_read(4839) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: -> tcp_read(4790) using WSAEventSelect 12:49:17: -> tcp_read(4790) immediate timeout previous thread count -1/0 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT,CVSROOT,(null) 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT,(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT) 12:49:17: -> tcp_read(4500) using WSAEventSelect 12:49:17: -> tcp_read(4500) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT) 12:49:17: -> tcp_read(4350) using WSAEventSelect 12:49:17: -> tcp_read(4350) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT)=]35+[sizeof(CVS/fileattr.xml)=]17+20=72 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: -> tcp_read(4174) using WSAEventSelect 12:49:17: -> tcp_read(4174) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(4092) using WSAEventSelect 12:49:17: -> tcp_read(4092) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/CVS/fileattr.xml") 12:49:17: -> tcp_read(3990) using WSAEventSelect 12:49:17: -> tcp_read(3990) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() ReadXmlFile() 12:49:17: -> tcp_read(3943) using WSAEventSelect 12:49:17: -> tcp_read(3943) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() close 12:49:17: -> tcp_read(3904) using WSAEventSelect 12:49:17: -> tcp_read(3904) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xfree fname 12:49:17: S -> fileattr_read() OK 12:49:17: -> tcp_read(3823) using WSAEventSelect 12:49:17: -> tcp_read(3823) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: -> tcp_read(3747) using WSAEventSelect 12:49:17: -> tcp_read(3747) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() last_component=CVSROOT 12:49:17: -> tcp_read(3522) using WSAEventSelect 12:49:17: -> tcp_read(3522) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(3294) using WSAEventSelect 12:49:17: -> tcp_read(3294) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() return 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT 12:49:17: -> tcp_read(3097) using WSAEventSelect 12:49:17: -> tcp_read(3097) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> verify_valid_name(Glen Starrett) 12:49:17: S -> Find all the groups the user is in and add them to the list of valid names. 12:49:17: -> tcp_read(2908) using WSAEventSelect 12:49:17: -> tcp_read(2908) immediate timeout previous thread count -1/0 12:49:17: S -> Open the admin group file CVSROOT/group 12:49:17: -> tcp_read(2851) using WSAEventSelect 12:49:17: -> tcp_read(2851) immediate timeout previous thread count -1/0 12:49:17: S -> Finished reading the admin group file CVSROOT/group 12:49:17: S -> add_valid_group(admin) 12:49:17: -> tcp_read(2742) using WSAEventSelect 12:49:17: -> tcp_read(2742) immediate timeout previous thread count -1/0 12:49:17: S -> Get the groups for windows 12:49:17: -> tcp_read(2698) using WSAEventSelect 12:49:17: -> tcp_read(2698) immediate timeout previous thread count -1/0 12:49:17: S -> Checking local access token for groups 12:49:17: -> tcp_read(2642) using WSAEventSelect 12:49:17: -> tcp_read(2642) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(None) 12:49:17: -> tcp_read(2603) using WSAEventSelect 12:49:17: -> tcp_read(2603) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(Everyone) 12:49:17: -> tcp_read(2560) using WSAEventSelect 12:49:17: -> tcp_read(2560) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(HomeUsers) 12:49:17: -> tcp_read(2516) using WSAEventSelect 12:49:17: -> tcp_read(2516) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(Remote Desktop Users) 12:49:17: -> tcp_read(2461) using WSAEventSelect 12:49:17: -> tcp_read(2461) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(Users) 12:49:17: -> tcp_read(2421) using WSAEventSelect 12:49:17: -> tcp_read(2421) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(INTERACTIVE) 12:49:17: -> tcp_read(2375) using WSAEventSelect 12:49:17: -> tcp_read(2375) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(CONSOLE LOGON) 12:49:17: -> tcp_read(2327) using WSAEventSelect 12:49:17: -> tcp_read(2327) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(Authenticated Users) 12:49:17: -> tcp_read(2273) using WSAEventSelect 12:49:17: -> tcp_read(2273) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(This Organization) 12:49:17: -> tcp_read(2221) using WSAEventSelect 12:49:17: -> tcp_read(2221) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(This Organization) 12:49:17: -> tcp_read(2169) using WSAEventSelect 12:49:17: -> tcp_read(2169) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(LOCAL) 12:49:17: -> tcp_read(2129) using WSAEventSelect 12:49:17: -> tcp_read(2129) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(NTLM Authentication) 12:49:17: -> tcp_read(2075) using WSAEventSelect 12:49:17: -> tcp_read(2075) immediate timeout previous thread count -1/0 12:49:17: S -> add_valid_group(Medium Mandatory Level) 12:49:17: -> tcp_read(2018) using WSAEventSelect 12:49:17: -> tcp_read(2018) immediate timeout previous thread count -1/0 12:49:17: S -> Got the groups for windows 12:49:17: S -> verify_valid_name(admin) 12:49:17: -> tcp_read(1932) using WSAEventSelect 12:49:17: -> tcp_read(1932) immediate timeout previous thread count -1/0 12:49:17: S -> matched ACL user=admin, branch=_default_, merge=(no acl_merge) 12:49:17: S -> calculated ACL priority is 6 12:49:17: S -> new max priority is 6 12:49:17: -> tcp_read(1767) using WSAEventSelect 12:49:17: -> tcp_read(1767) immediate timeout previous thread count -1/0 12:49:17: S -> matched ACL user=_default_, branch=_default_, merge=(no acl_merge) 12:49:17: S -> calculated ACL priority is 0 12:49:17: -> tcp_read(1637) using WSAEventSelect 12:49:17: -> tcp_read(1637) immediate timeout previous thread count -1/0 12:49:17: S -> matched ACL user=_default_, branch=_default_, merge=(no acl_merge) 12:49:17: S -> calculated ACL priority is 0 12:49:17: -> tcp_read(1507) using WSAEventSelect 12:49:17: -> tcp_read(1507) immediate timeout previous thread count -1/0 12:49:17: S -> user_state = 0, group_state = 1 12:49:17: S -> Match found. user_state = 0, group_state = 1 12:49:17: -> tcp_read(1396) using WSAEventSelect 12:49:17: -> tcp_read(1396) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(1339) using WSAEventSelect 12:49:17: -> tcp_read(1339) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: -> tcp_read(1172) using WSAEventSelect 12:49:17: -> tcp_read(1172) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(CVSROOT,,,0,) 12:49:17: -> tcp_read(1027) using WSAEventSelect 12:49:17: -> tcp_read(1027) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT,CVSROOT,(null),(null),0,(null),0) 12:49:17: S -> current_directory is already set 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: -> tcp_read(798) using WSAEventSelect 12:49:17: -> tcp_read(798) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT) 12:49:17: -> tcp_read(624) using WSAEventSelect 12:49:17: -> tcp_read(624) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/.directory_history,v) 12:49:17: -> tcp_read(537) using WSAEventSelect 12:49:17: -> tcp_read(537) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/.directory_history,v) failed to open file 1 times 12:49:17: -> tcp_read(422) using WSAEventSelect 12:49:17: -> tcp_read(422) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/.directory_history,v) file does not exist, so return 0 12:49:17: S -> rcsbuf_open failed 12:49:17: -> tcp_read(266) using WSAEventSelect 12:49:17: -> tcp_read(266) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/Attic/.directory_history,v) 12:49:17: -> tcp_read(173) using WSAEventSelect 12:49:17: -> tcp_read(173) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/Attic/.directory_history,v) failed to open file 1 times 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> No mapping file in this directory. 12:49:17: -> tcp_read(4958) using WSAEventSelect 12:49:17: -> tcp_read(4958) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copy the tag and date. 12:49:17: S -> open_directory copied the tag and date. 12:49:17: -> tcp_read(4846) using WSAEventSelect 12:49:17: -> tcp_read(4846) immediate timeout previous thread count -1/0 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: -> tcp_read(4790) using WSAEventSelect 12:49:17: -> tcp_read(4790) immediate timeout previous thread count -1/0 12:49:17: S -> Rename script file does not exist 12:49:17: -> tcp_read(4739) using WSAEventSelect 12:49:17: -> tcp_read(4739) immediate timeout previous thread count -1/0 12:49:17: S -> directory opened 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/CVSROOT) 12:49:17: -> tcp_read(4633) using WSAEventSelect 12:49:17: -> tcp_read(4633) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. CVSROOT 12:49:17: -> tcp_read(4466) using WSAEventSelect 12:49:17: -> tcp_read(4466) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL. 12:49:17: S -> more efficient - close directory in do_dir_proc 12:49:17: S -> close_directory() 12:49:17: S -> close_directory() directory_stack_size decreased by one to 1 12:49:17: -> tcp_read(4190) using WSAEventSelect 12:49:17: -> tcp_read(4190) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: S -> do_dir_proc() 12:49:17: -> tcp_read(4092) using WSAEventSelect 12:49:17: -> tcp_read(4092) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: -> tcp_read(3968) using WSAEventSelect 12:49:17: -> tcp_read(3968) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: S -> do_dir_proc() after all that now update_dir=JazzBand 12:49:17: -> tcp_read(3823) using WSAEventSelect 12:49:17: -> tcp_read(3823) immediate timeout previous thread count -1/0 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand) 12:49:17: -> tcp_read(3684) using WSAEventSelect 12:49:17: -> tcp_read(3684) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() 12:49:17: S -> lookup_module2(JazzBand) after rename? 12:49:17: -> tcp_read(3594) using WSAEventSelect 12:49:17: -> tcp_read(3594) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(3537) using WSAEventSelect 12:49:17: -> tcp_read(3537) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: S -> lookup_module2(JazzBand,6) 12:49:17: -> tcp_read(3426) using WSAEventSelect 12:49:17: -> tcp_read(3426) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !p lookup_repository_module(JazzBand) 12:49:17: S -> lookup_repository_module(JazzBand) 12:49:17: -> tcp_read(3303) using WSAEventSelect 12:49:17: -> tcp_read(3303) immediate timeout previous thread count -1/0 12:49:17: S -> modules2_struct_sort(JazzBand,testfilt) 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: -> tcp_read(3159) using WSAEventSelect 12:49:17: -> tcp_read(3159) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,JazzBand) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(3024) using WSAEventSelect 12:49:17: -> tcp_read(3024) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(2841) using WSAEventSelect 12:49:17: -> tcp_read(2841) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/JazzBand" 12:49:17: -> tcp_read(2692) using WSAEventSelect 12:49:17: -> tcp_read(2692) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(2517) using WSAEventSelect 12:49:17: -> tcp_read(2517) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: -> tcp_read(2468) using WSAEventSelect 12:49:17: -> tcp_read(2468) immediate timeout previous thread count -1/0 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/JazzBand,JazzBand,(null) 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand,(null),read,(null),(null)) 12:49:17: -> tcp_read(2291) using WSAEventSelect 12:49:17: -> tcp_read(2291) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand) 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand) 12:49:17: -> tcp_read(2022) using WSAEventSelect 12:49:17: -> tcp_read(2022) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand)=]36+[sizeof(CVS/fileattr.xml)=]17+20=73 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: -> tcp_read(1845) using WSAEventSelect 12:49:17: -> tcp_read(1845) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(1763) using WSAEventSelect 12:49:17: -> tcp_read(1763) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/CVS/fileattr.xml") 12:49:17: -> tcp_read(1660) using WSAEventSelect 12:49:17: -> tcp_read(1660) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() ReadXmlFile() 12:49:17: -> tcp_read(1613) using WSAEventSelect 12:49:17: -> tcp_read(1613) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() close 12:49:17: -> tcp_read(1574) using WSAEventSelect 12:49:17: -> tcp_read(1574) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xfree fname 12:49:17: S -> fileattr_read() OK 12:49:17: -> tcp_read(1493) using WSAEventSelect 12:49:17: -> tcp_read(1493) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/JazzBand",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(1261) using WSAEventSelect 12:49:17: -> tcp_read(1261) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() last_component=JazzBand 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(1043) using WSAEventSelect 12:49:17: -> tcp_read(1043) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> cache_directory_permissions() return 12:49:17: -> tcp_read(908) using WSAEventSelect 12:49:17: -> tcp_read(908) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/JazzBand 12:49:17: -> tcp_read(764) using WSAEventSelect 12:49:17: -> tcp_read(764) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(669) using WSAEventSelect 12:49:17: -> tcp_read(669) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: -> tcp_read(560) using WSAEventSelect 12:49:17: -> tcp_read(560) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(465) using WSAEventSelect 12:49:17: -> tcp_read(465) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: -> tcp_read(363) using WSAEventSelect 12:49:17: -> tcp_read(363) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() return 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(268) using WSAEventSelect 12:49:17: -> tcp_read(268) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: -> tcp_read(33) using WSAEventSelect 12:49:17: -> tcp_read(33) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag_Dir(JazzBand,,,0,) 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand,JazzBand,(null),(null),0,(null),0) 12:49:17: S -> current_directory is already set 12:49:17: -> tcp_read(4921) using WSAEventSelect 12:49:17: -> tcp_read(4921) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: -> tcp_read(4844) using WSAEventSelect 12:49:17: -> tcp_read(4844) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/JazzBand) 12:49:17: -> tcp_read(4669) using WSAEventSelect 12:49:17: -> tcp_read(4669) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/.directory_history,v) 12:49:17: -> tcp_read(4581) using WSAEventSelect 12:49:17: -> tcp_read(4581) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/.directory_history,v) failed to open file 1 times 12:49:17: -> tcp_read(4465) using WSAEventSelect 12:49:17: -> tcp_read(4465) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/.directory_history,v) file does not exist, so return 0 12:49:17: S -> rcsbuf_open failed 12:49:17: -> tcp_read(4308) using WSAEventSelect 12:49:17: -> tcp_read(4308) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/Attic/.directory_history,v) 12:49:17: -> tcp_read(4214) using WSAEventSelect 12:49:17: -> tcp_read(4214) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/Attic/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(3965) using WSAEventSelect 12:49:17: -> tcp_read(3965) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> No mapping file in this directory. 12:49:17: S -> open_directory copy the tag and date. 12:49:17: -> tcp_read(3822) using WSAEventSelect 12:49:17: -> tcp_read(3822) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copied the tag and date. 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: -> tcp_read(3709) using WSAEventSelect 12:49:17: -> tcp_read(3709) immediate timeout previous thread count -1/0 12:49:17: S -> Rename script file does not exist 12:49:17: -> tcp_read(3658) using WSAEventSelect 12:49:17: -> tcp_read(3658) immediate timeout previous thread count -1/0 12:49:17: S -> directory opened 12:49:17: -> tcp_read(3624) using WSAEventSelect 12:49:17: -> tcp_read(3624) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/JazzBand) 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: -> tcp_read(3483) using WSAEventSelect 12:49:17: -> tcp_read(3483) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. JazzBand 12:49:17: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL. 12:49:17: -> tcp_read(3286) using WSAEventSelect 12:49:17: -> tcp_read(3286) immediate timeout previous thread count -1/0 12:49:17: S -> more efficient - close directory in do_dir_proc 12:49:17: S -> close_directory() 12:49:17: S -> close_directory() directory_stack_size decreased by one to 1 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: -> tcp_read(3040) using WSAEventSelect 12:49:17: -> tcp_read(3040) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: -> tcp_read(2885) using WSAEventSelect 12:49:17: -> tcp_read(2885) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: S -> do_dir_proc() after all that now update_dir=MHtest2 12:49:17: -> tcp_read(2741) using WSAEventSelect 12:49:17: -> tcp_read(2741) immediate timeout previous thread count -1/0 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2) 12:49:17: -> tcp_read(2603) using WSAEventSelect 12:49:17: -> tcp_read(2603) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() 12:49:17: S -> lookup_module2(MHtest2) after rename? 12:49:17: -> tcp_read(2514) using WSAEventSelect 12:49:17: -> tcp_read(2514) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: S -> lookup_module2(MHtest2,7) 12:49:17: -> tcp_read(2347) using WSAEventSelect 12:49:17: -> tcp_read(2347) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !p lookup_repository_module(MHtest2) 12:49:17: -> tcp_read(2277) using WSAEventSelect 12:49:17: -> tcp_read(2277) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_repository_module(MHtest2) 12:49:17: S -> modules2_struct_sort(MHtest2,testfilt) 12:49:17: -> tcp_read(2170) using WSAEventSelect 12:49:17: -> tcp_read(2170) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,MHtest2) 12:49:17: -> tcp_read(2015) using WSAEventSelect 12:49:17: -> tcp_read(2015) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: -> tcp_read(1877) using WSAEventSelect 12:49:17: -> tcp_read(1877) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: -> tcp_read(1709) using WSAEventSelect 12:49:17: -> tcp_read(1709) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/MHtest2" 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: -> tcp_read(1536) using WSAEventSelect 12:49:17: -> tcp_read(1536) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(1443) using WSAEventSelect 12:49:17: -> tcp_read(1443) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: -> tcp_read(1394) using WSAEventSelect 12:49:17: -> tcp_read(1394) immediate timeout previous thread count -1/0 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/MHtest2,MHtest2,(null) 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2,(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: -> tcp_read(1171) using WSAEventSelect 12:49:17: -> tcp_read(1171) immediate timeout previous thread count -1/0 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2) 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2) 12:49:17: -> tcp_read(954) using WSAEventSelect 12:49:17: -> tcp_read(954) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2)=]35+[sizeof(CVS/fileattr.xml)=]17+20=72 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(696) using WSAEventSelect 12:49:17: -> tcp_read(696) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/CVS/fileattr.xml") 12:49:17: -> tcp_read(594) using WSAEventSelect 12:49:17: -> tcp_read(594) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() ReadXmlFile() 12:49:17: -> tcp_read(547) using WSAEventSelect 12:49:17: -> tcp_read(547) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() close 12:49:17: -> tcp_read(508) using WSAEventSelect 12:49:17: -> tcp_read(508) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xfree fname 12:49:17: S -> fileattr_read() OK 12:49:17: -> tcp_read(427) using WSAEventSelect 12:49:17: -> tcp_read(427) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/MHtest2",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(196) using WSAEventSelect 12:49:17: -> tcp_read(196) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() last_component=MHtest2 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> cache_directory_permissions() return 12:49:17: -> tcp_read(4964) using WSAEventSelect 12:49:17: -> tcp_read(4964) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/MHtest2 12:49:17: -> tcp_read(4821) using WSAEventSelect 12:49:17: -> tcp_read(4821) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(4726) using WSAEventSelect 12:49:17: -> tcp_read(4726) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: -> tcp_read(4617) using WSAEventSelect 12:49:17: -> tcp_read(4617) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: S -> no match at this level 12:49:17: -> tcp_read(4482) using WSAEventSelect 12:49:17: -> tcp_read(4482) immediate timeout previous thread count -1/0 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: S -> verify_perm() return 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(4325) using WSAEventSelect 12:49:17: -> tcp_read(4325) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: -> tcp_read(4268) using WSAEventSelect 12:49:17: -> tcp_read(4268) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(MHtest2,,,0,) 12:49:17: -> tcp_read(4013) using WSAEventSelect 12:49:17: -> tcp_read(4013) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2,MHtest2,(null),(null),0,(null),0) 12:49:17: S -> current_directory is already set 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: -> tcp_read(3784) using WSAEventSelect 12:49:17: -> tcp_read(3784) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/MHtest2) 12:49:17: -> tcp_read(3610) using WSAEventSelect 12:49:17: -> tcp_read(3610) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/.directory_history,v) 12:49:17: -> tcp_read(3523) using WSAEventSelect 12:49:17: -> tcp_read(3523) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(3288) using WSAEventSelect 12:49:17: -> tcp_read(3288) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/Attic/.directory_history,v) 12:49:17: -> tcp_read(3159) using WSAEventSelect 12:49:17: -> tcp_read(3159) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/Attic/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(2912) using WSAEventSelect 12:49:17: -> tcp_read(2912) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> No mapping file in this directory. 12:49:17: S -> open_directory copy the tag and date. 12:49:17: -> tcp_read(2769) using WSAEventSelect 12:49:17: -> tcp_read(2769) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copied the tag and date. 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: -> tcp_read(2656) using WSAEventSelect 12:49:17: -> tcp_read(2656) immediate timeout previous thread count -1/0 12:49:17: S -> Rename script file does not exist 12:49:17: -> tcp_read(2605) using WSAEventSelect 12:49:17: -> tcp_read(2605) immediate timeout previous thread count -1/0 12:49:17: S -> directory opened 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/MHtest2) 12:49:17: -> tcp_read(2499) using WSAEventSelect 12:49:17: -> tcp_read(2499) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. MHtest2 12:49:17: -> tcp_read(2332) using WSAEventSelect 12:49:17: -> tcp_read(2332) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL. 12:49:17: S -> more efficient - close directory in do_dir_proc 12:49:17: S -> close_directory() 12:49:17: S -> close_directory() directory_stack_size decreased by one to 1 12:49:17: -> tcp_read(2056) using WSAEventSelect 12:49:17: -> tcp_read(2056) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: -> tcp_read(1989) using WSAEventSelect 12:49:17: -> tcp_read(1989) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: -> tcp_read(1834) using WSAEventSelect 12:49:17: -> tcp_read(1834) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: S -> do_dir_proc() after all that now update_dir=deeptest 12:49:17: -> tcp_read(1689) using WSAEventSelect 12:49:17: -> tcp_read(1689) immediate timeout previous thread count -1/0 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/deeptest) 12:49:17: S -> lookup_module2() 12:49:17: -> tcp_read(1516) using WSAEventSelect 12:49:17: -> tcp_read(1516) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(deeptest) after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(1403) using WSAEventSelect 12:49:17: -> tcp_read(1403) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: S -> lookup_module2(deeptest,8) 12:49:17: S -> _lookup_module2 !p lookup_repository_module(deeptest) 12:49:17: -> tcp_read(1221) using WSAEventSelect 12:49:17: -> tcp_read(1221) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_repository_module(deeptest) 12:49:17: S -> modules2_struct_sort(deeptest,testfilt) 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: -> tcp_read(1025) using WSAEventSelect 12:49:17: -> tcp_read(1025) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,deeptest) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(890) using WSAEventSelect 12:49:17: -> tcp_read(890) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(707) using WSAEventSelect 12:49:17: -> tcp_read(707) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/deeptest" 12:49:17: -> tcp_read(558) using WSAEventSelect 12:49:17: -> tcp_read(558) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: S -> ParseTag() 12:49:17: -> tcp_read(448) using WSAEventSelect 12:49:17: -> tcp_read(448) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(383) using WSAEventSelect 12:49:17: -> tcp_read(383) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/deeptest,deeptest,(null) 12:49:17: -> tcp_read(250) using WSAEventSelect 12:49:17: -> tcp_read(250) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/deeptest,(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: -> tcp_read(108) using WSAEventSelect 12:49:17: -> tcp_read(108) immediate timeout previous thread count -1/0 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/deeptest) 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/deeptest) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/deeptest) 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/deeptest)=]36+[sizeof(CVS/fileattr.xml)=]17+20=73 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: -> tcp_read(4831) using WSAEventSelect 12:49:17: -> tcp_read(4831) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(4749) using WSAEventSelect 12:49:17: -> tcp_read(4749) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/deeptest/CVS/fileattr.xml") 12:49:17: -> tcp_read(4646) using WSAEventSelect 12:49:17: -> tcp_read(4646) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() ReadXmlFile() 12:49:17: -> tcp_read(4599) using WSAEventSelect 12:49:17: -> tcp_read(4599) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() close 12:49:17: -> tcp_read(4560) using WSAEventSelect 12:49:17: -> tcp_read(4560) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xfree fname 12:49:17: S -> fileattr_read() OK 12:49:17: -> tcp_read(4479) using WSAEventSelect 12:49:17: -> tcp_read(4479) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/deeptest",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(4247) using WSAEventSelect 12:49:17: -> tcp_read(4247) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() last_component=deeptest 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(4029) using WSAEventSelect 12:49:17: -> tcp_read(4029) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> cache_directory_permissions() return 12:49:17: -> tcp_read(3894) using WSAEventSelect 12:49:17: -> tcp_read(3894) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/deeptest 12:49:17: -> tcp_read(3750) using WSAEventSelect 12:49:17: -> tcp_read(3750) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(3655) using WSAEventSelect 12:49:17: -> tcp_read(3655) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: -> tcp_read(3546) using WSAEventSelect 12:49:17: -> tcp_read(3546) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(3451) using WSAEventSelect 12:49:17: -> tcp_read(3451) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: -> tcp_read(3349) using WSAEventSelect 12:49:17: -> tcp_read(3349) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() return 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(3254) using WSAEventSelect 12:49:17: -> tcp_read(3254) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: -> tcp_read(3087) using WSAEventSelect 12:49:17: -> tcp_read(3087) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(deeptest,,,0,) 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/deeptest,deeptest,(null),(null),0,(null),0) 12:49:17: -> tcp_read(2837) using WSAEventSelect 12:49:17: -> tcp_read(2837) immediate timeout previous thread count -1/0 12:49:17: S -> current_directory is already set 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: -> tcp_read(2710) using WSAEventSelect 12:49:17: -> tcp_read(2710) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/deeptest) 12:49:17: -> tcp_read(2535) using WSAEventSelect 12:49:17: -> tcp_read(2535) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/deeptest/.directory_history,v) 12:49:17: -> tcp_read(2447) using WSAEventSelect 12:49:17: -> tcp_read(2447) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/deeptest/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/deeptest/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(2210) using WSAEventSelect 12:49:17: -> tcp_read(2210) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/deeptest/Attic/.directory_history,v) 12:49:17: -> tcp_read(2080) using WSAEventSelect 12:49:17: -> tcp_read(2080) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/deeptest/Attic/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/deeptest/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(1831) using WSAEventSelect 12:49:17: -> tcp_read(1831) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> No mapping file in this directory. 12:49:17: -> tcp_read(1743) using WSAEventSelect 12:49:17: -> tcp_read(1743) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copy the tag and date. 12:49:17: S -> open_directory copied the tag and date. 12:49:17: -> tcp_read(1631) using WSAEventSelect 12:49:17: -> tcp_read(1631) immediate timeout previous thread count -1/0 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: S -> Rename script file does not exist 12:49:17: -> tcp_read(1524) using WSAEventSelect 12:49:17: -> tcp_read(1524) immediate timeout previous thread count -1/0 12:49:17: S -> directory opened 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/deeptest) 12:49:17: -> tcp_read(1417) using WSAEventSelect 12:49:17: -> tcp_read(1417) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. deeptest 12:49:17: -> tcp_read(1249) using WSAEventSelect 12:49:17: -> tcp_read(1249) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL. 12:49:17: S -> more efficient - close directory in do_dir_proc 12:49:17: S -> close_directory() 12:49:17: S -> close_directory() directory_stack_size decreased by one to 1 12:49:17: -> tcp_read(973) using WSAEventSelect 12:49:17: -> tcp_read(973) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: S -> do_dir_proc() 12:49:17: -> tcp_read(875) using WSAEventSelect 12:49:17: -> tcp_read(875) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: -> tcp_read(751) using WSAEventSelect 12:49:17: -> tcp_read(751) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: -> tcp_read(676) using WSAEventSelect 12:49:17: -> tcp_read(676) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after all that now update_dir=fubar 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: -> tcp_read(540) using WSAEventSelect 12:49:17: -> tcp_read(540) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/fubar) 12:49:17: S -> lookup_module2() 12:49:17: S -> lookup_module2(fubar) after rename? 12:49:17: -> tcp_read(386) using WSAEventSelect 12:49:17: -> tcp_read(386) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(329) using WSAEventSelect 12:49:17: -> tcp_read(329) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: S -> lookup_module2(fubar,9) 12:49:17: -> tcp_read(221) using WSAEventSelect 12:49:17: -> tcp_read(221) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !p lookup_repository_module(fubar) 12:49:17: S -> lookup_repository_module(fubar) 12:49:17: -> tcp_read(104) using WSAEventSelect 12:49:17: -> tcp_read(104) immediate timeout previous thread count -1/0 12:49:17: S -> modules2_struct_sort(fubar,testfilt) 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,fubar) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: -> tcp_read(4879) using WSAEventSelect 12:49:17: -> tcp_read(4879) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: -> tcp_read(4711) using WSAEventSelect 12:49:17: -> tcp_read(4711) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/fubar" 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: -> tcp_read(4540) using WSAEventSelect 12:49:17: -> tcp_read(4540) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(4447) using WSAEventSelect 12:49:17: -> tcp_read(4447) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/fubar,fubar,(null) 12:49:17: -> tcp_read(4320) using WSAEventSelect 12:49:17: -> tcp_read(4320) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/fubar,(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: -> tcp_read(4181) using WSAEventSelect 12:49:17: -> tcp_read(4181) immediate timeout previous thread count -1/0 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/fubar) 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/fubar) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/fubar) 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/fubar)=]33+[sizeof(CVS/fileattr.xml)=]17+20=70 12:49:17: -> tcp_read(3846) using WSAEventSelect 12:49:17: -> tcp_read(3846) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(3714) using WSAEventSelect 12:49:17: -> tcp_read(3714) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/fubar/CVS/fileattr.xml") 12:49:17: -> tcp_read(3614) using WSAEventSelect 12:49:17: -> tcp_read(3614) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() ReadXmlFile() 12:49:17: -> tcp_read(3567) using WSAEventSelect 12:49:17: -> tcp_read(3567) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() close 12:49:17: -> tcp_read(3528) using WSAEventSelect 12:49:17: -> tcp_read(3528) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xfree fname 12:49:17: S -> fileattr_read() OK 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: -> tcp_read(3371) using WSAEventSelect 12:49:17: -> tcp_read(3371) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/fubar",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() last_component=fubar 12:49:17: -> tcp_read(3150) using WSAEventSelect 12:49:17: -> tcp_read(3150) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: S -> cache_directory_permissions() return 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: -> tcp_read(2802) using WSAEventSelect 12:49:17: -> tcp_read(2802) immediate timeout previous thread count -1/0 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/fubar 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(2632) using WSAEventSelect 12:49:17: -> tcp_read(2632) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: -> tcp_read(2523) using WSAEventSelect 12:49:17: -> tcp_read(2523) immediate timeout previous thread count -1/0 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: S -> no match at this level 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: -> tcp_read(2326) using WSAEventSelect 12:49:17: -> tcp_read(2326) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() return 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(2231) using WSAEventSelect 12:49:17: -> tcp_read(2231) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: -> tcp_read(2064) using WSAEventSelect 12:49:17: -> tcp_read(2064) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(fubar,,,0,) 12:49:17: -> tcp_read(1921) using WSAEventSelect 12:49:17: -> tcp_read(1921) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/fubar,fubar,(null),(null),0,(null),0) 12:49:17: S -> current_directory is already set 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: -> tcp_read(1696) using WSAEventSelect 12:49:17: -> tcp_read(1696) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: -> tcp_read(1605) using WSAEventSelect 12:49:17: -> tcp_read(1605) immediate timeout previous thread count -1/0 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/fubar) 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/fubar/.directory_history,v) 12:49:17: -> tcp_read(1439) using WSAEventSelect 12:49:17: -> tcp_read(1439) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/fubar/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/fubar/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(1208) using WSAEventSelect 12:49:17: -> tcp_read(1208) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/fubar/Attic/.directory_history,v) 12:49:17: -> tcp_read(1081) using WSAEventSelect 12:49:17: -> tcp_read(1081) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/fubar/Attic/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/fubar/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(838) using WSAEventSelect 12:49:17: -> tcp_read(838) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> No mapping file in this directory. 12:49:17: S -> open_directory copy the tag and date. 12:49:17: -> tcp_read(695) using WSAEventSelect 12:49:17: -> tcp_read(695) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copied the tag and date. 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: -> tcp_read(582) using WSAEventSelect 12:49:17: -> tcp_read(582) immediate timeout previous thread count -1/0 12:49:17: S -> Rename script file does not exist 12:49:17: S -> directory opened 12:49:17: -> tcp_read(497) using WSAEventSelect 12:49:17: -> tcp_read(497) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/fubar) 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. 12:49:17: -> tcp_read(270) using WSAEventSelect 12:49:17: -> tcp_read(270) immediate timeout previous thread count -1/0 fubar 12:49:17: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL. 12:49:17: S -> more efficient - close directory in do_dir_proc 12:49:17: S -> close_directory() 12:49:17: -> tcp_read(65) using WSAEventSelect 12:49:17: -> tcp_read(65) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> close_directory() directory_stack_size decreased by one to 1 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: S -> do_dir_proc() 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: -> tcp_read(4809) using WSAEventSelect 12:49:17: -> tcp_read(4809) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after all that now update_dir=samp 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/samp) 12:49:17: -> tcp_read(4608) using WSAEventSelect 12:49:17: -> tcp_read(4608) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() 12:49:17: S -> lookup_module2(samp) after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(4465) using WSAEventSelect 12:49:17: -> tcp_read(4465) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: S -> lookup_module2(samp,10) 12:49:17: -> tcp_read(4357) using WSAEventSelect 12:49:17: -> tcp_read(4357) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !p lookup_repository_module(samp) 12:49:17: S -> lookup_repository_module(samp) 12:49:17: S -> modules2_struct_sort(samp,testfilt) 12:49:17: -> tcp_read(4189) using WSAEventSelect 12:49:17: -> tcp_read(4189) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,samp) 12:49:17: -> tcp_read(4037) using WSAEventSelect 12:49:17: -> tcp_read(4037) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: -> tcp_read(3788) using WSAEventSelect 12:49:17: -> tcp_read(3788) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() return ret=0 renamed=0 12:49:17: S -> map_repository - return(repository) "C:/PROGRA~3/MARCHH~1/myrepo/samp" 12:49:17: -> tcp_read(3643) using WSAEventSelect 12:49:17: -> tcp_read(3643) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(3468) using WSAEventSelect 12:49:17: -> tcp_read(3468) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/samp,samp,(null) 12:49:17: -> tcp_read(3343) using WSAEventSelect 12:49:17: -> tcp_read(3343) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/samp,(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: -> tcp_read(3205) using WSAEventSelect 12:49:17: -> tcp_read(3205) immediate timeout previous thread count -1/0 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/samp) 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/samp) 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/samp) 12:49:17: -> tcp_read(2997) using WSAEventSelect 12:49:17: -> tcp_read(2997) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/samp)=]32+[sizeof(CVS/fileattr.xml)=]17+20=69 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: -> tcp_read(2824) using WSAEventSelect 12:49:17: -> tcp_read(2824) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~3/MARCHH~1/myrepo/samp/CVS/fileattr.xml") 12:49:17: -> tcp_read(2643) using WSAEventSelect 12:49:17: -> tcp_read(2643) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() ReadXmlFile() 12:49:17: -> tcp_read(2596) using WSAEventSelect 12:49:17: -> tcp_read(2596) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() close 12:49:17: S -> fileattr_read() xfree fname 12:49:17: S -> fileattr_read() OK 12:49:17: -> tcp_read(2476) using WSAEventSelect 12:49:17: -> tcp_read(2476) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/samp",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: -> tcp_read(2248) using WSAEventSelect 12:49:17: -> tcp_read(2248) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() last_component=samp 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(1953) using WSAEventSelect 12:49:17: -> tcp_read(1953) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() return 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: -> tcp_read(1833) using WSAEventSelect 12:49:17: -> tcp_read(1833) immediate timeout previous thread count -1/0 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/samp 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: -> tcp_read(1713) using WSAEventSelect 12:49:17: -> tcp_read(1713) immediate timeout previous thread count -1/0 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: S -> no match at this level 12:49:17: -> tcp_read(1624) using WSAEventSelect 12:49:17: -> tcp_read(1624) immediate timeout previous thread count -1/0 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: -> tcp_read(1460) using WSAEventSelect 12:49:17: -> tcp_read(1460) immediate timeout previous thread count -1/0 12:49:17: S -> no match at this level 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: -> tcp_read(1358) using WSAEventSelect 12:49:17: -> tcp_read(1358) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm() return 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: -> tcp_read(1263) using WSAEventSelect 12:49:17: -> tcp_read(1263) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: -> tcp_read(1096) using WSAEventSelect 12:49:17: -> tcp_read(1096) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(samp,,,0,) 12:49:17: -> tcp_read(954) using WSAEventSelect 12:49:17: -> tcp_read(954) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/samp,samp,(null),(null),0,(null),0) 12:49:17: S -> current_directory is already set 12:49:17: -> tcp_read(808) using WSAEventSelect 12:49:17: -> tcp_read(808) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: -> tcp_read(640) using WSAEventSelect 12:49:17: -> tcp_read(640) immediate timeout previous thread count -1/0 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/samp) 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp/.directory_history,v) 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp/.directory_history,v) failed to open file 1 times 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp/.directory_history,v) file does not exist, so return 0 12:49:17: -> tcp_read(247) using WSAEventSelect 12:49:17: -> tcp_read(247) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp/Attic/.directory_history,v) 12:49:17: -> tcp_read(121) using WSAEventSelect 12:49:17: -> tcp_read(121) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp/Attic/.directory_history,v) failed to open file 1 times 12:49:17: -> tcp_read(3) using WSAEventSelect 12:49:17: -> tcp_read(3) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp/Attic/.directory_history,v) file does not exist, so return 0 12:49:17: S -> rcsbuf_open failed 12:49:17: S -> No mapping file in this directory. 12:49:17: S -> open_directory copy the tag and date. 12:49:17: -> tcp_read(4857) using WSAEventSelect 12:49:17: -> tcp_read(4857) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory copied the tag and date. 12:49:17: S -> Look for rename script file CVS/Rename 12:49:17: -> tcp_read(4744) using WSAEventSelect 12:49:17: -> tcp_read(4744) immediate timeout previous thread count -1/0 12:49:17: S -> Rename script file does not exist 12:49:17: -> tcp_read(4693) using WSAEventSelect 12:49:17: -> tcp_read(4693) immediate timeout previous thread count -1/0 12:49:17: S -> directory opened 12:49:17: S -> fileattr_startdir(C:/PROGRA~3/MARCHH~1/myrepo/samp) 12:49:17: S -> do_dir_proc() -- call-back dir entry proc (if any) 12:49:17: -> tcp_read(4522) using WSAEventSelect 12:49:17: -> tcp_read(4522) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. samp 12:49:17: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL. 12:49:17: S -> more efficient - close directory in do_dir_proc 12:49:17: -> tcp_read(4264) using WSAEventSelect 12:49:17: -> tcp_read(4264) immediate timeout previous thread count -1/0 12:49:17: S -> close_directory() 12:49:17: S -> close_directory() directory_stack_size decreased by one to 1 12:49:17: S -> fileattr_write() 12:49:17: S -> fileattr_free() 12:49:17: S -> do_dir_proc() 12:49:17: -> tcp_read(4052) using WSAEventSelect 12:49:17: -> tcp_read(4052) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() update_dir= 12:49:17: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 12:49:17: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead 12:49:17: -> tcp_read(3853) using WSAEventSelect 12:49:17: -> tcp_read(3853) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after all that now update_dir=testfilt 12:49:17: S -> (server_active || !current_parsed_root->isremote) 2 12:49:17: -> tcp_read(3714) using WSAEventSelect 12:49:17: -> tcp_read(3714) immediate timeout previous thread count -1/0 12:49:17: S -> map_repository(C:/PROGRA~3/MARCHH~1/myrepo/testfilt) 12:49:17: S -> lookup_module2() 12:49:17: -> tcp_read(3610) using WSAEventSelect 12:49:17: -> tcp_read(3610) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(testfilt) after rename? 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(3497) using WSAEventSelect 12:49:17: -> tcp_read(3497) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: S -> lookup_module2(testfilt,11) 12:49:17: -> tcp_read(3385) using WSAEventSelect 12:49:17: -> tcp_read(3385) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 !p lookup_repository_module(testfilt) 12:49:17: -> tcp_read(3314) using WSAEventSelect 12:49:17: -> tcp_read(3314) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_repository_module(testfilt) 12:49:17: S -> modules2_struct_sort(testfilt,testfilt) 12:49:17: -> tcp_read(3205) using WSAEventSelect 12:49:17: -> tcp_read(3205) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 lookup_repository_directory(,0,0) 12:49:17: S -> _lookup_module2 lookup_repository_directory results "",0,0 12:49:17: S -> lookup_module2() call to lookup_repository_directory() returned something 12:49:17: -> tcp_read(2971) using WSAEventSelect 12:49:17: -> tcp_read(2971) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 return at end 1 left,right(samp (^*doc$)),) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 1 12:49:17: S -> lookup_module2() found "samp (^*doc$))" 12:49:17: S -> lookup_module2() call _lookup_module2() 12:49:17: -> tcp_read(2714) using WSAEventSelect 12:49:17: -> tcp_read(2714) immediate timeout previous thread count -1/0 12:49:17: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 12:49:17: -> tcp_read(2647) using WSAEventSelect 12:49:17: -> tcp_read(2647) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2(samp (^*doc$)),11) 12:49:17: S -> _lookup_module2 !p lookup_repository_module(samp (^*doc$))) 12:49:17: -> tcp_read(2519) using WSAEventSelect 12:49:17: -> tcp_read(2519) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_repository_module(samp (^*doc$))) 12:49:17: S -> modules2_struct_sort(samp (^*doc$)),testfilt) 12:49:17: S -> lookup_module2() calls to lookup_repository_module() returned nothing 12:49:17: S -> _lookup_module2 !mod return 0 left,right(,samp (^*doc$))) 12:49:17: S -> lookup_module2() call _lookup_module2 returned 0 12:49:17: -> tcp_read(2170) using WSAEventSelect 12:49:17: -> tcp_read(2170) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() no recursion permitted so give up now 12:49:17: S -> lookup_module2() return 1 12:49:17: -> tcp_read(2055) using WSAEventSelect 12:49:17: -> tcp_read(2055) immediate timeout previous thread count -1/0 12:49:17: S -> lookup_module2() ret=1, current_directory!=NULL, current_directory->directory_mappings===NULL 12:49:17: S -> lookup_module2() return ret=1 renamed=0 12:49:17: S -> map_repository - lookup_module2 returned res==1 12:49:17: S -> map_repository - return(ret) "C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))" 12:49:17: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 12:49:17: -> tcp_read(1649) using WSAEventSelect 12:49:17: -> tcp_read(1649) immediate timeout previous thread count -1/0 12:49:17: S -> ParseTag() 12:49:17: S -> ParseTag_Dir((null),,,0,) 12:49:17: -> tcp_read(1556) using WSAEventSelect 12:49:17: -> tcp_read(1556) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- verify_access 12:49:17: S -> verify_access C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$)),testfilt,(null) 12:49:17: -> tcp_read(1417) using WSAEventSelect 12:49:17: -> tcp_read(1417) immediate timeout previous thread count -1/0 12:49:17: S -> verify_perm(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$)),(null),read,(null),(null)) 12:49:17: S -> verify_perm: verify_owner_dir() 12:49:17: -> tcp_read(1269) using WSAEventSelect 12:49:17: -> tcp_read(1269) immediate timeout previous thread count -1/0 12:49:17: S -> verify_owner(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))) 12:49:17: S -> cache_directory_permissions(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))) 12:49:17: -> tcp_read(1106) using WSAEventSelect 12:49:17: -> tcp_read(1106) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))) 12:49:17: S -> _fileattr_read() malloc [strlen(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$)))=]42+[sizeof(CVS/fileattr.xml)=]17+20=79 12:49:17: -> tcp_read(898) using WSAEventSelect 12:49:17: -> tcp_read(898) immediate timeout previous thread count -1/0 12:49:17: S -> fileattr_read() xmalloc fname OK 12:49:17: S -> _fileattr_read() allocated memory for fname - will now sprintf() 12:49:17: -> tcp_read(766) using WSAEventSelect 12:49:17: -> tcp_read(766) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no file "C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/CVS/fileattr.xml", so allocate ofname of 75 bytes. 12:49:17: S -> fileattr_read() xmalloc ofname OK 12:49:17: -> tcp_read(578) using WSAEventSelect 12:49:17: -> tcp_read(578) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/CVS/fileattr". 12:49:17: -> tcp_read(478) using WSAEventSelect 12:49:17: -> tcp_read(478) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))//.owner". 12:49:17: S -> _fileattr_read() no old "C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))//.perms". 12:49:17: -> tcp_read(288) using WSAEventSelect 12:49:17: -> tcp_read(288) immediate timeout previous thread count -1/0 12:49:17: S -> _fileattr_read() CXmlNode("fileattr") 12:49:17: S -> fileattr_read() xfree ofname and fname OK 12:49:17: -> tcp_read(174) using WSAEventSelect 12:49:17: -> tcp_read(174) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() - cache directory(fileattr,) 12:49:17: -> tcp_read(98) using WSAEventSelect 12:49:17: -> tcp_read(98) immediate timeout previous thread count -1/0 12:49:17: -> tcp_read(5120) using WSAEventSelect 12:49:17: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() last_component=samp (^*doc$)) 12:49:17: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~3/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~3/MARCHH~1/myrepo") 12:49:17: S -> cache_directory_permissions() free(C:/PROGRA~3/MARCHH~1/myrepo) 12:49:17: -> tcp_read(4751) using WSAEventSelect 12:49:17: -> tcp_read(4751) immediate timeout previous thread count -1/0 12:49:17: S -> cache_directory_permissions() return 12:49:17: S -> verify_perm() Read directory permission cache OK 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$)) 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: -> tcp_read(4501) using WSAEventSelect 12:49:17: -> tcp_read(4501) immediate timeout previous thread count -1/0 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: S -> no match at this level 12:49:17: -> tcp_read(4412) using WSAEventSelect 12:49:17: -> tcp_read(4412) immediate timeout previous thread count -1/0 12:49:17: S -> ACL lookup on directory C:/PROGRA~3/MARCHH~1/myrepo 12:49:17: S -> verify_acl(read,HEAD,(null)) 12:49:17: -> tcp_read(4297) using WSAEventSelect 12:49:17: -> tcp_read(4297) immediate timeout previous thread count -1/0 12:49:17: S -> user_state = 0, group_state = 0 12:49:17: S -> no match at this level 12:49:17: -> tcp_read(4208) using WSAEventSelect 12:49:17: -> tcp_read(4208) immediate timeout previous thread count -1/0 12:49:17: S -> Match found. user_state = 0, group_state = 0 12:49:17: S -> verify_perm() return 12:49:17: -> tcp_read(4108) using WSAEventSelect 12:49:17: -> tcp_read(4108) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 12:49:17: S -> do_dir_proc() -- process_this_directory 12:49:17: -> tcp_read(3994) using WSAEventSelect 12:49:17: -> tcp_read(3994) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 12:49:17: -> tcp_read(3884) using WSAEventSelect 12:49:17: -> tcp_read(3884) immediate timeout previous thread count -1/0 12:49:17: S -> do_dir_proc() after predirent call ParseTag_Dir() 12:49:17: S -> ParseTag_Dir(testfilt,,,0,) 12:49:17: -> tcp_read(3738) using WSAEventSelect 12:49:17: -> tcp_read(3738) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$)),testfilt,(null),(null),0,(null),0) 12:49:17: -> tcp_read(3628) using WSAEventSelect 12:49:17: -> tcp_read(3628) immediate timeout previous thread count -1/0 12:49:17: S -> current_directory is already set 12:49:17: S -> open_directory() directory_stack_size increased by one to 2 12:49:17: -> tcp_read(3501) using WSAEventSelect 12:49:17: -> tcp_read(3501) immediate timeout previous thread count -1/0 12:49:17: S -> open_directory, (!remote) try and find a mapping file in this directory . 12:49:17: S -> RCS_parse(.directory_history,C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))) 12:49:17: -> tcp_read(3320) using WSAEventSelect 12:49:17: -> tcp_read(3320) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) 12:49:17: -> tcp_read(3226) using WSAEventSelect 12:49:17: -> tcp_read(3226) immediate timeout previous thread count -1/0 12:49:17: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 1 times 12:49:17: -> tcp_read(3104) using WSAEventSelect 12:49:18: -> tcp_read(3104) immediate timeout previous thread count -1/0 12:49:18: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 2 times 12:49:18: -> tcp_read(2982) using WSAEventSelect 12:49:19: -> tcp_read(2982) immediate timeout previous thread count -1/0 12:49:19: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 3 times 12:49:19: -> tcp_read(2860) using WSAEventSelect 12:49:20: -> tcp_read(2860) immediate timeout previous thread count -1/0 12:49:20: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 4 times 12:49:20: -> tcp_read(2738) using WSAEventSelect 12:49:21: -> tcp_read(2738) immediate timeout previous thread count -1/0 12:49:21: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 5 times 12:49:21: -> tcp_read(2616) using WSAEventSelect 12:49:22: -> tcp_read(2616) immediate timeout previous thread count -1/0 12:49:22: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 6 times 12:49:22: -> tcp_read(2494) using WSAEventSelect 12:49:23: -> tcp_read(2494) immediate timeout previous thread count -1/0 12:49:23: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 7 times 12:49:23: -> tcp_read(2372) using WSAEventSelect 12:49:24: -> tcp_read(2372) immediate timeout previous thread count -1/0 12:49:24: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 8 times 12:49:24: -> tcp_read(2250) using WSAEventSelect 12:49:25: -> tcp_read(2250) immediate timeout previous thread count -1/0 12:49:25: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 9 times 12:49:25: -> tcp_read(2128) using WSAEventSelect 12:49:26: -> tcp_read(2128) immediate timeout previous thread count -1/0 12:49:26: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 10 times 12:49:26: -> tcp_read(2005) using WSAEventSelect 12:49:27: -> tcp_read(2005) immediate timeout previous thread count -1/0 12:49:27: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 11 times 12:49:27: -> tcp_read(1882) using WSAEventSelect 12:49:28: -> tcp_read(1882) immediate timeout previous thread count -1/0 12:49:28: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 12 times 12:49:28: -> tcp_read(1759) using WSAEventSelect 12:49:29: -> tcp_read(1759) immediate timeout previous thread count -1/0 12:49:29: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 13 times 12:49:29: -> tcp_read(1636) using WSAEventSelect 12:49:30: -> tcp_read(1636) immediate timeout previous thread count -1/0 12:49:30: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 14 times 12:49:30: -> tcp_read(1513) using WSAEventSelect 12:49:31: -> tcp_read(1513) immediate timeout previous thread count -1/0 12:49:31: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 15 times 12:49:31: -> tcp_read(1390) using WSAEventSelect 12:49:32: -> tcp_read(1390) immediate timeout previous thread count -1/0 12:49:32: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 16 times 12:49:32: -> tcp_read(1267) using WSAEventSelect 12:49:33: -> tcp_read(1267) immediate timeout previous thread count -1/0 12:49:33: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 17 times 12:49:33: -> tcp_read(1144) using WSAEventSelect 12:49:34: -> tcp_read(1144) immediate timeout previous thread count -1/0 12:49:34: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 18 times 12:49:34: -> tcp_read(1021) using WSAEventSelect 12:49:35: -> tcp_read(1021) immediate timeout previous thread count -1/0 12:49:35: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 19 times 12:49:35: -> tcp_read(898) using WSAEventSelect 12:49:36: -> tcp_read(898) immediate timeout previous thread count -1/0 12:49:36: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 20 times 12:49:36: -> tcp_read(775) using WSAEventSelect 12:49:37: -> tcp_read(775) immediate timeout previous thread count -1/0 12:49:37: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 21 times 12:49:37: -> tcp_read(652) using WSAEventSelect 12:49:38: -> tcp_read(652) immediate timeout previous thread count -1/0 12:49:38: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 22 times 12:49:38: -> tcp_read(529) using WSAEventSelect 12:49:39: -> tcp_read(529) immediate timeout previous thread count -1/0 12:49:39: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 23 times 12:49:39: -> tcp_read(406) using WSAEventSelect 12:49:40: -> tcp_read(406) immediate timeout previous thread count -1/0 12:49:40: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 24 times 12:49:40: -> tcp_read(283) using WSAEventSelect 12:49:41: -> tcp_read(283) immediate timeout previous thread count -1/0 12:49:41: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 25 times 12:49:41: -> tcp_read(160) using WSAEventSelect 12:49:42: -> tcp_read(160) immediate timeout previous thread count -1/0 12:49:42: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 26 times 12:49:42: -> tcp_read(37) using WSAEventSelect 12:49:43: -> tcp_read(37) immediate timeout previous thread count -1/0 12:49:43: -> tcp_read(5120) using WSAEventSelect 12:49:43: -> tcp_read(5120) immediate timeout previous thread count -1/0 12:49:43: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 27 times 12:49:43: -> tcp_read(5034) using WSAEventSelect 12:49:44: -> tcp_read(5034) immediate timeout previous thread count -1/0 12:49:44: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 28 times 12:49:44: -> tcp_read(4911) using WSAEventSelect 12:49:45: -> tcp_read(4911) immediate timeout previous thread count -1/0 12:49:45: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 29 times 12:49:45: -> tcp_read(4788) using WSAEventSelect 12:49:46: -> tcp_read(4788) immediate timeout previous thread count -1/0 12:49:46: S -> rcsbuf_open(C:/PROGRA~3/MARCHH~1/myrepo/samp (^*doc$))/.directory_history,v) failed to open file 30 times cvsntsrv [server aborted]: Couldn't open RCS file /myrepo/samp (^*doc$))/.directory_history,v: Invalid argument 12:49:46: -> Server: return from the requested function 12:49:46: -> Unloading - about to clear list 12:49:46: -> Unloading - cleared list 12:49:46: -> Unloading sspi 12:49:46: -> Process 9020 exiting 12:49:46: -> free_directory() 12:49:46: -> free_modules2() directory_stack_size and rubbish set to zero 12:49:46: -> Free CVS_Username and cvs_locale 12:49:46: -> Perform SYSTEM_CLEANUP () 12:49:46: -> Free global_session_time and global_real_session_time 12:49:46: -> CCvsgui::Close 12:49:46: -> main return EXIT_FAILURE 12:49:46: -> Set the terminate semaphore 12:49:46: -> Free trace_file