13:19:05: -> Client trace started, level=3. 13:19:05: -> Tracelevel set to 4. PID is 5420 13:19:05: -> Session ID is 152c55fc71b96191 13:19:05: -> Session time is Fri Sep 18 20:19:05 2015 13:19:05: -> Client was compiled Sep 9 2015 16:51:42 13:19:05: -> Client was compiled with MSVC C/C++ 1310 13:19:05: -> Client build platform is microsoft-windows-x86 13:19:05: -> Build version is CVSNT 2.8.01 (Soolin) Build 5737 13:19:05: -> User name is gstar 13:19:05: -> Client running on "Professional (Build 9200) [6.2.9200] " 13:19:05: -> /3GB is not enabled, highest user address is 7ffeffff 13:19:05: -> Client has 38 percent of memory in use. 13:19:05: -> Client has 3145204 total Kbytes of physical memory*. 13:19:05: -> Client has 1925508 free Kbytes of physical memory*. 13:19:05: -> Client has 3145204 total Kbytes of physical memory. 13:19:05: -> Client has 1925508 free Kbytes of physical memory. 13:19:05: -> Client has 4455924 total Kbytes of paging file. 13:19:05: -> Client has 3087592 free Kbytes of paging file. 13:19:05: -> Client has 2097024 total Kbytes of virtual memory. 13:19:05: -> Client has 2017984 free Kbytes of virtual memory. 13:19:05: -> Client has 0 free Kbytes of extended memory. 13:19:05: -> The custom heap has the low-fragmentation heap enabled. 13:19:05: -> The minimum large page size is 2097152. 13:19:05: -> Walking heap 0X01A30000... 13:19:05: -> Region 13:19:05: -> 4096 bytes committed 13:19:05: -> 61440 bytes uncommitted 13:19:05: -> First block address: 0X01A304A0 13:19:05: -> Last block address: 0X01A3F000 13:19:05: -> Data portion begins at: 0X01A30000 13:19:05: -> Size: 1176 bytes 13:19:05: -> Overhead: 0 bytes 13:19:05: -> Region index: 0 13:19:05: -> Block 13:19:05: -> Data portion begins at: 0X01A305B0 13:19:05: -> Size: 2608 bytes 13:19:05: -> Overhead: 16 bytes 13:19:05: -> Region index: 0 13:19:05: -> Uncommitted range 13:19:05: -> Data portion begins at: 0X01A31000 13:19:05: -> Size: 57344 bytes 13:19:05: -> Overhead: 0 bytes 13:19:05: -> Region index: 0 13:19:05: -> Client Hardware information: 13:19:05: -> Number of processors: 4 13:19:05: -> Processor type: 586 13:19:05: -> processor architecture: x32 13:19:05: -> processor 386/486 model: 7680 step: 5 13:19:05: -> Free space in C:\Users\gstar\AppData\Local\Temp 2277814272 13:19:05: -> Total space in C:\Users\gstar\AppData\Local\Temp 3768578048 13:19:05: -> Total Free space in C:\Users\gstar\AppData\Local\Temp 2277814272 13:19:05: -> Loading protocol sspi as sspi.dll 13:19:05: -> CLibraryAccess::Load loading C:\PROGRA~1\CVSSUI~1\CVSNT/protocols/sspi.dll 13:19:05: -> main loop with CVSROOT=:sspi:localhost:/myrepo 13:19:05: -> main - about to start the server yes 13:19:05: -> start_server(verify_only=0) 13:19:05: -> client start - client_protocol->connect 13:19:05: -> Buffer size is 5120 13:19:05: -> sspi_connect 13:19:05: -> tcp_write(26,732) 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> SSPI:ClientAuthenticate(Negotiate,*NONE*,*NONE*) !Schannel - AcquireCredentialsHandle 13:19:05: -> SSPI:ClientAuthenticate() !Schannel - AcquireCredentialsHandle result = OK 13:19:05: -> SSPI:ClientAuthenticate() InitializeSecurityContext 13:19:05: -> tcp_write(2,732) 13:19:05: -> tcp_write(133,732) 13:19:05: -> tcp_read(2) using WSAEventSelect 13:19:05: -> tcp_read(2) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(271) using WSAEventSelect 13:19:05: -> tcp_read(271) immediate timeout previous thread count -1/0 13:19:05: -> SSPI:ClientAuthenticate() InitializeSecurityContext 13:19:05: -> tcp_write(2,732) 13:19:05: -> tcp_write(121,732) 13:19:05: -> tcp_read(2) using WSAEventSelect 13:19:05: -> tcp_read(2) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(29) using WSAEventSelect 13:19:05: -> tcp_read(29) immediate timeout previous thread count -1/0 13:19:05: -> SSPI:ClientAuthenticate() InitializeSecurityContext 13:19:05: -> tcp_write(8,732) 13:19:05: -> client start - Loop, getting responses from the server. 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 73(I), index=0 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 32( ), index=1 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 76(L), index=2 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 79(O), index=3 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 86(V), index=4 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 69(E), index=5 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 32( ), index=6 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 89(Y), index=7 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 79(O), index=8 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 85(U), index=9 13:19:05: -> tcp_read(1) using WSAEventSelect 13:19:05: -> tcp_read(1) immediate timeout previous thread count -1/0 13:19:05: -> recv_line - got 1 chars, first is 10( ), index=10 13:19:05: -> client start - got "I LOVE YOU" 13:19:05: -> client start - continue login. 13:19:05: -> client start - server started. 13:19:05: -> client start - send Valid-responses to server. 13:19:05: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=0 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=15 13:19:05: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=16 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=18 13:19:05: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=19 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=24 13:19:05: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=25 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=39 13:19:05: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=40 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=50 13:19:05: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=51 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=60 13:19:05: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=61 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=69 13:19:05: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=70 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=79 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=80 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=87 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=88 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=95 13:19:05: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=96 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=111 13:19:05: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=112 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=118 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=119 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=126 13:19:05: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=127 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=135 13:19:05: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=136 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=150 13:19:05: -> send_to_server_untranslated() calls buf_output() len=4 nbytes=151 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=155 13:19:05: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=156 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=164 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=165 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=172 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=173 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=180 13:19:05: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=181 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=193 13:19:05: -> send_to_server_untranslated() calls buf_output() len=20 nbytes=194 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=214 13:19:05: -> send_to_server_untranslated() calls buf_output() len=22 nbytes=215 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=237 13:19:05: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=238 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=248 13:19:05: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=249 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=261 13:19:05: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=262 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=270 13:19:05: -> send_to_server_untranslated() calls buf_output() len=8 nbytes=271 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=279 13:19:05: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=280 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=296 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=297 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=314 13:19:05: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=315 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=327 13:19:05: -> send_to_server_untranslated() calls buf_output() len=6 nbytes=328 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=334 13:19:05: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=335 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=347 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=348 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=349 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=350 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=357 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=358 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=359 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=360 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=361 13:19:05: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=362 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=364 13:19:05: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=365 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=381 13:19:05: -> send_to_server_untranslated() calls buf_output() len=14 nbytes=382 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=396 13:19:05: -> client start - send valid-requests to server. 13:19:05: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=397 13:19:05: -> tcp_write(412,732) 13:19:05: -> tcp_read(5120) using WSAEventSelect 13:19:05: -> tcp_read(5120) immediate timeout previous thread count -1/0 13:19:05: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=412 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=424 13:19:05: -> tcp_write(13,732) 13:19:05: -> client start - rootless_encryption. 13:19:05: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=425 13:19:05: -> send_to_server_untranslated() calls buf_output() len=7 nbytes=430 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=437 13:19:05: -> send_to_server_untranslated() calls buf_output() len=16 nbytes=438 13:19:05: -> tcp_write(29,732) 13:19:05: -> tcp_read(4186) using WSAEventSelect 13:19:05: -> tcp_read(4186) immediate timeout previous thread count -1/0 13:19:05: -> Server codepage is CP1252 13:19:05: -> Client codepage is CP1252 13:19:05: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=454 13:19:05: -> send_to_server_untranslated() calls buf_output() len=32 nbytes=469 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=501 13:19:05: -> tcp_write(48,732) 13:19:05: -> tcp_read(4179) using WSAEventSelect 13:19:05: -> tcp_read(4179) immediate timeout previous thread count -1/0 13:19:05: -> Server version is CVSNT 2.8.01 (Soolin) Build 5737 13:19:05: -> Client version is CVSNT 2.8.01 (Soolin) Build 5737 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=502 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=519 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=536 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=553 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=570 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=587 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=588 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=589 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=590 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=591 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=592 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=593 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=594 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=595 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=596 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=597 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=598 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=599 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=600 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=601 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=602 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=603 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=604 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=605 13:19:05: -> Requesting server cvsignore 13:19:05: -> send_to_server_untranslated() calls buf_output() len=15 nbytes=606 13:19:05: -> tcp_write(119,732) 13:19:05: -> tcp_read(4146) using WSAEventSelect 13:19:05: -> tcp_read(4146) immediate timeout previous thread count -1/0 13:19:05: -> wrap_add(*.a -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.a" 13:19:05: -> Call CGetOptions::getOpt: "*.a -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ai -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ai" 13:19:05: -> Call CGetOptions::getOpt: "*.ai -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.avi -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.avi" 13:19:05: -> Call CGetOptions::getOpt: "*.avi -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.bin -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.bin" 13:19:05: -> Call CGetOptions::getOpt: "*.bin -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.bmp -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.bmp" 13:19:05: -> Call CGetOptions::getOpt: "*.bmp -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.bz2 -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.bz2" 13:19:05: -> Call CGetOptions::getOpt: "*.bz2 -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.cdd -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.cdd" 13:19:05: -> Call CGetOptions::getOpt: "*.cdd -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.cdmz -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.cdmz" 13:19:05: -> Call CGetOptions::getOpt: "*.cdmz -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.chm -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.chm" 13:19:05: -> Call CGetOptions::getOpt: "*.chm -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.class -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.class" 13:19:05: -> Call CGetOptions::getOpt: "*.class -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.cur -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.cur" 13:19:05: -> Call CGetOptions::getOpt: "*.cur -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.dll -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.dll" 13:19:05: -> Call CGetOptions::getOpt: "*.dll -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.doc -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.doc" 13:19:05: -> Call CGetOptions::getOpt: "*.doc -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.docx -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.docx" 13:19:05: -> Call CGetOptions::getOpt: "*.docx -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.docm -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.docm" 13:19:05: -> Call CGetOptions::getOpt: "*.docm -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.fp -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.fp" 13:19:05: -> Call CGetOptions::getOpt: "*.fp -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.pptx -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.pptx" 13:19:05: -> Call CGetOptions::getOpt: "*.pptx -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.pptm -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.pptm" 13:19:05: -> Call CGetOptions::getOpt: "*.pptm -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.xlsx -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.xlsx" 13:19:05: -> Call CGetOptions::getOpt: "*.xlsx -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.xlsm -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.xlsm" 13:19:05: -> Call CGetOptions::getOpt: "*.xlsm -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.odt -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.odt" 13:19:05: -> Call CGetOptions::getOpt: "*.odt -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ods -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ods" 13:19:05: -> Call CGetOptions::getOpt: "*.ods -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.odp -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.odp" 13:19:05: -> Call CGetOptions::getOpt: "*.odp -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.odb -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.odb" 13:19:05: -> Call CGetOptions::getOpt: "*.odb -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.odg -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.odg" 13:19:05: -> Call CGetOptions::getOpt: "*.odg -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.odf -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.odf" 13:19:05: -> Call CGetOptions::getOpt: "*.odf -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.mpp -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.mpp" 13:19:05: -> Call CGetOptions::getOpt: "*.mpp -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.dvi -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.dvi" 13:19:05: -> Call CGetOptions::getOpt: "*.dvi -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.exe -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.exe" 13:19:05: -> Call CGetOptions::getOpt: "*.exe -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.gif -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.gif" 13:19:05: -> Call CGetOptions::getOpt: "*.gif -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.gz -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.gz" 13:19:05: -> Call CGetOptions::getOpt: "*.gz -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.hqx -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.hqx" 13:19:05: -> Call CGetOptions::getOpt: "*.hqx -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ico -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ico" 13:19:05: -> Call CGetOptions::getOpt: "*.ico -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ilk -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ilk" 13:19:05: -> Call CGetOptions::getOpt: "*.ilk -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.indd -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.indd" 13:19:05: -> Call CGetOptions::getOpt: "*.indd -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.iso -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.iso" 13:19:05: -> Call CGetOptions::getOpt: "*.iso -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.lib -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.lib" 13:19:05: -> Call CGetOptions::getOpt: "*.lib -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.jar -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.jar" 13:19:05: -> Call CGetOptions::getOpt: "*.jar -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.jpg -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.jpg" 13:19:05: -> Call CGetOptions::getOpt: "*.jpg -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.jpeg -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.jpeg" 13:19:05: -> Call CGetOptions::getOpt: "*.jpeg -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.lnk -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.lnk" 13:19:05: -> Call CGetOptions::getOpt: "*.lnk -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.mpg -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.mpg" 13:19:05: -> Call CGetOptions::getOpt: "*.mpg -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.mpeg -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.mpeg" 13:19:05: -> Call CGetOptions::getOpt: "*.mpeg -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.mov -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.mov" 13:19:05: -> Call CGetOptions::getOpt: "*.mov -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.mp3 -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.mp3" 13:19:05: -> Call CGetOptions::getOpt: "*.mp3 -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ncb -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ncb" 13:19:05: -> Call CGetOptions::getOpt: "*.ncb -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.o -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.o" 13:19:05: -> Call CGetOptions::getOpt: "*.o -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ogg -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ogg" 13:19:05: -> Call CGetOptions::getOpt: "*.ogg -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ocx -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ocx" 13:19:05: -> Call CGetOptions::getOpt: "*.ocx -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.obj -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.obj" 13:19:05: -> Call CGetOptions::getOpt: "*.obj -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.pdb -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.pdb" 13:19:05: -> Call CGetOptions::getOpt: "*.pdb -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.pdf -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.pdf" 13:19:05: -> Call CGetOptions::getOpt: "*.pdf -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.png -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.png" 13:19:05: -> Call CGetOptions::getOpt: "*.png -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ppt -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ppt" 13:19:05: -> Call CGetOptions::getOpt: "*.ppt -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.psd -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.psd" 13:19:05: -> Call CGetOptions::getOpt: "*.psd -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.res -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.res" 13:19:05: -> Call CGetOptions::getOpt: "*.res -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.rpm -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.rpm" 13:19:05: -> Call CGetOptions::getOpt: "*.rpm -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.sit -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.sit" 13:19:05: -> Call CGetOptions::getOpt: "*.sit -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.so -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.so" 13:19:05: -> Call CGetOptions::getOpt: "*.so -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.tar -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.tar" 13:19:05: -> Call CGetOptions::getOpt: "*.tar -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.tga -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.tga" 13:19:05: -> Call CGetOptions::getOpt: "*.tga -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.tgz -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.tgz" 13:19:05: -> Call CGetOptions::getOpt: "*.tgz -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.tif -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.tif" 13:19:05: -> Call CGetOptions::getOpt: "*.tif -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.tiff -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.tiff" 13:19:05: -> Call CGetOptions::getOpt: "*.tiff -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.ttf -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.ttf" 13:19:05: -> Call CGetOptions::getOpt: "*.ttf -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.uir -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.uir" 13:19:05: -> Call CGetOptions::getOpt: "*.uir -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.wav -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.wav" 13:19:05: -> Call CGetOptions::getOpt: "*.wav -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.wmv -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.wmv" 13:19:05: -> Call CGetOptions::getOpt: "*.wmv -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.xls -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.xls" 13:19:05: -> Call CGetOptions::getOpt: "*.xls -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.zip -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.zip" 13:19:05: -> Call CGetOptions::getOpt: "*.zip -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> wrap_add(*.Z -kb, 0, 0, 0, 0) 13:19:05: -> Call CFileAccess::mimetype: "*.Z" 13:19:05: -> Call CGetOptions::getOpt: "*.Z -kb" "+k:x:m:t:" 13:19:05: -> wrap_add() Sort. 13:19:05: -> Requesting server cvswrappers 13:19:05: -> send_to_server_untranslated() calls buf_output() len=17 nbytes=621 13:19:05: -> tcp_write(17,732) 13:19:05: -> tcp_read(4144) using WSAEventSelect 13:19:05: -> tcp_read(4144) immediate timeout previous thread count -1/0 13:19:05: -> Got the server cvswrappers 13:19:05: -> The first item in the sorted list is "b" with wildcard "*.a" 13:19:05: -> Requesting server cvsrc (read-cvsrc2) 13:19:05: -> send_to_server_untranslated() calls buf_output() len=12 nbytes=638 13:19:05: -> tcp_write(12,732) 13:19:05: -> tcp_read(4140) using WSAEventSelect 13:19:05: -> tcp_read(4140) immediate timeout previous thread count -1/0 13:19:05: -> Parsing global cvsrc started 13:19:05: -> Parsing global cvsrc finished 0 lines. 13:19:05: -> No local C:\Users\gstar/.cvsrc to parse 13:19:05: -> Server: call the requested function rlog 13:19:05: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=650 13:19:05: -> send_to_server_untranslated() calls buf_output() len=2 nbytes=659 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=661 13:19:05: -> send_to_server_untranslated() calls buf_output() len=9 nbytes=662 13:19:05: -> send_to_server_untranslated() calls buf_output() len=10 nbytes=671 13:19:05: -> send_to_server_untranslated() calls buf_output() len=1 nbytes=681 13:19:05: -> send_to_server_untranslated() calls buf_output() len=5 nbytes=682 13:19:05: -> tcp_write(37,732) 13:19:05: -> tcp_read(4138) using WSAEventSelect 13:19:05: -> tcp_read(4138) immediate timeout previous thread count -1/0 13:19:05: S -> server_main started 13:19:05: S -> server_main process id is 696 13:19:05: -> tcp_read(4054) using WSAEventSelect 13:19:05: -> tcp_read(4054) immediate timeout previous thread count -1/0 13:19:05: S -> server_main CVS2_SERVER_SLEEP not set. 13:19:05: S -> run plugintools proc server 13:19:05: -> tcp_read(3953) using WSAEventSelect 13:19:05: -> tcp_read(3953) immediate timeout previous thread count -1/0 13:19:05: S -> run_trigger() 13:19:05: -> tcp_read(3922) using WSAEventSelect 13:19:05: -> tcp_read(3922) immediate timeout previous thread count -1/0 13:19:05: S -> LoadTrigger(audit.dll) 13:19:05: S -> CLibraryAccess::Load loading C:\PROGRA~1\CVSSUI~1\CVSNT/triggers/audit.dll 13:19:05: -> tcp_read(3790) using WSAEventSelect 13:19:05: -> tcp_read(3790) immediate timeout previous thread count -1/0 13:19:05: S -> Audit trigger not enabled. 13:19:05: -> tcp_read(3746) using WSAEventSelect 13:19:05: -> tcp_read(3746) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger audit.dll 13:19:05: -> tcp_read(3697) using WSAEventSelect 13:19:05: -> tcp_read(3697) immediate timeout previous thread count -1/0 13:19:05: S -> LoadTrigger(bugzilla.dll) 13:19:05: -> tcp_read(3654) using WSAEventSelect 13:19:05: -> tcp_read(3654) immediate timeout previous thread count -1/0 13:19:05: S -> CLibraryAccess::Load loading C:\PROGRA~1\CVSSUI~1\CVSNT/triggers/bugzilla.dll 13:19:05: -> tcp_read(3559) using WSAEventSelect 13:19:05: -> tcp_read(3559) immediate timeout previous thread count -1/0 13:19:05: S -> Defect Tracking trigger not enabled. 13:19:05: -> tcp_read(3502) using WSAEventSelect 13:19:05: -> tcp_read(3502) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger bugzilla.dll 13:19:05: -> tcp_read(3450) using WSAEventSelect 13:19:05: -> tcp_read(3450) immediate timeout previous thread count -1/0 13:19:05: S -> Checking admin file C:/PROGRA~2/MARCHH~1/myrepo/CVSROOT/admin for user MicrosoftAccount\gstarrett@mediacombb.net 13:19:05: -> tcp_read(3320) using WSAEventSelect 13:19:05: -> tcp_read(3320) immediate timeout previous thread count -1/0 13:19:05: S -> Checked admin file (null) for user MicrosoftAccount\gstarrett@mediacombb.net they are not an admin. 13:19:05: S -> Check windows admin for user. 13:19:05: -> tcp_read(3156) using WSAEventSelect 13:19:05: -> tcp_read(3156) immediate timeout previous thread count -1/0 13:19:05: S -> Determine if the current thread is running as a user that is a member of the local admins group. 13:19:05: -> tcp_read(3042) using WSAEventSelect 13:19:05: -> tcp_read(3042) immediate timeout previous thread count -1/0 13:19:05: S -> No - is NOT admin (WIN32). 13:19:05: -> tcp_read(2998) using WSAEventSelect 13:19:05: -> tcp_read(2998) immediate timeout previous thread count -1/0 13:19:05: S -> Check windows admin for user completed. 13:19:05: S -> InitTrigger(checkout.dll) 13:19:05: -> tcp_read(2898) using WSAEventSelect 13:19:05: -> tcp_read(2898) immediate timeout previous thread count -1/0 13:19:05: S -> Checkout trigger not enabled. 13:19:05: -> tcp_read(2851) using WSAEventSelect 13:19:05: -> tcp_read(2851) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger checkout.dll 13:19:05: S -> InitTrigger(email.dll) 13:19:05: -> tcp_read(2759) using WSAEventSelect 13:19:05: -> tcp_read(2759) immediate timeout previous thread count -1/0 13:19:05: S -> Email trigger not enabled. 13:19:05: -> tcp_read(2715) using WSAEventSelect 13:19:05: -> tcp_read(2715) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger email.dll 13:19:05: S -> InitTrigger(info.dll) 13:19:05: -> tcp_read(2627) using WSAEventSelect 13:19:05: -> tcp_read(2627) immediate timeout previous thread count -1/0 13:19:05: S -> InitTrigger(make.dll) 13:19:05: -> tcp_read(2588) using WSAEventSelect 13:19:05: -> tcp_read(2588) immediate timeout previous thread count -1/0 13:19:05: S -> Make trigger not enabled. 13:19:05: -> tcp_read(2542) using WSAEventSelect 13:19:05: -> tcp_read(2542) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger make.dll 13:19:05: S -> InitTrigger(script.dll) 13:19:05: -> tcp_read(2453) using WSAEventSelect 13:19:05: -> tcp_read(2453) immediate timeout previous thread count -1/0 13:19:05: S -> Script trigger not enabled. 13:19:05: -> tcp_read(2408) using WSAEventSelect 13:19:05: -> tcp_read(2408) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger script.dll 13:19:05: S -> InitTrigger(sync.dll) 13:19:05: -> tcp_read(2319) using WSAEventSelect 13:19:05: -> tcp_read(2319) immediate timeout previous thread count -1/0 13:19:05: S -> Sync trigger not enabled. 13:19:05: -> tcp_read(2273) using WSAEventSelect 13:19:05: -> tcp_read(2273) immediate timeout previous thread count -1/0 13:19:05: S -> Couldn't load trigger sync.dll 13:19:05: -> tcp_read(2225) using WSAEventSelect 13:19:05: -> tcp_read(2225) immediate timeout previous thread count -1/0 13:19:05: S -> Call pre-loaded 'info.dll' 13:19:05: -> tcp_read(2181) using WSAEventSelect 13:19:05: -> tcp_read(2181) immediate timeout previous thread count -1/0 13:19:05: S -> plugintools_proc() 13:19:05: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 13:19:05: -> tcp_read(2077) using WSAEventSelect 13:19:05: -> tcp_read(2077) immediate timeout previous thread count -1/0 13:19:05: S -> run_trigger returning 0 13:19:05: S -> run precommand proc server 13:19:05: -> tcp_read(1992) using WSAEventSelect 13:19:05: -> tcp_read(1992) immediate timeout previous thread count -1/0 13:19:05: S -> run_trigger() 13:19:05: -> tcp_read(1961) using WSAEventSelect 13:19:05: -> tcp_read(1961) immediate timeout previous thread count -1/0 13:19:05: S -> Call pre-loaded 'info.dll' 13:19:05: S -> precommand_proc() 13:19:05: -> tcp_read(1882) using WSAEventSelect 13:19:05: -> tcp_read(1882) immediate timeout previous thread count -1/0 13:19:05: S -> default_trigger: parse_info(CVSROOT/precommand,%r %c,%) 13:19:05: -> tcp_read(1800) using WSAEventSelect 13:19:05: -> tcp_read(1800) immediate timeout previous thread count -1/0 13:19:05: S -> CFileAccess::open('C:/PROGRA~2/MARCHH~1/myrepo/CVSROOT/precommand','rb') 13:19:05: -> tcp_read(1710) using WSAEventSelect 13:19:05: -> tcp_read(1710) immediate timeout previous thread count -1/0 13:19:05: S -> CFileAccess::open() setvbuf(_IONBF) 13:19:05: -> tcp_read(1657) using WSAEventSelect 13:19:05: -> tcp_read(1657) immediate timeout previous thread count -1/0 13:19:05: S -> CFileAccess::close() 13:19:05: S -> CFileAccess::close() 13:19:05: -> tcp_read(1581) using WSAEventSelect 13:19:05: -> tcp_read(1581) immediate timeout previous thread count -1/0 13:19:05: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 13:19:05: S -> run_trigger returning 0 13:19:05: -> tcp_read(1472) using WSAEventSelect 13:19:05: -> tcp_read(1472) immediate timeout previous thread count -1/0 13:19:05: S -> open_module(C:/PROGRA~2/MARCHH~1/myrepo) 13:19:05: -> tcp_read(1414) using WSAEventSelect 13:19:05: -> tcp_read(1414) immediate timeout previous thread count -1/0 13:19:05: S -> rlog - about to do_module 1 times 13:19:05: -> tcp_read(1363) using WSAEventSelect 13:19:05: -> tcp_read(1363) immediate timeout previous thread count -1/0 13:19:05: S -> do_module(SmTestImp3, Logging, , ) 13:19:05: -> tcp_read(1311) using WSAEventSelect 13:19:05: -> tcp_read(1311) immediate timeout previous thread count -1/0 13:19:05: S -> do_module calls map_repository() 13:19:05: -> tcp_read(1261) using WSAEventSelect 13:19:05: -> tcp_read(1261) immediate timeout previous thread count -1/0 13:19:05: S -> map_repository(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(1189) using WSAEventSelect 13:19:05: -> tcp_read(1189) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: -> tcp_read(1097) using WSAEventSelect 13:19:05: -> tcp_read(1097) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(1040) using WSAEventSelect 13:19:05: -> tcp_read(1040) immediate timeout previous thread count -1/0 13:19:05: S -> Loading modules2 from C:/PROGRA~2/MARCHH~1/myrepo/CVSROOT/modules2 13:19:05: -> tcp_read(956) using WSAEventSelect 13:19:05: -> tcp_read(956) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: S -> lookup_module2(SmTestImp3,1) 13:19:05: -> tcp_read(843) using WSAEventSelect 13:19:05: -> tcp_read(843) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(770) using WSAEventSelect 13:19:05: -> tcp_read(770) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: -> tcp_read(629) using WSAEventSelect 13:19:05: -> tcp_read(629) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3) 13:19:05: -> tcp_read(558) using WSAEventSelect 13:19:05: -> tcp_read(558) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: -> tcp_read(492) using WSAEventSelect 13:19:05: -> tcp_read(492) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory==NULL, current_directory->directory_mappings=!!!! 13:19:05: -> tcp_read(311) using WSAEventSelect 13:19:05: -> tcp_read(311) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: -> tcp_read(254) using WSAEventSelect 13:19:05: -> tcp_read(254) immediate timeout previous thread count -1/0 13:19:05: S -> map_repository - return(repository) "C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3" 13:19:05: S -> do_module call to map_repository() has returned something 13:19:05: -> tcp_read(85) using WSAEventSelect 13:19:05: -> tcp_read(85) immediate timeout previous thread count -1/0 13:19:05: S -> * check to see if mname is a directory or file 13:19:05: -> tcp_read(20) using WSAEventSelect 13:19:05: -> tcp_read(20) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(5120) using WSAEventSelect 13:19:05: -> tcp_read(5120) immediate timeout previous thread count -1/0 13:19:05: S -> do_module calls isdir(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(5061) using WSAEventSelect 13:19:05: -> tcp_read(5061) immediate timeout previous thread count -1/0 13:19:05: S -> do_module yes it is a directory ... 13:19:05: -> tcp_read(5008) using WSAEventSelect 13:19:05: -> tcp_read(5008) immediate timeout previous thread count -1/0 13:19:05: S -> * OK, we have now set up modargv with the actual file/directory we want to work on. 13:19:05: S -> run premodule trigger 13:19:05: -> tcp_read(4868) using WSAEventSelect 13:19:05: -> tcp_read(4868) immediate timeout previous thread count -1/0 13:19:05: S -> run_trigger() 13:19:05: -> tcp_read(4837) using WSAEventSelect 13:19:05: -> tcp_read(4837) immediate timeout previous thread count -1/0 13:19:05: S -> Call pre-loaded 'info.dll' 13:19:05: S -> premodule_proc(SmTestImp3) 13:19:05: -> tcp_read(4749) using WSAEventSelect 13:19:05: -> tcp_read(4749) immediate timeout previous thread count -1/0 13:19:05: S -> default_trigger: parse_info(CVSROOT/premodule,%r/%p %c %o,,SmTestImp3) 13:19:05: -> tcp_read(4661) using WSAEventSelect 13:19:05: -> tcp_read(4661) immediate timeout previous thread count -1/0 13:19:05: S -> CFileAccess::open('C:/PROGRA~2/MARCHH~1/myrepo/CVSROOT/premodule','rb') 13:19:05: -> tcp_read(4572) using WSAEventSelect 13:19:05: -> tcp_read(4572) immediate timeout previous thread count -1/0 13:19:05: S -> CFileAccess::open() setvbuf(_IONBF) 13:19:05: -> tcp_read(4519) using WSAEventSelect 13:19:05: -> tcp_read(4519) immediate timeout previous thread count -1/0 13:19:05: S -> CFileAccess::close() 13:19:05: S -> CFileAccess::close() 13:19:05: -> tcp_read(4443) using WSAEventSelect 13:19:05: -> tcp_read(4443) immediate timeout previous thread count -1/0 13:19:05: S -> Call pre-loaded 'info.dll' returned 0, total ret 0 13:19:05: S -> run_trigger returning 0 13:19:05: -> tcp_read(4334) using WSAEventSelect 13:19:05: -> tcp_read(4334) immediate timeout previous thread count -1/0 13:19:05: S -> do_modue(1): call the callback_proc((null)) 13:19:05: S -> map_repository(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> lookup_module2() 13:19:05: -> tcp_read(4167) using WSAEventSelect 13:19:05: -> tcp_read(4167) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(4052) using WSAEventSelect 13:19:05: -> tcp_read(4052) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: S -> lookup_module2(SmTestImp3,2) 13:19:05: -> tcp_read(3939) using WSAEventSelect 13:19:05: -> tcp_read(3939) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(3812) using WSAEventSelect 13:19:05: -> tcp_read(3812) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3) 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: -> tcp_read(3588) using WSAEventSelect 13:19:05: -> tcp_read(3588) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory==NULL, current_directory->directory_mappings=!!!! 13:19:05: -> tcp_read(3407) using WSAEventSelect 13:19:05: -> tcp_read(3407) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: S -> map_repository - return(repository) "C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3" 13:19:05: -> tcp_read(3256) using WSAEventSelect 13:19:05: -> tcp_read(3256) immediate timeout previous thread count -1/0 13:19:05: S -> start_recursion(SmTestImp3,C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(3172) using WSAEventSelect 13:19:05: -> tcp_read(3172) immediate timeout previous thread count -1/0 13:19:05: S -> start_recursion() dirleaveproc is NULL 13:19:05: S -> start_recursion() update_dir set to "SmTestImp3" 13:19:05: S -> start_recursion - argument count (argc) == 0 13:19:05: -> tcp_read(2988) using WSAEventSelect 13:19:05: -> tcp_read(2988) immediate timeout previous thread count -1/0 13:19:05: S -> * There were no arguments, so we'll probably just recurse. The 13:19:05: S -> * exception to the rule is when we are called from a directory 13:19:05: S -> * without any CVS administration files. That has always meant to 13:19:05: -> tcp_read(2745) using WSAEventSelect 13:19:05: -> tcp_read(2745) immediate timeout previous thread count -1/0 13:19:05: S -> * process each of the sub-directories, so we pretend like we were 13:19:05: S -> * called with the list of sub-dirs of the current dir as args 13:19:05: -> tcp_read(2583) using WSAEventSelect 13:19:05: -> tcp_read(2583) immediate timeout previous thread count -1/0 13:19:05: S -> start_recursion: !just_subdirs - call addlist(dirlist==NULL, ".") 13:19:05: S -> start_recursion: addlist() returns and now dirlist!=NULL 13:19:05: -> tcp_read(2425) using WSAEventSelect 13:19:05: -> tcp_read(2425) immediate timeout previous thread count -1/0 13:19:05: S -> start_recursion/do_the_work: do_recursion() on the frame (dirlist != NULL). 13:19:05: S -> do_recursion: begin 13:19:05: S -> do_recursion: Check the value in CVSADM_ROOT 13:19:05: -> tcp_read(2232) using WSAEventSelect 13:19:05: -> tcp_read(2232) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: Fill in repository with the current repository 13:19:05: -> tcp_read(2154) using WSAEventSelect 13:19:05: -> tcp_read(2154) immediate timeout previous thread count -1/0 13:19:05: S -> (repository && (server_active || !current_parsed_root->isremote)) 13:19:05: -> tcp_read(2071) using WSAEventSelect 13:19:05: -> tcp_read(2071) immediate timeout previous thread count -1/0 13:19:05: S -> map_repository(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(1999) using WSAEventSelect 13:19:05: -> tcp_read(1999) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() 13:19:05: -> tcp_read(1965) using WSAEventSelect 13:19:05: -> tcp_read(1965) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: -> tcp_read(1907) using WSAEventSelect 13:19:05: -> tcp_read(1907) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(1850) using WSAEventSelect 13:19:05: -> tcp_read(1850) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: S -> lookup_module2(SmTestImp3,3) 13:19:05: -> tcp_read(1737) using WSAEventSelect 13:19:05: -> tcp_read(1737) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(1664) using WSAEventSelect 13:19:05: -> tcp_read(1664) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: -> tcp_read(1523) using WSAEventSelect 13:19:05: -> tcp_read(1523) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3) 13:19:05: -> tcp_read(1452) using WSAEventSelect 13:19:05: -> tcp_read(1452) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: -> tcp_read(1314) using WSAEventSelect 13:19:05: -> tcp_read(1314) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() ret=0, current_directory==NULL, current_directory->directory_mappings=!!!! 13:19:05: -> tcp_read(1205) using WSAEventSelect 13:19:05: -> tcp_read(1205) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: S -> map_repository - return(repository) "C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3" 13:19:05: -> tcp_read(1054) using WSAEventSelect 13:19:05: -> tcp_read(1054) immediate timeout previous thread count -1/0 13:19:05: S -> Repository = C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3 13:19:05: S -> Mapped repository = C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3 13:19:05: -> tcp_read(909) using WSAEventSelect 13:19:05: -> tcp_read(909) immediate timeout previous thread count -1/0 13:19:05: S -> (repository && (server_active || !current_parsed_root->isremote)) 13:19:05: -> tcp_read(826) using WSAEventSelect 13:19:05: -> tcp_read(826) immediate timeout previous thread count -1/0 13:19:05: S -> ParseTag() 13:19:05: -> tcp_read(798) using WSAEventSelect 13:19:05: -> tcp_read(798) immediate timeout previous thread count -1/0 13:19:05: S -> ParseTag_Dir((null),,,0,) 13:19:05: -> tcp_read(744) using WSAEventSelect 13:19:05: -> tcp_read(744) immediate timeout previous thread count -1/0 13:19:05: S -> verify_access C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3,SmTestImp3,(null) 13:19:05: S -> verify_perm(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3,(null),read,(null),(null)) 13:19:05: S -> verify_perm: verify_owner_dir() 13:19:05: -> tcp_read(512) using WSAEventSelect 13:19:05: -> tcp_read(512) immediate timeout previous thread count -1/0 13:19:05: S -> verify_owner(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> cache_directory_permissions(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> fileattr_read(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> _fileattr_read() malloc [strlen(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3)=]38+[sizeof(CVS/fileattr.xml)=]4+20=62 13:19:05: -> tcp_read(158) using WSAEventSelect 13:19:05: -> tcp_read(158) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read() xmalloc fname OK 13:19:05: S -> _fileattr_read() allocated memory for fname - will now sprintf() 13:19:05: -> tcp_read(26) using WSAEventSelect 13:19:05: -> tcp_read(26) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(5120) using WSAEventSelect 13:19:05: -> tcp_read(5120) immediate timeout previous thread count -1/0 13:19:05: S -> _fileattr_read() ReadXmlFile("C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/CVS/fileattr.xml") 13:19:05: -> tcp_read(5041) using WSAEventSelect 13:19:05: -> tcp_read(5041) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read() ReadXmlFile() 13:19:05: -> tcp_read(4994) using WSAEventSelect 13:19:05: -> tcp_read(4994) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read() close 13:19:05: -> tcp_read(4955) using WSAEventSelect 13:19:05: -> tcp_read(4955) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read() xfree fname 13:19:05: S -> fileattr_read() OK 13:19:05: S -> cache_directory_permissions() - cache directory(fileattr,) 13:19:05: -> tcp_read(4798) using WSAEventSelect 13:19:05: -> tcp_read(4798) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: S -> cache_directory_permissions() last_component=SmTestImp3 13:19:05: -> tcp_read(4567) using WSAEventSelect 13:19:05: -> tcp_read(4567) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read(C:/PROGRA~2/MARCHH~1/myrepo) 13:19:05: -> tcp_read(4507) using WSAEventSelect 13:19:05: -> tcp_read(4507) immediate timeout previous thread count -1/0 13:19:05: S -> _fileattr_read() malloc [strlen(C:/PROGRA~2/MARCHH~1/myrepo)=]27+[sizeof(CVS/fileattr.xml)=]4+20=51 13:19:05: S -> fileattr_read() xmalloc fname OK 13:19:05: -> tcp_read(4340) using WSAEventSelect 13:19:05: -> tcp_read(4340) immediate timeout previous thread count -1/0 13:19:05: S -> _fileattr_read() allocated memory for fname - will now sprintf() 13:19:05: -> tcp_read(4258) using WSAEventSelect 13:19:05: -> tcp_read(4258) immediate timeout previous thread count -1/0 13:19:05: S -> _fileattr_read() no file "C:/PROGRA~2/MARCHH~1/myrepo/CVS/fileattr.xml", so allocate ofname of 60 bytes. 13:19:05: -> tcp_read(4136) using WSAEventSelect 13:19:05: -> tcp_read(4136) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read() xmalloc ofname OK 13:19:05: -> tcp_read(4085) using WSAEventSelect 13:19:05: -> tcp_read(4085) immediate timeout previous thread count -1/0 13:19:05: S -> _fileattr_read() no old "C:/PROGRA~2/MARCHH~1/myrepo/CVS/fileattr". 13:19:05: -> tcp_read(4000) using WSAEventSelect 13:19:05: -> tcp_read(4000) immediate timeout previous thread count -1/0 13:19:05: S -> _fileattr_read() no old "C:/PROGRA~2/MARCHH~1/myrepo//.owner". 13:19:05: S -> _fileattr_read() no old "C:/PROGRA~2/MARCHH~1/myrepo//.perms". 13:19:05: S -> _fileattr_read() CXmlNode("fileattr") 13:19:05: -> tcp_read(3785) using WSAEventSelect 13:19:05: -> tcp_read(3785) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_read() xfree ofname and fname OK 13:19:05: -> tcp_read(3726) using WSAEventSelect 13:19:05: -> tcp_read(3726) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() - cache directory(fileattr,) 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: S -> cache_directory_permissions() free(C:/PROGRA~2/MARCHH~1/myrepo) 13:19:05: -> tcp_read(3422) using WSAEventSelect 13:19:05: -> tcp_read(3422) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() return 13:19:05: -> tcp_read(3368) using WSAEventSelect 13:19:05: -> tcp_read(3368) immediate timeout previous thread count -1/0 13:19:05: S -> verify_valid_name(MicrosoftAccount\gstarrett@mediacombb.net) 13:19:05: -> tcp_read(3290) using WSAEventSelect 13:19:05: -> tcp_read(3290) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: -> tcp_read(3047) using WSAEventSelect 13:19:05: -> tcp_read(3047) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() last_component=SmTestImp3 13:19:05: -> tcp_read(2974) using WSAEventSelect 13:19:05: -> tcp_read(2974) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: -> tcp_read(2827) using WSAEventSelect 13:19:05: -> tcp_read(2827) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() free(C:/PROGRA~2/MARCHH~1/myrepo) 13:19:05: S -> cache_directory_permissions() return 13:19:05: -> tcp_read(2692) using WSAEventSelect 13:19:05: -> tcp_read(2692) immediate timeout previous thread count -1/0 13:19:05: S -> verify_perm() Read directory permission cache OK 13:19:05: S -> ACL lookup on directory C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3 13:19:05: -> tcp_read(2546) using WSAEventSelect 13:19:05: -> tcp_read(2546) immediate timeout previous thread count -1/0 13:19:05: S -> verify_acl(read,HEAD,(null)) 13:19:05: -> tcp_read(2500) using WSAEventSelect 13:19:05: -> tcp_read(2500) immediate timeout previous thread count -1/0 13:19:05: S -> user_state = 0, group_state = 0 13:19:05: -> tcp_read(2451) using WSAEventSelect 13:19:05: -> tcp_read(2451) immediate timeout previous thread count -1/0 13:19:05: S -> no match at this level 13:19:05: S -> ACL lookup on directory C:/PROGRA~2/MARCHH~1/myrepo 13:19:05: -> tcp_read(2342) using WSAEventSelect 13:19:05: -> tcp_read(2342) immediate timeout previous thread count -1/0 13:19:05: S -> verify_acl(read,HEAD,(null)) 13:19:05: -> tcp_read(2296) using WSAEventSelect 13:19:05: -> tcp_read(2296) immediate timeout previous thread count -1/0 13:19:05: S -> user_state = 0, group_state = 0 13:19:05: S -> no match at this level 13:19:05: -> tcp_read(2207) using WSAEventSelect 13:19:05: -> tcp_read(2207) immediate timeout previous thread count -1/0 13:19:05: S -> Match found. user_state = 0, group_state = 0 13:19:05: -> tcp_read(2145) using WSAEventSelect 13:19:05: -> tcp_read(2145) immediate timeout previous thread count -1/0 13:19:05: S -> verify_perm() return 13:19:05: -> tcp_read(2107) using WSAEventSelect 13:19:05: -> tcp_read(2107) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_startdir(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> * The filesdoneproc needs to be called for each directory where files 13:19:05: -> tcp_read(1945) using WSAEventSelect 13:19:05: -> tcp_read(1945) immediate timeout previous thread count -1/0 13:19:05: S -> * processed, or each directory that is processed by a call where no 13:19:05: -> tcp_read(1860) using WSAEventSelect 13:19:05: -> tcp_read(1860) immediate timeout previous thread count -1/0 13:19:05: S -> * directories were passed in. In fact, the only time we don't want to 13:19:05: -> tcp_read(1772) using WSAEventSelect 13:19:05: -> tcp_read(1772) immediate timeout previous thread count -1/0 13:19:05: S -> * call back the filesdoneproc is when we are processing directories that 13:19:05: S -> * were passed in on the command line (or in the special case of `.' when 13:19:05: S -> * we were called with no args 13:19:05: -> tcp_read(1545) using WSAEventSelect 13:19:05: -> tcp_read(1545) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: dirlist != NULL && filelist == NULL 13:19:05: S -> do_recursion: dodoneproc=0 13:19:05: -> tcp_read(1434) using WSAEventSelect 13:19:05: -> tcp_read(1434) immediate timeout previous thread count -1/0 13:19:05: S -> * If filelist or dirlist is already set, we don't look again. Otherwise, 13:19:05: -> tcp_read(1344) using WSAEventSelect 13:19:05: -> tcp_read(1344) immediate timeout previous thread count -1/0 13:19:05: S -> * find the files and directories 13:19:05: S -> do_recursion: something was passed on the command line 13:19:05: -> tcp_read(1221) using WSAEventSelect 13:19:05: -> tcp_read(1221) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: process the files (if any) 13:19:05: -> tcp_read(1162) using WSAEventSelect 13:19:05: -> tcp_read(1162) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: call-back files done proc (if any) 13:19:05: S -> do_recursion: no: don't call-back files done proc (process_this_directory!dodoneprocframe->filesdoneproc == NULL) 13:19:05: -> tcp_read(963) using WSAEventSelect 13:19:05: -> tcp_read(963) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: at skip_directory 13:19:05: -> tcp_read(913) using WSAEventSelect 13:19:05: -> tcp_read(913) immediate timeout previous thread count -1/0 13:19:05: S -> (repository && (server_active || !current_parsed_root->isremote)) 13:19:05: -> tcp_read(830) using WSAEventSelect 13:19:05: -> tcp_read(830) immediate timeout previous thread count -1/0 13:19:05: S -> fileattr_write() 13:19:05: S -> fileattr_free() 13:19:05: -> tcp_read(763) using WSAEventSelect 13:19:05: -> tcp_read(763) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: process the directories (if necessary) 13:19:05: -> tcp_read(692) using WSAEventSelect 13:19:05: -> tcp_read(692) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: really process the directories (if necessary) 13:19:05: S -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent) 13:19:05: -> tcp_read(523) using WSAEventSelect 13:19:05: -> tcp_read(523) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() 13:19:05: -> tcp_read(492) using WSAEventSelect 13:19:05: -> tcp_read(492) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() update_dir=SmTestImp3 13:19:05: -> tcp_read(439) using WSAEventSelect 13:19:05: -> tcp_read(439) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- set up update_dir(SmTestImp3) - no dots ? 13:19:05: S -> do_dir_proc() -- set up update_dir is now dirp "SmTestImp3" - no dots ? 13:19:05: -> tcp_read(272) using WSAEventSelect 13:19:05: -> tcp_read(272) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote 13:19:05: -> tcp_read(190) using WSAEventSelect 13:19:05: -> tcp_read(190) immediate timeout previous thread count -1/0 13:19:05: S -> ParseTag() 13:19:05: -> tcp_read(162) using WSAEventSelect 13:19:05: -> tcp_read(162) immediate timeout previous thread count -1/0 13:19:05: S -> ParseTag_Dir((null),,,0,) 13:19:05: -> tcp_read(97) using WSAEventSelect 13:19:05: -> tcp_read(97) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- verify_access 13:19:05: -> tcp_read(48) using WSAEventSelect 13:19:05: -> tcp_read(48) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(5120) using WSAEventSelect 13:19:05: -> tcp_read(5120) immediate timeout previous thread count -1/0 13:19:05: S -> verify_access C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3,SmTestImp3,(null) 13:19:05: S -> verify_perm(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3,(null),read,(null),(null)) 13:19:05: S -> verify_perm: verify_owner_dir() 13:19:05: S -> verify_owner(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> cache_directory_permissions(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(4781) using WSAEventSelect 13:19:05: -> tcp_read(4781) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: S -> cache_directory_permissions() last_component=SmTestImp3 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: -> tcp_read(4403) using WSAEventSelect 13:19:05: -> tcp_read(4403) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() free(C:/PROGRA~2/MARCHH~1/myrepo) 13:19:05: S -> cache_directory_permissions() return 13:19:05: -> tcp_read(4268) using WSAEventSelect 13:19:05: -> tcp_read(4268) immediate timeout previous thread count -1/0 13:19:05: S -> verify_valid_name(MicrosoftAccount\gstarrett@mediacombb.net) 13:19:05: -> tcp_read(4190) using WSAEventSelect 13:19:05: -> tcp_read(4190) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: -> tcp_read(3947) using WSAEventSelect 13:19:05: -> tcp_read(3947) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() last_component=SmTestImp3 13:19:05: -> tcp_read(3874) using WSAEventSelect 13:19:05: -> tcp_read(3874) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() fncmp(d="C:/PROGRA~2/MARCHH~1/myrepo",current_parsed_root->directory="C:/PROGRA~2/MARCHH~1/myrepo") 13:19:05: -> tcp_read(3727) using WSAEventSelect 13:19:05: -> tcp_read(3727) immediate timeout previous thread count -1/0 13:19:05: S -> cache_directory_permissions() free(C:/PROGRA~2/MARCHH~1/myrepo) 13:19:05: S -> cache_directory_permissions() return 13:19:05: -> tcp_read(3592) using WSAEventSelect 13:19:05: -> tcp_read(3592) immediate timeout previous thread count -1/0 13:19:05: S -> verify_perm() Read directory permission cache OK 13:19:05: -> tcp_read(3526) using WSAEventSelect 13:19:05: -> tcp_read(3526) immediate timeout previous thread count -1/0 13:19:05: S -> ACL lookup on directory C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3 13:19:05: -> tcp_read(3446) using WSAEventSelect 13:19:05: -> tcp_read(3446) immediate timeout previous thread count -1/0 13:19:05: S -> verify_acl(read,HEAD,(null)) 13:19:05: S -> user_state = 0, group_state = 0 13:19:05: -> tcp_read(3351) using WSAEventSelect 13:19:05: -> tcp_read(3351) immediate timeout previous thread count -1/0 13:19:05: S -> no match at this level 13:19:05: -> tcp_read(3311) using WSAEventSelect 13:19:05: -> tcp_read(3311) immediate timeout previous thread count -1/0 13:19:05: S -> ACL lookup on directory C:/PROGRA~2/MARCHH~1/myrepo 13:19:05: S -> verify_acl(read,HEAD,(null)) 13:19:05: -> tcp_read(3196) using WSAEventSelect 13:19:05: -> tcp_read(3196) immediate timeout previous thread count -1/0 13:19:05: S -> user_state = 0, group_state = 0 13:19:05: -> tcp_read(3147) using WSAEventSelect 13:19:05: -> tcp_read(3147) immediate timeout previous thread count -1/0 13:19:05: S -> no match at this level 13:19:05: -> tcp_read(3107) using WSAEventSelect 13:19:05: -> tcp_read(3107) immediate timeout previous thread count -1/0 13:19:05: S -> Match found. user_state = 0, group_state = 0 13:19:05: S -> verify_perm() return 13:19:05: -> tcp_read(3007) using WSAEventSelect 13:19:05: -> tcp_read(3007) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL 13:19:05: -> tcp_read(2950) using WSAEventSelect 13:19:05: -> tcp_read(2950) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- process_this_directory 13:19:05: -> tcp_read(2893) using WSAEventSelect 13:19:05: -> tcp_read(2893) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory 13:19:05: S -> do_dir_proc() after predirent call ParseTag_Dir() 13:19:05: -> tcp_read(2715) using WSAEventSelect 13:19:05: -> tcp_read(2715) immediate timeout previous thread count -1/0 13:19:05: S -> ParseTag_Dir(.,,,0,) 13:19:05: -> tcp_read(2644) using WSAEventSelect 13:19:05: -> tcp_read(2644) immediate timeout previous thread count -1/0 13:19:05: S -> open_directory(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3,.,(null),(null),0,(null),0) 13:19:05: S -> open_directory() directory_stack_size increased by one to 1 13:19:05: -> tcp_read(2468) using WSAEventSelect 13:19:05: -> tcp_read(2468) immediate timeout previous thread count -1/0 13:19:05: S -> open_directory, (!remote) try and find a mapping file in this directory . 13:19:05: -> tcp_read(2377) using WSAEventSelect 13:19:05: -> tcp_read(2377) immediate timeout previous thread count -1/0 13:19:05: S -> RCS_parse(.directory_history,C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.directory_history,v) 13:19:05: -> tcp_read(2201) using WSAEventSelect 13:19:05: -> tcp_read(2201) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.directory_history,v) failed to open file 1 times 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.directory_history,v) file does not exist, so return 0 13:19:05: S -> rcsbuf_open failed 13:19:05: -> tcp_read(1924) using WSAEventSelect 13:19:05: -> tcp_read(1924) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/Attic/.directory_history,v) 13:19:05: -> tcp_read(1828) using WSAEventSelect 13:19:05: -> tcp_read(1828) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/Attic/.directory_history,v) failed to open file 1 times 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/Attic/.directory_history,v) file does not exist, so return 0 13:19:05: -> tcp_read(1575) using WSAEventSelect 13:19:05: -> tcp_read(1575) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_open failed 13:19:05: S -> No mapping file in this directory. 13:19:05: S -> open_directory copy the tag and date. 13:19:05: S -> open_directory copied the tag and date. 13:19:05: -> tcp_read(1375) using WSAEventSelect 13:19:05: -> tcp_read(1375) immediate timeout previous thread count -1/0 13:19:05: S -> Look for rename script file CVS/Rename 13:19:05: -> tcp_read(1319) using WSAEventSelect 13:19:05: -> tcp_read(1319) immediate timeout previous thread count -1/0 13:19:05: S -> Rename script file does not exist 13:19:05: -> tcp_read(1268) using WSAEventSelect 13:19:05: -> tcp_read(1268) immediate timeout previous thread count -1/0 13:19:05: S -> directory opened 13:19:05: S -> fileattr_startdir(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> do_dir_proc() -- call-back dir entry proc (if any) 13:19:05: -> tcp_read(1091) using WSAEventSelect 13:19:05: -> tcp_read(1091) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc. cvsntsrv rlog: Logging SmTestImp3 13:19:05: S -> do_dir_proc() -- only process the dir if the return code was 0. 13:19:05: -> tcp_read(885) using WSAEventSelect 13:19:05: -> tcp_read(885) immediate timeout previous thread count -1/0 13:19:05: S -> do_dir_proc() -- !(frame->which&(W_LOCAL|W_FAKE)) 13:19:05: -> tcp_read(818) using WSAEventSelect 13:19:05: -> tcp_read(818) immediate timeout previous thread count -1/0 13:19:05: S -> debug: 18 13:19:05: S -> debug: 19 13:19:05: S -> debug: 20 13:19:05: -> tcp_read(737) using WSAEventSelect 13:19:05: -> tcp_read(737) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: begin 13:19:05: S -> do_recursion: Check the value in CVSADM_ROOT 13:19:05: S -> do_recursion: Fill in repository with the current repository 13:19:05: -> tcp_read(559) using WSAEventSelect 13:19:05: -> tcp_read(559) immediate timeout previous thread count -1/0 13:19:05: S -> (repository && (server_active || !current_parsed_root->isremote)) 13:19:05: S -> map_repository(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(404) using WSAEventSelect 13:19:05: -> tcp_read(404) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() 13:19:05: -> tcp_read(370) using WSAEventSelect 13:19:05: -> tcp_read(370) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: -> tcp_read(312) using WSAEventSelect 13:19:05: -> tcp_read(312) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: -> tcp_read(188) using WSAEventSelect 13:19:05: -> tcp_read(188) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3,4) 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(15) using WSAEventSelect 13:19:05: -> tcp_read(15) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(5120) using WSAEventSelect 13:19:05: -> tcp_read(5120) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3) 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: -> tcp_read(4671) using WSAEventSelect 13:19:05: -> tcp_read(4671) immediate timeout previous thread count -1/0 13:19:05: S -> map_repository - return(repository) "C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3" 13:19:05: S -> Repository = C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3 13:19:05: S -> Mapped repository = C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3 13:19:05: S -> * The filesdoneproc needs to be called for each directory where files 13:19:05: -> tcp_read(4345) using WSAEventSelect 13:19:05: -> tcp_read(4345) immediate timeout previous thread count -1/0 13:19:05: S -> * processed, or each directory that is processed by a call where no 13:19:05: S -> * directories were passed in. In fact, the only time we don't want to 13:19:05: -> tcp_read(4172) using WSAEventSelect 13:19:05: -> tcp_read(4172) immediate timeout previous thread count -1/0 13:19:05: S -> * call back the filesdoneproc is when we are processing directories that 13:19:05: S -> * were passed in on the command line (or in the special case of `.' when 13:19:05: -> tcp_read(3992) using WSAEventSelect 13:19:05: -> tcp_read(3992) immediate timeout previous thread count -1/0 13:19:05: S -> * we were called with no args 13:19:05: -> tcp_read(3945) using WSAEventSelect 13:19:05: -> tcp_read(3945) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: dirlist == NULL && filelist == NULL 13:19:05: S -> do_recursion: dodoneproc=1 13:19:05: -> tcp_read(3834) using WSAEventSelect 13:19:05: -> tcp_read(3834) immediate timeout previous thread count -1/0 13:19:05: S -> * If filelist or dirlist is already set, we don't look again. Otherwise, 13:19:05: -> tcp_read(3744) using WSAEventSelect 13:19:05: -> tcp_read(3744) immediate timeout previous thread count -1/0 13:19:05: S -> * find the files and directories 13:19:05: S -> * both lists were NULL, so start from scratch 13:19:05: S -> * In the !(which & W_LOCAL) case, we filled in repository 13:19:05: S -> earlier in the function. In the (which & W_LOCAL) case, 13:19:05: S -> the Find_Names function is going to look through the 13:19:05: S -> Entries file. If we do not have a repository, that 13:19:05: -> tcp_read(3336) using WSAEventSelect 13:19:05: -> tcp_read(3336) immediate timeout previous thread count -1/0 13:19:05: S -> does not make sense, so we insist upon having a 13:19:05: S -> repository at this point. Name_Repository will give a 13:19:05: S -> reasonable error message. 13:19:05: -> tcp_read(3149) using WSAEventSelect 13:19:05: -> tcp_read(3149) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: repository != NULL 13:19:05: S -> do_recursion: mapped_repository != NULL 13:19:05: -> tcp_read(3042) using WSAEventSelect 13:19:05: -> tcp_read(3042) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: find the files and fill in entries if appropriate 13:19:05: S -> do_recursion: process_this_directory, call Find_Names() 13:19:05: -> tcp_read(2888) using WSAEventSelect 13:19:05: -> tcp_read(2888) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: -> tcp_read(2796) using WSAEventSelect 13:19:05: -> tcp_read(2796) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(2739) using WSAEventSelect 13:19:05: -> tcp_read(2739) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: -> tcp_read(2672) using WSAEventSelect 13:19:05: -> tcp_read(2672) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3,5) 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(2553) using WSAEventSelect 13:19:05: -> tcp_read(2553) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(2499) using WSAEventSelect 13:19:05: -> tcp_read(2499) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: S -> _lookup_module2 !mod return 0 left,right((null),(null)) 13:19:05: -> tcp_read(2339) using WSAEventSelect 13:19:05: -> tcp_read(2339) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: -> tcp_read(2273) using WSAEventSelect 13:19:05: -> tcp_read(2273) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 13:19:05: -> tcp_read(2090) using WSAEventSelect 13:19:05: -> tcp_read(2090) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: -> tcp_read(2033) using WSAEventSelect 13:19:05: -> tcp_read(2033) immediate timeout previous thread count -1/0 13:19:05: S -> find_virtual_rcs(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> lookup_module2() 13:19:05: -> tcp_read(1925) using WSAEventSelect 13:19:05: -> tcp_read(1925) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: S -> lookup_module2(SmTestImp3,6) 13:19:05: -> tcp_read(1697) using WSAEventSelect 13:19:05: -> tcp_read(1697) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(1570) using WSAEventSelect 13:19:05: -> tcp_read(1570) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3) 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: -> tcp_read(1346) using WSAEventSelect 13:19:05: -> tcp_read(1346) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 13:19:05: -> tcp_read(1163) using WSAEventSelect 13:19:05: -> tcp_read(1163) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: S -> find_rename_rcs(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(1033) using WSAEventSelect 13:19:05: -> tcp_read(1033) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: Find_Names got a filelist 13:19:05: S -> lookup_module2() 13:19:05: -> tcp_read(942) using WSAEventSelect 13:19:05: -> tcp_read(942) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: -> tcp_read(884) using WSAEventSelect 13:19:05: -> tcp_read(884) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(827) using WSAEventSelect 13:19:05: -> tcp_read(827) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: -> tcp_read(760) using WSAEventSelect 13:19:05: -> tcp_read(760) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3,7) 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(641) using WSAEventSelect 13:19:05: -> tcp_read(641) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(587) using WSAEventSelect 13:19:05: -> tcp_read(587) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: S -> _lookup_module2 !mod return 0 left,right((null),(null)) 13:19:05: -> tcp_read(427) using WSAEventSelect 13:19:05: -> tcp_read(427) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: -> tcp_read(289) using WSAEventSelect 13:19:05: -> tcp_read(289) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 13:19:05: -> tcp_read(178) using WSAEventSelect 13:19:05: -> tcp_read(178) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: -> tcp_read(121) using WSAEventSelect 13:19:05: -> tcp_read(121) immediate timeout previous thread count -1/0 13:19:05: S -> find_virtual_dirs(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: S -> lookup_module2() 13:19:05: -> tcp_read(12) using WSAEventSelect 13:19:05: -> tcp_read(12) immediate timeout previous thread count -1/0 13:19:05: -> tcp_read(5120) using WSAEventSelect 13:19:05: -> tcp_read(5120) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2(SmTestImp3) after rename? 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(5017) using WSAEventSelect 13:19:05: -> tcp_read(5017) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: S -> lookup_module2(SmTestImp3,8) 13:19:05: S -> _lookup_module2 !p lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(4777) using WSAEventSelect 13:19:05: -> tcp_read(4777) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3) 13:19:05: -> tcp_read(4619) using WSAEventSelect 13:19:05: -> tcp_read(4619) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 13:19:05: -> tcp_read(4370) using WSAEventSelect 13:19:05: -> tcp_read(4370) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: S -> find_rename_dirs(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(4239) using WSAEventSelect 13:19:05: -> tcp_read(4239) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: process the files (if any) 13:19:05: S -> do_recursion: read lock it if necessary 13:19:05: -> tcp_read(4122) using WSAEventSelect 13:19:05: -> tcp_read(4122) immediate timeout previous thread count -1/0 13:19:05: S -> * For the server, we handle notifications in a completely different 13:19:05: -> tcp_read(4037) using WSAEventSelect 13:19:05: -> tcp_read(4037) immediate timeout previous thread count -1/0 13:19:05: S -> place (server_notify). For local, we can't do them here--we don't 13:19:05: S -> have writelocks in place, and there is no way to get writelocks here 13:19:05: -> tcp_read(3863) using WSAEventSelect 13:19:05: -> tcp_read(3863) immediate timeout previous thread count -1/0 13:19:05: S -> do_recursion: process the files by calling walklist() 13:19:05: S -> do_file_proc: process the files ".cvsignore" 13:19:05: -> tcp_read(3730) using WSAEventSelect 13:19:05: -> tcp_read(3730) immediate timeout previous thread count -1/0 13:19:05: S -> (server_active || !current_parsed_root->isremote) 13:19:05: -> tcp_read(3663) using WSAEventSelect 13:19:05: -> tcp_read(3663) immediate timeout previous thread count -1/0 13:19:05: S -> map_filename(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3,.cvsignore) 13:19:05: S -> map_filename - call map_repository(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore) 13:19:05: -> tcp_read(3479) using WSAEventSelect 13:19:05: -> tcp_read(3479) immediate timeout previous thread count -1/0 13:19:05: S -> map_repository(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore) 13:19:05: -> tcp_read(3396) using WSAEventSelect 13:19:05: -> tcp_read(3396) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() 13:19:05: S -> lookup_module2(SmTestImp3/.cvsignore) after rename? 13:19:05: -> tcp_read(3293) using WSAEventSelect 13:19:05: -> tcp_read(3293) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() call _lookup_module2() 13:19:05: -> tcp_read(3236) using WSAEventSelect 13:19:05: -> tcp_read(3236) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 - continue_if_no_mod2=1 (default) 13:19:05: S -> lookup_module2(SmTestImp3/.cvsignore,9) 13:19:05: -> tcp_read(3112) using WSAEventSelect 13:19:05: -> tcp_read(3112) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 p!=NULL lookup_repository_module(SmTestImp3) 13:19:05: S -> lookup_repository_module(SmTestImp3) 13:19:05: -> tcp_read(2980) using WSAEventSelect 13:19:05: -> tcp_read(2980) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() calls to lookup_repository_module() returned nothing 13:19:05: -> tcp_read(2893) using WSAEventSelect 13:19:05: -> tcp_read(2893) immediate timeout previous thread count -1/0 13:19:05: S -> _lookup_module2 !mod return 0 left,right(,SmTestImp3/.cvsignore) 13:19:05: S -> lookup_module2() call _lookup_module2 returned 0 13:19:05: -> tcp_read(2745) using WSAEventSelect 13:19:05: -> tcp_read(2745) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() no recursion permitted so give up now 13:19:05: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL 13:19:05: -> tcp_read(2562) using WSAEventSelect 13:19:05: -> tcp_read(2562) immediate timeout previous thread count -1/0 13:19:05: S -> lookup_module2() return ret=0 renamed=0 13:19:05: S -> map_repository - return(repository) "C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore" 13:19:05: S -> map_filename - map_repository() returns C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore 13:19:05: -> tcp_read(2293) using WSAEventSelect 13:19:05: -> tcp_read(2293) immediate timeout previous thread count -1/0 13:19:05: S -> do_file_proc: mapped name of file ".cvsignore" 13:19:05: S -> do_file_proc: frfile->frame->dosrcs && mapped_file_repository 13:19:05: -> tcp_read(2150) using WSAEventSelect 13:19:05: -> tcp_read(2150) immediate timeout previous thread count -1/0 13:19:05: S -> RCS_parse(.cvsignore,C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3) 13:19:05: -> tcp_read(2072) using WSAEventSelect 13:19:05: -> tcp_read(2072) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_open(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore,v) 13:19:05: -> tcp_read(1990) using WSAEventSelect 13:19:05: -> tcp_read(1990) immediate timeout previous thread count -1/0 13:19:05: S -> normalize_path(C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore,v) 13:19:05: -> tcp_read(1905) using WSAEventSelect 13:19:05: -> tcp_read(1905) immediate timeout previous thread count -1/0 13:19:05: S -> ...returns C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore,v 13:19:05: S -> Lock server command sent: Lock Read Full|C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore,v 13:19:05: -> tcp_read(1712) using WSAEventSelect 13:19:05: -> tcp_read(1712) immediate timeout previous thread count -1/0 13:19:05: S -> Lock server command received: 000 OK Lock granted (187) 13:19:05: S -> do_lock_server: [13:19:05] obtained lock 187 in .cvsignore,v 13:19:05: -> tcp_read(1558) using WSAEventSelect 13:19:05: -> tcp_read(1558) immediate timeout previous thread count -1/0 13:19:05: S -> do_lock_file: lock server returns 187 13:19:05: -> tcp_read(1503) using WSAEventSelect 13:19:05: -> tcp_read(1503) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_open(/myrepo/SmTestImp3/.cvsignore,v) -- obtained a new lock 13:19:05: -> tcp_read(1418) using WSAEventSelect 13:19:05: -> tcp_read(1418) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_fill(windows,BigMemory=0) 13:19:05: S -> rcsbuf_fill(0): expanded to 8192 (was: 0, req: 5120, inc: 5120, RCSBUF_BUFSIZE: 5120). 13:19:05: -> tcp_read(1264) using WSAEventSelect 13:19:05: -> tcp_read(1264) immediate timeout previous thread count -1/0 13:19:05: S -> rcsbuf_fill got EOF trying to read 5120 bytes (errno=0) cvsntsrv [rlog aborted]: EOF in value in RCS file C:/PROGRA~2/MARCHH~1/myrepo/SmTestImp3/.cvsignore,v 13:19:05: -> Server: return from the requested function 13:19:05: -> Unloading - about to clear list 13:19:05: -> Unloading - cleared list 13:19:05: -> Unloading sspi 13:19:05: -> Process 5420 exiting 13:19:05: -> free_directory() 13:19:05: -> free_modules2() directory_stack_size and rubbish set to zero 13:19:05: -> Free CVS_Username and cvs_locale 13:19:05: -> Perform SYSTEM_CLEANUP () 13:19:05: -> Free global_session_time and global_real_session_time 13:19:05: -> CCvsgui::Close 13:19:05: -> main return EXIT_FAILURE 13:19:05: -> Set the terminate semaphore 13:19:05: -> Free trace_file