11:55:09:   -> Client trace started, level=3.
11:55:09:   -> Tracelevel set to 4.  PID is 2012
11:55:09:   -> Session ID is 7dc591c806d0835
11:55:09:   -> Session time is Wed May 17 16:55:09 2017
11:55:09:   -> Client was compiled Nov 17 2016 00:47:19
11:55:09:   -> Client was compiled with MSVC C/C++ 1310
11:55:09:   -> Client build platform is microsoft-windows-x86
11:55:09:   -> Build version is CVSNT 2.8.01 (Soolin) Build 6145
11:55:09:   -> User name is admin
11:55:09:   -> Client running on "Windows Server 2012 x64 Server 4.0  (Build 9200) [6.2.9200] "
11:55:09:   -> /3GB is enabled, highest user address is fffeffff
11:55:09:   -> Client has      26 percent of memory in use.
11:55:09:   -> Client has 2096628 total Kbytes of physical memory*.
11:55:09:   -> Client has 1542432 free Kbytes of physical memory*.
11:55:09:   -> Client has 2096628 total Kbytes of physical memory.
11:55:09:   -> Client has 1542432 free Kbytes of physical memory.
11:55:09:   -> Client has 2489844 total Kbytes of paging file.
11:55:09:   -> Client has 1928532 free Kbytes of paging file.
11:55:09:   -> Client has 4194176 total Kbytes of virtual memory.
11:55:09:   -> Client has 4117680 free Kbytes of virtual memory.
11:55:09:   -> Client has       0 free Kbytes of extended memory.
11:55:09:   -> The custom heap has the low-fragmentation heap enabled.
11:55:09:   -> The minimum large page size is 2097152.
11:55:09:   -> Walking heap 0X02350000...
11:55:09:   -> Region
11:55:09:   ->   4096 bytes committed
11:55:09:   ->   258048 bytes uncommitted
11:55:09:   ->   First block address: 0X023504A0
11:55:09:   ->   Last block address: 0X0238F000
11:55:09:   ->   Data portion begins at: 0X02350000
11:55:09:   ->   Size: 1176 bytes
11:55:09:   ->   Overhead: 0 bytes
11:55:09:   ->   Region index: 0
11:55:09:   -> Block
11:55:09:   ->   Data portion begins at: 0X023505B0
11:55:09:   ->   Size: 2608 bytes
11:55:09:   ->   Overhead: 16 bytes
11:55:09:   ->   Region index: 0
11:55:09:   -> Uncommitted range
11:55:09:   ->   Data portion begins at: 0X02351000
11:55:09:   ->   Size: 253952 bytes
11:55:09:   ->   Overhead: 0 bytes
11:55:09:   ->   Region index: 0
11:55:09:   -> Client Hardware information (Wow64): 
11:55:09:   ->   Number of processors: 2
11:55:09:   ->   Processor type: 8664
11:55:09:   ->   processor architecture: x64
11:55:09:   ->   processor type 8664/Xeon model: unknown
11:55:09:   -> Free space in C:\Users\ADMIN~1.DEL\AppData\Local\Temp 2845929472
11:55:09:   -> Total space in C:\Users\ADMIN~1.DEL\AppData\Local\Temp 4292866048
11:55:09:   -> Total Free space in C:\Users\ADMIN~1.DEL\AppData\Local\Temp 2845929472
11:55:09:   -> Loading protocol sspi as sspi.dll
11:55:09:   -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/protocols/sspi.dll
11:55:09:   -> main loop with CVSROOT=:sspi;force=kerberos:d-w2012x64-m:/myrepo
11:55:09:   -> main - about to start the server yes
11:55:09:   -> start_server(verify_only=0)
11:55:09:   -> client start - client_protocol->connect
11:55:09:   -> Buffer size is 5120
11:55:09:   -> sspi_connect
11:55:09:   -> tcp_write(20,496)
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> SSPI:ClientAuthenticate(Kerberos,*NONE*,*NONE*) !Schannel - AcquireCredentialsHandle
11:55:09:   -> SSPI:ClientAuthenticate() !Schannel - AcquireCredentialsHandle result = OK
11:55:09:   -> SSPI:ClientAuthenticate() InitializeSecurityContext
11:55:09:   -> tcp_write(2,496)
11:55:09:   -> tcp_write(1638,496)
11:55:09:   -> tcp_write(8,496)
11:55:09:   -> client start - Loop, getting responses from the server.
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 73(I), index=0
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 32( ), index=1
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 76(L), index=2
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 79(O), index=3
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 86(V), index=4
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 69(E), index=5
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 32( ), index=6
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 89(Y), index=7
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 79(O), index=8
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 85(U), index=9
11:55:09:   -> tcp_read(1) using WSAEventSelect
11:55:09:   -> tcp_read(1) immediate timeout previous thread count -1/0
11:55:09:   -> recv_line - got 1 chars, first is 10( ), index=10
11:55:09:   -> client start - got "I LOVE YOU"
11:55:09:   -> client start - continue login.
11:55:09:   -> client start - server started.
11:55:09:   -> client start - send Valid-responses to server.
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=0
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=15
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=2 nbytes=16
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=18
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=5 nbytes=19
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=24
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=25
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=39
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=10 nbytes=40
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=50
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=9 nbytes=51
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=60
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=61
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=69
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=9 nbytes=70
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=79
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=80
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=87
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=88
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=95
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=96
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=111
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=6 nbytes=112
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=118
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=119
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=126
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=127
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=135
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=136
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=150
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=4 nbytes=151
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=155
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=156
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=164
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=165
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=172
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=173
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=180
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=181
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=193
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=20 nbytes=194
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=214
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=22 nbytes=215
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=237
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=10 nbytes=238
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=248
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=249
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=261
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=262
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=270
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=271
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=279
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=280
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=296
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=297
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=314
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=315
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=327
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=6 nbytes=328
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=334
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=335
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=347
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=348
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=349
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=350
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=357
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=358
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=359
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=360
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=361
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=2 nbytes=362
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=364
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=365
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=381
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=382
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=396
11:55:09:   -> client start - send valid-requests to server.
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=397
11:55:09:   -> tcp_write(412,496)
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=412
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=424
11:55:09:   -> tcp_write(13,496)
11:55:09:   -> client start - rootless_encryption.
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=5 nbytes=425
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=430
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=437
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=438
11:55:09:   -> tcp_write(29,496)
11:55:09:   -> tcp_read(4175) using WSAEventSelect
11:55:09:   -> tcp_read(4175) immediate timeout previous thread count -1/0
11:55:09:   -> Server codepage is CP1252
11:55:09:   -> Client codepage is CP1252
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=454
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=32 nbytes=469
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=501
11:55:09:   -> tcp_write(48,496)
11:55:09:   -> tcp_read(4168) using WSAEventSelect
11:55:09:   -> tcp_read(4168) immediate timeout previous thread count -1/0
11:55:09:   -> Server version is CVSNT 2.8.01 (Soolin) Build 6145
11:55:09:   -> Client version is CVSNT 2.8.01 (Soolin) Build 6145
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=502
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=519
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=536
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=553
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=570
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=587
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=588
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=589
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=590
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=591
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=592
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=593
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=594
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=595
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=596
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=597
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=598
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=599
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=600
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=601
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=602
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=603
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=604
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=605
11:55:09:   -> Requesting server cvsignore
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=606
11:55:09:   -> tcp_write(119,496)
11:55:09:   -> tcp_read(4135) using WSAEventSelect
11:55:09:   -> tcp_read(4135) immediate timeout previous thread count -1/0
11:55:09:   -> wrap_add(*.a -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.a"
11:55:09:   -> Call CGetOptions::getOpt: "*.a -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ai -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ai"
11:55:09:   -> Call CGetOptions::getOpt: "*.ai -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.avi -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.avi"
11:55:09:   -> Call CGetOptions::getOpt: "*.avi -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.bin -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.bin"
11:55:09:   -> Call CGetOptions::getOpt: "*.bin -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.bmp -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.bmp"
11:55:09:   -> Call CGetOptions::getOpt: "*.bmp -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.bz2 -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.bz2"
11:55:09:   -> Call CGetOptions::getOpt: "*.bz2 -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.cdd -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.cdd"
11:55:09:   -> Call CGetOptions::getOpt: "*.cdd -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.cdmz -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.cdmz"
11:55:09:   -> Call CGetOptions::getOpt: "*.cdmz -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.chm -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.chm"
11:55:09:   -> Call CGetOptions::getOpt: "*.chm -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.class -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.class"
11:55:09:   -> Call CGetOptions::getOpt: "*.class -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.cur -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.cur"
11:55:09:   -> Call CGetOptions::getOpt: "*.cur -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcproject -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcproject"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcproject -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcprocess -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcprocess"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcprocess -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcmessage -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcmessage"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcmessage -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcplatform -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcplatform"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcplatform -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcphyspf -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcphyspf"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcphyspf -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcres -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcres"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcres -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcrset -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcrset"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcrset -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcruntime -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcruntime"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcruntime -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dcphysrt -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dcphysrt"
11:55:09:   -> Call CGetOptions::getOpt: "*.dcphysrt -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dll -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dll"
11:55:09:   -> Call CGetOptions::getOpt: "*.dll -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.doc -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.doc"
11:55:09:   -> Call CGetOptions::getOpt: "*.doc -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.docx -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.docx"
11:55:09:   -> Call CGetOptions::getOpt: "*.docx -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.docm -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.docm"
11:55:09:   -> Call CGetOptions::getOpt: "*.docm -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.fp -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.fp"
11:55:09:   -> Call CGetOptions::getOpt: "*.fp -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.pptx -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.pptx"
11:55:09:   -> Call CGetOptions::getOpt: "*.pptx -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.pptm -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.pptm"
11:55:09:   -> Call CGetOptions::getOpt: "*.pptm -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.xlsx -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.xlsx"
11:55:09:   -> Call CGetOptions::getOpt: "*.xlsx -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.xlsm -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.xlsm"
11:55:09:   -> Call CGetOptions::getOpt: "*.xlsm -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.odt -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.odt"
11:55:09:   -> Call CGetOptions::getOpt: "*.odt -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ods -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ods"
11:55:09:   -> Call CGetOptions::getOpt: "*.ods -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.odp -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.odp"
11:55:09:   -> Call CGetOptions::getOpt: "*.odp -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.odb -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.odb"
11:55:09:   -> Call CGetOptions::getOpt: "*.odb -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.odg -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.odg"
11:55:09:   -> Call CGetOptions::getOpt: "*.odg -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.odf -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.odf"
11:55:09:   -> Call CGetOptions::getOpt: "*.odf -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.mpp -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.mpp"
11:55:09:   -> Call CGetOptions::getOpt: "*.mpp -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.dvi -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.dvi"
11:55:09:   -> Call CGetOptions::getOpt: "*.dvi -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.exe -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.exe"
11:55:09:   -> Call CGetOptions::getOpt: "*.exe -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.gif -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.gif"
11:55:09:   -> Call CGetOptions::getOpt: "*.gif -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.gz -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.gz"
11:55:09:   -> Call CGetOptions::getOpt: "*.gz -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.hqx -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.hqx"
11:55:09:   -> Call CGetOptions::getOpt: "*.hqx -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ico -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ico"
11:55:09:   -> Call CGetOptions::getOpt: "*.ico -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ilk -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ilk"
11:55:09:   -> Call CGetOptions::getOpt: "*.ilk -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.indd -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.indd"
11:55:09:   -> Call CGetOptions::getOpt: "*.indd -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.iso -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.iso"
11:55:09:   -> Call CGetOptions::getOpt: "*.iso -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.lib -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.lib"
11:55:09:   -> Call CGetOptions::getOpt: "*.lib -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.jar -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.jar"
11:55:09:   -> Call CGetOptions::getOpt: "*.jar -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.jpg -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.jpg"
11:55:09:   -> Call CGetOptions::getOpt: "*.jpg -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.jpeg -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.jpeg"
11:55:09:   -> Call CGetOptions::getOpt: "*.jpeg -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.lnk -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.lnk"
11:55:09:   -> Call CGetOptions::getOpt: "*.lnk -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.mpg -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.mpg"
11:55:09:   -> Call CGetOptions::getOpt: "*.mpg -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.mpeg -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.mpeg"
11:55:09:   -> Call CGetOptions::getOpt: "*.mpeg -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.mov -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.mov"
11:55:09:   -> Call CGetOptions::getOpt: "*.mov -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.mp3 -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.mp3"
11:55:09:   -> Call CGetOptions::getOpt: "*.mp3 -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ncb -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ncb"
11:55:09:   -> Call CGetOptions::getOpt: "*.ncb -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.o -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.o"
11:55:09:   -> Call CGetOptions::getOpt: "*.o -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ogg -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ogg"
11:55:09:   -> Call CGetOptions::getOpt: "*.ogg -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ocx -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ocx"
11:55:09:   -> Call CGetOptions::getOpt: "*.ocx -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.obj -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.obj"
11:55:09:   -> Call CGetOptions::getOpt: "*.obj -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.pdb -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.pdb"
11:55:09:   -> Call CGetOptions::getOpt: "*.pdb -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.pdf -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.pdf"
11:55:09:   -> Call CGetOptions::getOpt: "*.pdf -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.png -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.png"
11:55:09:   -> Call CGetOptions::getOpt: "*.png -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ppt -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ppt"
11:55:09:   -> Call CGetOptions::getOpt: "*.ppt -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.psd -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.psd"
11:55:09:   -> Call CGetOptions::getOpt: "*.psd -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.res -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.res"
11:55:09:   -> Call CGetOptions::getOpt: "*.res -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.rpm -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.rpm"
11:55:09:   -> Call CGetOptions::getOpt: "*.rpm -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.sit -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.sit"
11:55:09:   -> Call CGetOptions::getOpt: "*.sit -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.so -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.so"
11:55:09:   -> Call CGetOptions::getOpt: "*.so -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.tar -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.tar"
11:55:09:   -> Call CGetOptions::getOpt: "*.tar -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.tga -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.tga"
11:55:09:   -> Call CGetOptions::getOpt: "*.tga -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.tgz -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.tgz"
11:55:09:   -> Call CGetOptions::getOpt: "*.tgz -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.tif -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.tif"
11:55:09:   -> Call CGetOptions::getOpt: "*.tif -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.tiff -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.tiff"
11:55:09:   -> Call CGetOptions::getOpt: "*.tiff -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.ttf -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.ttf"
11:55:09:   -> Call CGetOptions::getOpt: "*.ttf -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.uir -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.uir"
11:55:09:   -> Call CGetOptions::getOpt: "*.uir -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.wav -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.wav"
11:55:09:   -> Call CGetOptions::getOpt: "*.wav -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.wmv -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.wmv"
11:55:09:   -> Call CGetOptions::getOpt: "*.wmv -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.xls -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.xls"
11:55:09:   -> Call CGetOptions::getOpt: "*.xls -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.zip -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.zip"
11:55:09:   -> Call CGetOptions::getOpt: "*.zip -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> wrap_add(*.Z -kb, 0, 0, 0, 0)
11:55:09:   -> Call CFileAccess::mimetype: "*.Z"
11:55:09:   -> Call CGetOptions::getOpt: "*.Z -kb" "+k:x:m:t:"
11:55:09:   -> wrap_add() Sort.
11:55:09:   -> Requesting server cvswrappers
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=621
11:55:09:   -> tcp_write(17,496)
11:55:09:   -> tcp_read(4133) using WSAEventSelect
11:55:09:   -> tcp_read(4133) immediate timeout previous thread count -1/0
11:55:09:   -> Got the server cvswrappers
11:55:09:   -> The first item in the sorted list is "b" with wildcard "*.a"
11:55:09:   -> Requesting server cvsrc (read-cvsrc2)
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=638
11:55:09:   -> tcp_write(12,496)
11:55:09:   -> tcp_read(4129) using WSAEventSelect
11:55:09:   -> tcp_read(4129) immediate timeout previous thread count -1/0
11:55:09:   -> Parsing global cvsrc started
11:55:09:   -> Parsing global cvsrc finished 0 lines.
11:55:09:   -> No local C:\Users\admin.DELTA/.cvsrc to parse
11:55:09:   -> Server: call the requested function ls
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=9 nbytes=650
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=2 nbytes=659
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=661
11:55:09:   -> send_to_server_untranslated() calls buf_output() len=4 nbytes=662
11:55:09:   -> tcp_write(16,496)
11:55:09:   -> tcp_read(4127) using WSAEventSelect
11:55:09:   -> tcp_read(4127) immediate timeout previous thread count -1/0
11:55:09: S -> server_main started
11:55:09: S -> server_main process id is 1908
11:55:09:   -> tcp_read(4042) using WSAEventSelect
11:55:09:   -> tcp_read(4042) immediate timeout previous thread count -1/0
11:55:09: S -> server_main CVS2_SERVER_SLEEP not set.
11:55:09:   -> tcp_read(3986) using WSAEventSelect
11:55:09:   -> tcp_read(3986) immediate timeout previous thread count -1/0
11:55:09: S -> run plugintools proc server
11:55:09: S -> run_trigger()
11:55:09:   -> tcp_read(3910) using WSAEventSelect
11:55:09:   -> tcp_read(3910) immediate timeout previous thread count -1/0
11:55:09: S -> LoadTrigger(audit.dll)
11:55:09:   -> tcp_read(3870) using WSAEventSelect
11:55:09:   -> tcp_read(3870) immediate timeout previous thread count -1/0
11:55:09: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/audit.dll
11:55:09:   -> tcp_read(3778) using WSAEventSelect
11:55:09:   -> tcp_read(3778) immediate timeout previous thread count -1/0
11:55:09: S -> Audit trigger not enabled.
11:55:09:   -> tcp_read(3734) using WSAEventSelect
11:55:09:   -> tcp_read(3734) immediate timeout previous thread count -1/0
11:55:09: S -> Couldn't load trigger audit.dll
11:55:09:   -> tcp_read(3685) using WSAEventSelect
11:55:09:   -> tcp_read(3685) immediate timeout previous thread count -1/0
11:55:09: S -> LoadTrigger(bugzilla.dll)
11:55:09:   -> tcp_read(3642) using WSAEventSelect
11:55:09:   -> tcp_read(3642) immediate timeout previous thread count -1/0
11:55:09: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/bugzilla.dll
11:55:09:   -> tcp_read(3547) using WSAEventSelect
11:55:09:   -> tcp_read(3547) immediate timeout previous thread count -1/0
11:55:09: S -> Defect Tracking trigger not enabled (preinit).
11:55:09:   -> tcp_read(3483) using WSAEventSelect
11:55:09:   -> tcp_read(3483) immediate timeout previous thread count -1/0
11:55:09: S -> Couldn't load trigger bugzilla.dll
11:55:09:   -> tcp_read(3431) using WSAEventSelect
11:55:09:   -> tcp_read(3431) immediate timeout previous thread count -1/0
11:55:09: S -> Checking admin file C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/admin for user admin
11:55:09:   -> tcp_read(3337) using WSAEventSelect
11:55:09:   -> tcp_read(3337) immediate timeout previous thread count -1/0
11:55:09: S -> Checked admin file (null) for user admin they are not an admin.
11:55:09: S -> Check windows admin for user.
11:55:09:   -> tcp_read(3209) using WSAEventSelect
11:55:09:   -> tcp_read(3209) immediate timeout previous thread count -1/0
11:55:09: S -> Determine if the current thread is running as a user that is a member of the local admins group.
11:55:09:   -> tcp_read(3095) using WSAEventSelect
11:55:09:   -> tcp_read(3095) immediate timeout previous thread count -1/0
11:55:09: S -> No - is NOT admin (WIN32).
11:55:09: S -> Check windows admin for user completed.
11:55:09:   -> tcp_read(2994) using WSAEventSelect
11:55:09:   -> tcp_read(2994) immediate timeout previous thread count -1/0
11:55:09: S -> InitTrigger(checkout.dll)
11:55:09:   -> tcp_read(2951) using WSAEventSelect
11:55:09:   -> tcp_read(2951) immediate timeout previous thread count -1/0
11:55:09: S -> Checkout trigger not enabled.
11:55:09:   -> tcp_read(2904) using WSAEventSelect
11:55:09:   -> tcp_read(2904) immediate timeout previous thread count -1/0
11:55:09: S -> Couldn't load trigger checkout.dll
11:55:09:   -> tcp_read(2852) using WSAEventSelect
11:55:09:   -> tcp_read(2852) immediate timeout previous thread count -1/0
11:55:09: S -> InitTrigger(email.dll)
11:55:09:   -> tcp_read(2812) using WSAEventSelect
11:55:09:   -> tcp_read(2812) immediate timeout previous thread count -1/0
11:55:09: S -> Email trigger not enabled.
11:55:09: S -> Couldn't load trigger email.dll
11:55:09:   -> tcp_read(2719) using WSAEventSelect
11:55:09:   -> tcp_read(2719) immediate timeout previous thread count -1/0
11:55:09: S -> InitTrigger(info.dll)
11:55:09:   -> tcp_read(2680) using WSAEventSelect
11:55:09:   -> tcp_read(2680) immediate timeout previous thread count -1/0
11:55:09: S -> LoadTrigger(make.dll)
11:55:09:   -> tcp_read(2641) using WSAEventSelect
11:55:09:   -> tcp_read(2641) immediate timeout previous thread count -1/0
11:55:09: S -> CLibraryAccess::Load loading C:\PROGRA~2\CVSSUI~1\CVSNT/triggers/make.dll
11:55:09:   -> tcp_read(2550) using WSAEventSelect
11:55:09:   -> tcp_read(2550) immediate timeout previous thread count -1/0
11:55:09: S -> Make trigger is not enabled (preinit fail).
11:55:09:   -> tcp_read(2489) using WSAEventSelect
11:55:09:   -> tcp_read(2489) immediate timeout previous thread count -1/0
11:55:09: S -> Couldn't load trigger make.dll
11:55:09: S -> InitTrigger(script.dll)
11:55:09:   -> tcp_read(2400) using WSAEventSelect
11:55:09:   -> tcp_read(2400) immediate timeout previous thread count -1/0
11:55:09: S -> Script trigger not enabled.
11:55:09:   -> tcp_read(2355) using WSAEventSelect
11:55:09:   -> tcp_read(2355) immediate timeout previous thread count -1/0
11:55:09: S -> Couldn't load trigger script.dll
11:55:09: S -> InitTrigger(sync.dll)
11:55:09:   -> tcp_read(2266) using WSAEventSelect
11:55:09:   -> tcp_read(2266) immediate timeout previous thread count -1/0
11:55:09: S -> Sync trigger not enabled.

11:55:09: S -> Couldn't load trigger sync.dll
11:55:09:   -> tcp_read(2172) using WSAEventSelect
11:55:09:   -> tcp_read(2172) immediate timeout previous thread count -1/0
11:55:09: S -> Call pre-loaded 'info.dll'
11:55:09: S -> plugintools_proc()
11:55:09:   -> tcp_read(2092) using WSAEventSelect
11:55:09:   -> tcp_read(2092) immediate timeout previous thread count -1/0
11:55:09: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:55:09: S -> run_trigger returning 0
11:55:09:   -> tcp_read(1983) using WSAEventSelect
11:55:09:   -> tcp_read(1983) immediate timeout previous thread count -1/0
11:55:09: S -> run precommand proc server
11:55:09:   -> tcp_read(1939) using WSAEventSelect
11:55:09:   -> tcp_read(1939) immediate timeout previous thread count -1/0
11:55:09: S -> run_trigger()
11:55:09: S -> Call pre-loaded 'info.dll'
11:55:09:   -> tcp_read(1864) using WSAEventSelect
11:55:09:   -> tcp_read(1864) immediate timeout previous thread count -1/0
11:55:09: S -> precommand_proc()
11:55:09:   -> tcp_read(1829) using WSAEventSelect
11:55:09:   -> tcp_read(1829) immediate timeout previous thread count -1/0
11:55:09: S -> default_trigger: parse_info(CVSROOT/precommand,%r %c,%<a,<null>)
11:55:09:   -> tcp_read(1747) using WSAEventSelect
11:55:09:   -> tcp_read(1747) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::open('C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/precommand','rb')
11:55:09:   -> tcp_read(1657) using WSAEventSelect
11:55:09:   -> tcp_read(1657) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::open() setvbuf(_IONBF)
11:55:09:   -> tcp_read(1604) using WSAEventSelect
11:55:09:   -> tcp_read(1604) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::close()
11:55:09: S -> CFileAccess::close()
11:55:09:   -> tcp_read(1528) using WSAEventSelect
11:55:09:   -> tcp_read(1528) immediate timeout previous thread count -1/0
11:55:09: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:55:09: S -> run_trigger returning 0
11:55:09:   -> tcp_read(1419) using WSAEventSelect
11:55:09:   -> tcp_read(1419) immediate timeout previous thread count -1/0
11:55:09: S -> open_module(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1361) using WSAEventSelect
11:55:09:   -> tcp_read(1361) immediate timeout previous thread count -1/0
11:55:09: S -> do_module(., Listing, , )
11:55:09:   -> tcp_read(1318) using WSAEventSelect
11:55:09:   -> tcp_read(1318) immediate timeout previous thread count -1/0
11:55:09: S -> do_module calls map_repository()
11:55:09: S -> map_repository(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1207) using WSAEventSelect
11:55:09:   -> tcp_read(1207) immediate timeout previous thread count -1/0
11:55:09: S -> do_module call to map_repository() has returned something
11:55:09:   -> tcp_read(1132) using WSAEventSelect
11:55:09:   -> tcp_read(1132) immediate timeout previous thread count -1/0
11:55:09: S -> * check to see if mname is a directory or file 
11:55:09: S -> do_module calls isdir(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(999) using WSAEventSelect
11:55:09:   -> tcp_read(999) immediate timeout previous thread count -1/0
11:55:09: S -> do_module yes it is a directory ...
11:55:09: S -> * OK, we have now set up modargv with the actual file/directory we want to work on.
11:55:09:   -> tcp_read(845) using WSAEventSelect
11:55:09:   -> tcp_read(845) immediate timeout previous thread count -1/0
11:55:09: S -> run premodule trigger
11:55:09:   -> tcp_read(806) using WSAEventSelect
11:55:09:   -> tcp_read(806) immediate timeout previous thread count -1/0
11:55:09: S -> run_trigger()
11:55:09: S -> Call pre-loaded 'info.dll'
11:55:09: S -> premodule_proc(.)
11:55:09: S -> default_trigger: parse_info(CVSROOT/premodule,%r/%p %c %o,,.)
11:55:09: S -> CFileAccess::open('C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/premodule','rb')
11:55:09: S -> CFileAccess::open() setvbuf(_IONBF)
11:55:09:   -> tcp_read(475) using WSAEventSelect
11:55:09:   -> tcp_read(475) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::close()
11:55:09:   -> tcp_read(437) using WSAEventSelect
11:55:09:   -> tcp_read(437) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::close()
11:55:09: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:55:09:   -> tcp_read(331) using WSAEventSelect
11:55:09:   -> tcp_read(331) immediate timeout previous thread count -1/0
11:55:09: S -> run_trigger returning 0
11:55:09:   -> tcp_read(290) using WSAEventSelect
11:55:09:   -> tcp_read(290) immediate timeout previous thread count -1/0
11:55:09: S -> do_modue(1): call the callback_proc((null))
Listing modules on server

11:55:09:   -> tcp_read(198) using WSAEventSelect
11:55:09:   -> tcp_read(198) immediate timeout previous thread count -1/0
11:55:09: S -> map_repository(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(137) using WSAEventSelect
11:55:09:   -> tcp_read(137) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion(.,(null))
11:55:09:   -> tcp_read(94) using WSAEventSelect
11:55:09:   -> tcp_read(94) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion() dirleaveproc is NULL
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion() update_dir set to "."
11:55:09: S -> start_recursion - argument count (argc) == 0
11:55:09:   -> tcp_read(5039) using WSAEventSelect
11:55:09:   -> tcp_read(5039) immediate timeout previous thread count -1/0
11:55:09: S -> * There were no arguments, so we'll probably just recurse. The
11:55:09: S -> * exception to the rule is when we are called from a directory
11:55:09:   -> tcp_read(4879) using WSAEventSelect
11:55:09:   -> tcp_read(4879) immediate timeout previous thread count -1/0
11:55:09: S -> * without any CVS administration files.  That has always meant to
11:55:09:   -> tcp_read(4796) using WSAEventSelect
11:55:09:   -> tcp_read(4796) immediate timeout previous thread count -1/0
11:55:09: S -> * process each of the sub-directories, so we pretend like we were
11:55:09:   -> tcp_read(4713) using WSAEventSelect
11:55:09:   -> tcp_read(4713) immediate timeout previous thread count -1/0
11:55:09: S -> * called with the list of sub-dirs of the current dir as args
11:55:09:   -> tcp_read(4634) using WSAEventSelect
11:55:09:   -> tcp_read(4634) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion: !just_subdirs - call addlist(dirlist==NULL, ".")
11:55:09: S -> start_recursion: addlist() returns and now dirlist!=NULL 
11:55:09:   -> tcp_read(4476) using WSAEventSelect
11:55:09:   -> tcp_read(4476) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion/do_the_work: do_recursion() on the frame (dirlist != NULL).
11:55:09:   -> tcp_read(4383) using WSAEventSelect
11:55:09:   -> tcp_read(4383) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: begin
11:55:09: S -> do_recursion: Check the value in CVSADM_ROOT 
11:55:09:   -> tcp_read(4283) using WSAEventSelect
11:55:09:   -> tcp_read(4283) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: Fill in repository with the current repository
11:55:09:   -> tcp_read(4205) using WSAEventSelect
11:55:09:   -> tcp_read(4205) immediate timeout previous thread count -1/0
11:55:09: S -> (repository && (server_active || !current_parsed_root->isremote))
11:55:09: S -> map_repository(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(4061) using WSAEventSelect
11:55:09:   -> tcp_read(4061) immediate timeout previous thread count -1/0
11:55:09: S -> Repository = C:\PROGRA~3\MARCHH~1\myrepo/.
11:55:09:   -> tcp_read(4001) using WSAEventSelect
11:55:09:   -> tcp_read(4001) immediate timeout previous thread count -1/0
11:55:09: S -> Mapped repository = C:\PROGRA~3\MARCHH~1\myrepo
11:55:09: S -> (repository && (server_active || !current_parsed_root->isremote))
11:55:09:   -> tcp_read(3853) using WSAEventSelect
11:55:09:   -> tcp_read(3853) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag()
11:55:09:   -> tcp_read(3825) using WSAEventSelect
11:55:09:   -> tcp_read(3825) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir((null),tag=<something>,,nonbranch=0,)
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09:   -> tcp_read(3666) using WSAEventSelect
11:55:09:   -> tcp_read(3666) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open fn="CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(3581) using WSAEventSelect
11:55:09:   -> tcp_read(3581) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09:   -> tcp_read(3536) using WSAEventSelect
11:55:09:   -> tcp_read(3536) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09: S -> verify_access C:\PROGRA~3\MARCHH~1\myrepo,.,(null)
11:55:09:   -> tcp_read(3377) using WSAEventSelect
11:55:09:   -> tcp_read(3377) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm(C:\PROGRA~3\MARCHH~1\myrepo,(null),read,(null),(null))
11:55:09:   -> tcp_read(3293) using WSAEventSelect
11:55:09:   -> tcp_read(3293) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm: verify_owner_dir()
11:55:09: S -> verify_owner(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3185) using WSAEventSelect
11:55:09:   -> tcp_read(3185) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3111) using WSAEventSelect
11:55:09:   -> tcp_read(3111) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3051) using WSAEventSelect
11:55:09:   -> tcp_read(3051) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() malloc [strlen(C:\PROGRA~3\MARCHH~1\myrepo)=]27+[sizeof(CVS/fileattr.xml)=]4+20=51
11:55:09: S -> fileattr_read() xmalloc fname OK
11:55:09:   -> tcp_read(2884) using WSAEventSelect
11:55:09:   -> tcp_read(2884) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() allocated memory for fname - will now sprintf()
11:55:09:   -> tcp_read(2802) using WSAEventSelect
11:55:09:   -> tcp_read(2802) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() no file "C:\PROGRA~3\MARCHH~1\myrepo/CVS/fileattr.xml", so allocate ofname of 60 bytes.
11:55:09: S -> fileattr_read() xmalloc ofname OK
11:55:09:   -> tcp_read(2629) using WSAEventSelect
11:55:09:   -> tcp_read(2629) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() no old "C:\PROGRA~3\MARCHH~1\myrepo/CVS/fileattr".
11:55:09:   -> tcp_read(2544) using WSAEventSelect
11:55:09:   -> tcp_read(2544) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() no old "C:\PROGRA~3\MARCHH~1\myrepo//.owner".
11:55:09:   -> tcp_read(2464) using WSAEventSelect
11:55:09:   -> tcp_read(2464) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() no old "C:\PROGRA~3\MARCHH~1\myrepo//.perms".
11:55:09:   -> tcp_read(2384) using WSAEventSelect
11:55:09:   -> tcp_read(2384) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() CXmlNode("fileattr")
11:55:09:   -> tcp_read(2329) using WSAEventSelect
11:55:09:   -> tcp_read(2329) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() xfree ofname and fname OK
11:55:09: S -> cache_directory_permissions() - cache directory(fileattr,)
11:55:09:   -> tcp_read(2194) using WSAEventSelect
11:55:09:   -> tcp_read(2194) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(2047) using WSAEventSelect
11:55:09:   -> tcp_read(2047) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> cache_directory_permissions() return
11:55:09:   -> tcp_read(1912) using WSAEventSelect
11:55:09:   -> tcp_read(1912) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(1691) using WSAEventSelect
11:55:09:   -> tcp_read(1691) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1610) using WSAEventSelect
11:55:09:   -> tcp_read(1610) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() return
11:55:09: S -> verify_perm() Read directory permission cache OK
11:55:09:   -> tcp_read(1490) using WSAEventSelect
11:55:09:   -> tcp_read(1490) immediate timeout previous thread count -1/0
11:55:09: S -> ACL lookup on directory C:\PROGRA~3\MARCHH~1\myrepo
11:55:09:   -> tcp_read(1421) using WSAEventSelect
11:55:09:   -> tcp_read(1421) immediate timeout previous thread count -1/0
11:55:09: S -> verify_acl(read,HEAD,(null))
11:55:09: S -> user_state = 0, group_state = 0
11:55:09:   -> tcp_read(1326) using WSAEventSelect
11:55:09:   -> tcp_read(1326) immediate timeout previous thread count -1/0
11:55:09: S -> no match at this level
11:55:09:   -> tcp_read(1286) using WSAEventSelect
11:55:09:   -> tcp_read(1286) immediate timeout previous thread count -1/0
11:55:09: S -> Match found. user_state = 0, group_state = 0
11:55:09:   -> tcp_read(1224) using WSAEventSelect
11:55:09:   -> tcp_read(1224) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm() return
11:55:09: S -> fileattr_startdir(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1122) using WSAEventSelect
11:55:09:   -> tcp_read(1122) immediate timeout previous thread count -1/0
11:55:09: S -> * The filesdoneproc needs to be called for each directory where files
11:55:09:   -> tcp_read(1035) using WSAEventSelect
11:55:09:   -> tcp_read(1035) immediate timeout previous thread count -1/0
11:55:09: S -> * processed, or each directory that is processed by a call where no
11:55:09:   -> tcp_read(950) using WSAEventSelect
11:55:09:   -> tcp_read(950) immediate timeout previous thread count -1/0
11:55:09: S -> * directories were passed in.  In fact, the only time we don't want to
11:55:09: S -> * call back the filesdoneproc is when we are processing directories that
11:55:09:   -> tcp_read(772) using WSAEventSelect
11:55:09:   -> tcp_read(772) immediate timeout previous thread count -1/0
11:55:09: S -> * were passed in on the command line (or in the special case of `.' when
11:55:09:   -> tcp_read(682) using WSAEventSelect
11:55:09:   -> tcp_read(682) immediate timeout previous thread count -1/0
11:55:09: S -> * we were called with no args
11:55:09: S -> do_recursion: dirlist != NULL && filelist == NULL
11:55:09:   -> tcp_read(568) using WSAEventSelect
11:55:09:   -> tcp_read(568) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: dodoneproc=0
11:55:09:   -> tcp_read(524) using WSAEventSelect
11:55:09:   -> tcp_read(524) immediate timeout previous thread count -1/0
11:55:09: S -> * If filelist or dirlist is already set, we don't look again. Otherwise,
11:55:09: S -> * find the files and directories
11:55:09:   -> tcp_read(384) using WSAEventSelect
11:55:09:   -> tcp_read(384) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: something was passed on the command line 
11:55:09:   -> tcp_read(311) using WSAEventSelect
11:55:09:   -> tcp_read(311) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: process the files (if any) 
11:55:09: S -> do_recursion: clean up by calling dellist(filelist)
11:55:09:   -> tcp_read(183) using WSAEventSelect
11:55:09:   -> tcp_read(183) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: call-back files done proc (if any) 
11:55:09:   -> tcp_read(116) using WSAEventSelect
11:55:09:   -> tcp_read(116) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: no: don't call-back files done proc (process_this_directory!dodoneprocframe->filesdoneproc == NULL) 
11:55:09: S -> do_recursion: at skip_directory 
11:55:09: S -> (repository && (server_active || !current_parsed_root->isremote))
11:55:09:   -> tcp_read(4971) using WSAEventSelect
11:55:09:   -> tcp_read(4971) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_write()
11:55:09:   -> tcp_read(4937) using WSAEventSelect
11:55:09:   -> tcp_read(4937) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_free()
11:55:09: S -> do_recursion: process the directories (if necessary) 
11:55:09:   -> tcp_read(4833) using WSAEventSelect
11:55:09:   -> tcp_read(4833) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: really process the directories (if necessary) 
11:55:09: S -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent)
11:55:09:   -> tcp_read(4664) using WSAEventSelect
11:55:09:   -> tcp_read(4664) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc()
11:55:09:   -> tcp_read(4633) using WSAEventSelect
11:55:09:   -> tcp_read(4633) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() update_dir=.
11:55:09: S -> do_dir_proc() -- set up update_dir(.) - no dots ? 
11:55:09:   -> tcp_read(4521) using WSAEventSelect
11:55:09:   -> tcp_read(4521) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- set up update_dir is now dirp "." - no dots ? 
11:55:09: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote
11:55:09:   -> tcp_read(4358) using WSAEventSelect
11:55:09:   -> tcp_read(4358) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag()
11:55:09: S -> ParseTag_Dir((null),tag=<something>,<something>,nonbranch=0,)
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09: S -> ParseTag_Dir() open fn="CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(4075) using WSAEventSelect
11:55:09:   -> tcp_read(4075) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09:   -> tcp_read(3939) using WSAEventSelect
11:55:09:   -> tcp_read(3939) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- verify_access 
11:55:09:   -> tcp_read(3890) using WSAEventSelect
11:55:09:   -> tcp_read(3890) immediate timeout previous thread count -1/0
11:55:09: S -> verify_access C:\PROGRA~3\MARCHH~1\myrepo,.,(null)
11:55:09: S -> verify_perm(C:\PROGRA~3\MARCHH~1\myrepo,(null),read,(null),(null))
11:55:09:   -> tcp_read(3738) using WSAEventSelect
11:55:09:   -> tcp_read(3738) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm: verify_owner_dir()
11:55:09:   -> tcp_read(3689) using WSAEventSelect
11:55:09:   -> tcp_read(3689) immediate timeout previous thread count -1/0
11:55:09: S -> verify_owner(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3556) using WSAEventSelect
11:55:09:   -> tcp_read(3556) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(3409) using WSAEventSelect
11:55:09:   -> tcp_read(3409) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3328) using WSAEventSelect
11:55:09:   -> tcp_read(3328) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() return
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3200) using WSAEventSelect
11:55:09:   -> tcp_read(3200) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(3053) using WSAEventSelect
11:55:09:   -> tcp_read(3053) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> cache_directory_permissions() return
11:55:09:   -> tcp_read(2918) using WSAEventSelect
11:55:09:   -> tcp_read(2918) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm() Read directory permission cache OK
11:55:09: S -> ACL lookup on directory C:\PROGRA~3\MARCHH~1\myrepo
11:55:09:   -> tcp_read(2783) using WSAEventSelect
11:55:09:   -> tcp_read(2783) immediate timeout previous thread count -1/0
11:55:09: S -> verify_acl(read,HEAD,(null))
11:55:09:   -> tcp_read(2737) using WSAEventSelect
11:55:09:   -> tcp_read(2737) immediate timeout previous thread count -1/0
11:55:09: S -> user_state = 0, group_state = 0
11:55:09: S -> no match at this level
11:55:09:   -> tcp_read(2648) using WSAEventSelect
11:55:09:   -> tcp_read(2648) immediate timeout previous thread count -1/0
11:55:09: S -> Match found. user_state = 0, group_state = 0
11:55:09:   -> tcp_read(2586) using WSAEventSelect
11:55:09:   -> tcp_read(2586) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm() return
11:55:09: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL
11:55:09:   -> tcp_read(2491) using WSAEventSelect
11:55:09:   -> tcp_read(2491) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- process_this_directory
11:55:09: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory
11:55:09:   -> tcp_read(2324) using WSAEventSelect
11:55:09:   -> tcp_read(2324) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() after predirent call ParseTag_Dir() 
11:55:09:   -> tcp_read(2256) using WSAEventSelect
11:55:09:   -> tcp_read(2256) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir(.,tag=<something>,<something>,nonbranch=0,<something>)
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09:   -> tcp_read(2080) using WSAEventSelect
11:55:09:   -> tcp_read(2080) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open fn="./CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(1993) using WSAEventSelect
11:55:09:   -> tcp_read(1993) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09:   -> tcp_read(1857) using WSAEventSelect
11:55:09:   -> tcp_read(1857) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory(C:\PROGRA~3\MARCHH~1\myrepo,.,(null),(null),0,(null),0)
11:55:09:   -> tcp_read(1769) using WSAEventSelect
11:55:09:   -> tcp_read(1769) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory() directory_stack_size increased by one to 1
11:55:09:   -> tcp_read(1692) using WSAEventSelect
11:55:09:   -> tcp_read(1692) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory, (!remote) try and find a mapping file in this directory .
11:55:09:   -> tcp_read(1601) using WSAEventSelect
11:55:09:   -> tcp_read(1601) immediate timeout previous thread count -1/0
11:55:09: S -> RCS_parse(.directory_history,C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1526) using WSAEventSelect
11:55:09:   -> tcp_read(1526) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/.directory_history,v)
11:55:09:   -> tcp_read(1447) using WSAEventSelect
11:55:09:   -> tcp_read(1447) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/.directory_history,v) failed to open file 1 times
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/.directory_history,v) file does not exist, so return 0
11:55:09:   -> tcp_read(1228) using WSAEventSelect
11:55:09:   -> tcp_read(1228) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open failed
11:55:09:   -> tcp_read(1192) using WSAEventSelect
11:55:09:   -> tcp_read(1192) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/Attic/.directory_history,v)
11:55:09:   -> tcp_read(1107) using WSAEventSelect
11:55:09:   -> tcp_read(1107) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/Attic/.directory_history,v) failed to open file 1 times
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/Attic/.directory_history,v) file does not exist, so return 0
11:55:09:   -> tcp_read(876) using WSAEventSelect
11:55:09:   -> tcp_read(876) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open failed
11:55:09:   -> tcp_read(840) using WSAEventSelect
11:55:09:   -> tcp_read(840) immediate timeout previous thread count -1/0
11:55:09: S -> No mapping file in this directory.
11:55:09: S -> open_directory copy the tag and date.
11:55:09:   -> tcp_read(733) using WSAEventSelect
11:55:09:   -> tcp_read(733) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory copied the tag and date.
11:55:09:   -> tcp_read(676) using WSAEventSelect
11:55:09:   -> tcp_read(676) immediate timeout previous thread count -1/0
11:55:09: S -> Look for rename script file CVS/Rename
11:55:09:   -> tcp_read(620) using WSAEventSelect
11:55:09:   -> tcp_read(620) immediate timeout previous thread count -1/0
11:55:09: S -> Rename script file does not exist
11:55:09:   -> tcp_read(569) using WSAEventSelect
11:55:09:   -> tcp_read(569) immediate timeout previous thread count -1/0
11:55:09: S -> directory opened
11:55:09: S -> fileattr_startdir(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(471) using WSAEventSelect
11:55:09:   -> tcp_read(471) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- call-back dir entry proc (if any)
11:55:09: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc.
11:55:09:   -> tcp_read(314) using WSAEventSelect
11:55:09:   -> tcp_read(314) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- only process the dir if the return code was 0.
11:55:09:   -> tcp_read(233) using WSAEventSelect
11:55:09:   -> tcp_read(233) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- !(frame->which&(W_LOCAL|W_FAKE))
11:55:09: S -> debug: 18
11:55:09:   -> tcp_read(139) using WSAEventSelect
11:55:09:   -> tcp_read(139) immediate timeout previous thread count -1/0
11:55:09: S -> debug: 19
11:55:09:   -> tcp_read(112) using WSAEventSelect
11:55:09:   -> tcp_read(112) immediate timeout previous thread count -1/0
11:55:09: S -> debug: 20
11:55:09: S -> do_recursion: begin
11:55:09:   -> tcp_read(48) using WSAEventSelect
11:55:09:   -> tcp_read(48) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: Check the value in CVSADM_ROOT 
11:55:09: S -> do_recursion: Fill in repository with the current repository
11:55:09: S -> (repository && (server_active || !current_parsed_root->isremote))
11:55:09:   -> tcp_read(4944) using WSAEventSelect
11:55:09:   -> tcp_read(4944) immediate timeout previous thread count -1/0
11:55:09: S -> map_repository(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> lookup_module2()
11:55:09:   -> tcp_read(4849) using WSAEventSelect
11:55:09:   -> tcp_read(4849) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() after rename?
11:55:09: S -> lookup_module2() call _lookup_module2()
11:55:09:   -> tcp_read(4744) using WSAEventSelect
11:55:09:   -> tcp_read(4744) immediate timeout previous thread count -1/0
11:55:09: S -> Loading modules2 from C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/modules2
11:55:09:   -> tcp_read(4660) using WSAEventSelect
11:55:09:   -> tcp_read(4660) immediate timeout previous thread count -1/0
11:55:09: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:55:09:   -> tcp_read(4593) using WSAEventSelect
11:55:09:   -> tcp_read(4593) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(,1)
11:55:09: S -> lookup_module2() call _lookup_module2 returned 0
11:55:09:   -> tcp_read(4491) using WSAEventSelect
11:55:09:   -> tcp_read(4491) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() no recursion permitted so give up now
11:55:09:   -> tcp_read(4419) using WSAEventSelect
11:55:09:   -> tcp_read(4419) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL
11:55:09:   -> tcp_read(4308) using WSAEventSelect
11:55:09:   -> tcp_read(4308) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() return ret=0 renamed=0
11:55:09: S -> map_repository - return(repository) "C:\PROGRA~3\MARCHH~1\myrepo"
11:55:09:   -> tcp_read(4168) using WSAEventSelect
11:55:09:   -> tcp_read(4168) immediate timeout previous thread count -1/0
11:55:09: S -> Repository = C:\PROGRA~3\MARCHH~1\myrepo
11:55:09:   -> tcp_read(4110) using WSAEventSelect
11:55:09:   -> tcp_read(4110) immediate timeout previous thread count -1/0
11:55:09: S -> Mapped repository = C:\PROGRA~3\MARCHH~1\myrepo
11:55:09: S -> * The filesdoneproc needs to be called for each directory where files
11:55:09:   -> tcp_read(3958) using WSAEventSelect
11:55:09:   -> tcp_read(3958) immediate timeout previous thread count -1/0
11:55:09: S -> * processed, or each directory that is processed by a call where no
11:55:09: S -> * directories were passed in.  In fact, the only time we don't want to
11:55:09:   -> tcp_read(3785) using WSAEventSelect
11:55:09:   -> tcp_read(3785) immediate timeout previous thread count -1/0
11:55:09: S -> * call back the filesdoneproc is when we are processing directories that
11:55:09: S -> * were passed in on the command line (or in the special case of `.' when
11:55:09:   -> tcp_read(3605) using WSAEventSelect
11:55:09:   -> tcp_read(3605) immediate timeout previous thread count -1/0
11:55:09: S -> * we were called with no args
11:55:09:   -> tcp_read(3558) using WSAEventSelect
11:55:09:   -> tcp_read(3558) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: dirlist == NULL && filelist == NULL
11:55:09: S -> do_recursion: dodoneproc=1
11:55:09:   -> tcp_read(3447) using WSAEventSelect
11:55:09:   -> tcp_read(3447) immediate timeout previous thread count -1/0
11:55:09: S -> * If filelist or dirlist is already set, we don't look again. Otherwise,
11:55:09:   -> tcp_read(3357) using WSAEventSelect
11:55:09:   -> tcp_read(3357) immediate timeout previous thread count -1/0
11:55:09: S -> * find the files and directories
11:55:09: S -> * both lists were NULL, so start from scratch 
11:55:09:   -> tcp_read(3243) using WSAEventSelect
11:55:09:   -> tcp_read(3243) immediate timeout previous thread count -1/0
11:55:09: S -> * In the !(which & W_LOCAL) case, we filled in repository
11:55:09:   -> tcp_read(3168) using WSAEventSelect
11:55:09:   -> tcp_read(3168) immediate timeout previous thread count -1/0
11:55:09: S ->   earlier in the function.  In the (which & W_LOCAL) case,
11:55:09: S ->   the Find_Names function is going to look through the
11:55:09:   -> tcp_read(3020) using WSAEventSelect
11:55:09:   -> tcp_read(3020) immediate timeout previous thread count -1/0
11:55:09: S ->   Entries file.  If we do not have a repository, that
11:55:09:   -> tcp_read(2949) using WSAEventSelect
11:55:09:   -> tcp_read(2949) immediate timeout previous thread count -1/0
11:55:09: S ->   does not make sense, so we insist upon having a
11:55:09: S ->   repository at this point.  Name_Repository will give a
11:55:09:   -> tcp_read(2808) using WSAEventSelect
11:55:09:   -> tcp_read(2808) immediate timeout previous thread count -1/0
11:55:09: S ->   reasonable error message. 
11:55:09:   -> tcp_read(2762) using WSAEventSelect
11:55:09:   -> tcp_read(2762) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: repository != NULL
11:55:09: S -> do_recursion: mapped_repository != NULL
11:55:09:   -> tcp_read(2655) using WSAEventSelect
11:55:09:   -> tcp_read(2655) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: find the files and fill in entries if appropriate
11:55:09:   -> tcp_read(2574) using WSAEventSelect
11:55:09:   -> tcp_read(2574) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: process_this_directory, call Find_Names()
11:55:09: S -> lookup_module2()
11:55:09:   -> tcp_read(2467) using WSAEventSelect
11:55:09:   -> tcp_read(2467) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() after rename?
11:55:09:   -> tcp_read(2419) using WSAEventSelect
11:55:09:   -> tcp_read(2419) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() call _lookup_module2()
11:55:09:   -> tcp_read(2362) using WSAEventSelect
11:55:09:   -> tcp_read(2362) immediate timeout previous thread count -1/0
11:55:09: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:55:09:   -> tcp_read(2295) using WSAEventSelect
11:55:09:   -> tcp_read(2295) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(,2)
11:55:09: S -> lookup_module2() call _lookup_module2 returned 0
11:55:09:   -> tcp_read(2193) using WSAEventSelect
11:55:09:   -> tcp_read(2193) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() no recursion permitted so give up now
11:55:09:   -> tcp_read(2121) using WSAEventSelect
11:55:09:   -> tcp_read(2121) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL
11:55:09: S -> lookup_module2() return ret=0 renamed=0
11:55:09:   -> tcp_read(1953) using WSAEventSelect
11:55:09:   -> tcp_read(1953) immediate timeout previous thread count -1/0
11:55:09: S -> find_virtual_rcs(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> lookup_module2()
11:55:09:   -> tcp_read(1856) using WSAEventSelect
11:55:09:   -> tcp_read(1856) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() after rename?
11:55:09:   -> tcp_read(1808) using WSAEventSelect
11:55:09:   -> tcp_read(1808) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() call _lookup_module2()
11:55:09:   -> tcp_read(1751) using WSAEventSelect
11:55:09:   -> tcp_read(1751) immediate timeout previous thread count -1/0
11:55:09: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:55:09:   -> tcp_read(1684) using WSAEventSelect
11:55:09:   -> tcp_read(1684) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(,3)
11:55:09: S -> lookup_module2() call _lookup_module2 returned 0
11:55:09:   -> tcp_read(1582) using WSAEventSelect
11:55:09:   -> tcp_read(1582) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() no recursion permitted so give up now
11:55:09:   -> tcp_read(1510) using WSAEventSelect
11:55:09:   -> tcp_read(1510) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL
11:55:09: S -> lookup_module2() return ret=0 renamed=0
11:55:09:   -> tcp_read(1342) using WSAEventSelect
11:55:09:   -> tcp_read(1342) immediate timeout previous thread count -1/0
11:55:09: S -> find_rename_rcs(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1280) using WSAEventSelect
11:55:09:   -> tcp_read(1280) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: Find_Names got a filelist
11:55:09: S -> lookup_module2()
11:55:09:   -> tcp_read(1189) using WSAEventSelect
11:55:09:   -> tcp_read(1189) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() after rename?
11:55:09: S -> lookup_module2() call _lookup_module2()
11:55:09:   -> tcp_read(1084) using WSAEventSelect
11:55:09:   -> tcp_read(1084) immediate timeout previous thread count -1/0
11:55:09: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:55:09: S -> lookup_module2(,4)
11:55:09:   -> tcp_read(981) using WSAEventSelect
11:55:09:   -> tcp_read(981) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() call _lookup_module2 returned 0
11:55:09: S -> lookup_module2() no recursion permitted so give up now
11:55:09:   -> tcp_read(843) using WSAEventSelect
11:55:09:   -> tcp_read(843) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL
11:55:09:   -> tcp_read(732) using WSAEventSelect
11:55:09:   -> tcp_read(732) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() return ret=0 renamed=0
11:55:09:   -> tcp_read(675) using WSAEventSelect
11:55:09:   -> tcp_read(675) immediate timeout previous thread count -1/0
11:55:09: S -> find_virtual_dirs(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09: S -> find_rename_dirs(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(548) using WSAEventSelect
11:55:09:   -> tcp_read(548) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: process the files (if any) 
11:55:09:   -> tcp_read(489) using WSAEventSelect
11:55:09:   -> tcp_read(489) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: read lock it if necessary 
11:55:09: S -> * For the server, we handle notifications in a completely different
11:55:09:   -> tcp_read(346) using WSAEventSelect
11:55:09:   -> tcp_read(346) immediate timeout previous thread count -1/0
11:55:09: S ->   place (server_notify).  For local, we can't do them here--we don't
11:55:09:   -> tcp_read(260) using WSAEventSelect
11:55:09:   -> tcp_read(260) immediate timeout previous thread count -1/0
11:55:09: S ->   have writelocks in place, and there is no way to get writelocks here
11:55:09: S -> do_recursion: process the files by calling walklist()
11:55:09:   -> tcp_read(101) using WSAEventSelect
11:55:09:   -> tcp_read(101) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: unlock it by calling Lock_Cleanup_Directory()
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: clean up by calling dellist(filelist)
11:55:09: S -> do_recursion: call-back files done proc (if any) 
11:55:09:   -> tcp_read(5008) using WSAEventSelect
11:55:09:   -> tcp_read(5008) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: no: don't call-back files done proc (process_this_directorydodoneprocframe->filesdoneproc == NULL) 
11:55:09:   -> tcp_read(4877) using WSAEventSelect
11:55:09:   -> tcp_read(4877) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: at skip_directory 
11:55:09:   -> tcp_read(4827) using WSAEventSelect
11:55:09:   -> tcp_read(4827) immediate timeout previous thread count -1/0
11:55:09: S -> (repository && (server_active || !current_parsed_root->isremote))
11:55:09: S -> fileattr_write()
11:55:09:   -> tcp_read(4710) using WSAEventSelect
11:55:09:   -> tcp_read(4710) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_free()
11:55:09:   -> tcp_read(4677) using WSAEventSelect
11:55:09:   -> tcp_read(4677) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: process the directories (if necessary) 
11:55:09: S -> do_recursion: really process the directories (if necessary) 
11:55:09:   -> tcp_read(4528) using WSAEventSelect
11:55:09:   -> tcp_read(4528) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion() -- walklist (dirlist !=NULL, do_dir_proc, (void *) &frent)
11:55:09:   -> tcp_read(4437) using WSAEventSelect
11:55:09:   -> tcp_read(4437) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc()
11:55:09: S -> do_dir_proc() update_dir=
11:55:09:   -> tcp_read(4363) using WSAEventSelect
11:55:09:   -> tcp_read(4363) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 
11:55:09:   -> tcp_read(4282) using WSAEventSelect
11:55:09:   -> tcp_read(4282) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead
11:55:09: S -> do_dir_proc() after all that now update_dir=CVSROOT
11:55:09:   -> tcp_read(4138) using WSAEventSelect
11:55:09:   -> tcp_read(4138) immediate timeout previous thread count -1/0
11:55:09: S -> (server_active || !current_parsed_root->isremote) 2
11:55:09:   -> tcp_read(4069) using WSAEventSelect
11:55:09:   -> tcp_read(4069) immediate timeout previous thread count -1/0
11:55:09: S -> map_repository(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09: S -> lookup_module2()
11:55:09:   -> tcp_read(3966) using WSAEventSelect
11:55:09:   -> tcp_read(3966) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(CVSROOT) after rename?
11:55:09:   -> tcp_read(3911) using WSAEventSelect
11:55:09:   -> tcp_read(3911) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() call _lookup_module2()
11:55:09:   -> tcp_read(3854) using WSAEventSelect
11:55:09:   -> tcp_read(3854) immediate timeout previous thread count -1/0
11:55:09: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:55:09:   -> tcp_read(3787) using WSAEventSelect
11:55:09:   -> tcp_read(3787) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(CVSROOT,5)
11:55:09: S -> _lookup_module2 !p lookup_repository_module(CVSROOT)
11:55:09:   -> tcp_read(3674) using WSAEventSelect
11:55:09:   -> tcp_read(3674) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_repository_module(CVSROOT)
11:55:09:   -> tcp_read(3623) using WSAEventSelect
11:55:09:   -> tcp_read(3623) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() calls to lookup_repository_module() returned nothing
11:55:09: S -> _lookup_module2 !mod return 0 left,right(,CVSROOT)
11:55:09:   -> tcp_read(3468) using WSAEventSelect
11:55:09:   -> tcp_read(3468) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() call _lookup_module2 returned 0
11:55:09:   -> tcp_read(3402) using WSAEventSelect
11:55:09:   -> tcp_read(3402) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() no recursion permitted so give up now
11:55:09: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL
11:55:09:   -> tcp_read(3219) using WSAEventSelect
11:55:09:   -> tcp_read(3219) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() return ret=0 renamed=0
11:55:09:   -> tcp_read(3162) using WSAEventSelect
11:55:09:   -> tcp_read(3162) immediate timeout previous thread count -1/0
11:55:09: S -> map_repository - return(repository) "C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT"
11:55:09: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote
11:55:09:   -> tcp_read(2989) using WSAEventSelect
11:55:09:   -> tcp_read(2989) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag()
11:55:09:   -> tcp_read(2961) using WSAEventSelect
11:55:09:   -> tcp_read(2961) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir((null),tag=<something>,<something>,nonbranch=0,)
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09:   -> tcp_read(2791) using WSAEventSelect
11:55:09:   -> tcp_read(2791) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open fn="CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(2706) using WSAEventSelect
11:55:09:   -> tcp_read(2706) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09:   -> tcp_read(2661) using WSAEventSelect
11:55:09:   -> tcp_read(2661) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09:   -> tcp_read(2570) using WSAEventSelect
11:55:09:   -> tcp_read(2570) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- verify_access 
11:55:09: S -> verify_access C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT,CVSROOT,(null)
11:55:09:   -> tcp_read(2439) using WSAEventSelect
11:55:09:   -> tcp_read(2439) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT,(null),read,(null),(null))
11:55:09:   -> tcp_read(2347) using WSAEventSelect
11:55:09:   -> tcp_read(2347) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm: verify_owner_dir()
11:55:09: S -> verify_owner(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09:   -> tcp_read(2231) using WSAEventSelect
11:55:09:   -> tcp_read(2231) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09:   -> tcp_read(2149) using WSAEventSelect
11:55:09:   -> tcp_read(2149) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09: S -> _fileattr_read() malloc [strlen(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)=]35+[sizeof(CVS/fileattr.xml)=]4+20=59
11:55:09:   -> tcp_read(1956) using WSAEventSelect
11:55:09:   -> tcp_read(1956) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() xmalloc fname OK
11:55:09: S -> _fileattr_read() allocated memory for fname - will now sprintf()
11:55:09:   -> tcp_read(1824) using WSAEventSelect
11:55:09:   -> tcp_read(1824) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() ReadXmlFile("C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/CVS/fileattr.xml")
11:55:09:   -> tcp_read(1722) using WSAEventSelect
11:55:09:   -> tcp_read(1722) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() ReadXmlFile()
11:55:09:   -> tcp_read(1675) using WSAEventSelect
11:55:09:   -> tcp_read(1675) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() close
11:55:09: S -> fileattr_read() xfree fname
11:55:09:   -> tcp_read(1591) using WSAEventSelect
11:55:09:   -> tcp_read(1591) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() OK
11:55:09:   -> tcp_read(1555) using WSAEventSelect
11:55:09:   -> tcp_read(1555) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() - cache directory(fileattr,)
11:55:09:   -> tcp_read(1479) using WSAEventSelect
11:55:09:   -> tcp_read(1479) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09: S -> cache_directory_permissions() last_component=CVSROOT
11:55:09:   -> tcp_read(1254) using WSAEventSelect
11:55:09:   -> tcp_read(1254) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(1107) using WSAEventSelect
11:55:09:   -> tcp_read(1107) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(1026) using WSAEventSelect
11:55:09:   -> tcp_read(1026) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() return
11:55:09: S -> verify_valid_name(admin)
11:55:09:   -> tcp_read(930) using WSAEventSelect
11:55:09:   -> tcp_read(930) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09:   -> tcp_read(848) using WSAEventSelect
11:55:09:   -> tcp_read(848) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09: S -> cache_directory_permissions() last_component=CVSROOT
11:55:09:   -> tcp_read(623) using WSAEventSelect
11:55:09:   -> tcp_read(623) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(395) using WSAEventSelect
11:55:09:   -> tcp_read(395) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() return
11:55:09:   -> tcp_read(341) using WSAEventSelect
11:55:09:   -> tcp_read(341) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm() Read directory permission cache OK
11:55:09: S -> ACL lookup on directory C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT
11:55:09:   -> tcp_read(198) using WSAEventSelect
11:55:09:   -> tcp_read(198) immediate timeout previous thread count -1/0
11:55:09: S -> verify_acl(read,HEAD,(null))
11:55:09: S -> verify_valid_name(admin)
11:55:09:   -> tcp_read(110) using WSAEventSelect
11:55:09:   -> tcp_read(110) immediate timeout previous thread count -1/0
11:55:09: S -> matched ACL user=admin, branch=_default_, merge=(no acl_merge)
11:55:09:   -> tcp_read(30) using WSAEventSelect
11:55:09:   -> tcp_read(30) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> calculated ACL priority is 10
11:55:09: S -> new max priority is 10
11:55:09: S -> verify_valid_name(admin)
11:55:09:   -> tcp_read(5021) using WSAEventSelect
11:55:09:   -> tcp_read(5021) immediate timeout previous thread count -1/0
11:55:09: S -> matched ACL user=admin, branch=_default_, merge=(no acl_merge)
11:55:09:   -> tcp_read(4941) using WSAEventSelect
11:55:09:   -> tcp_read(4941) immediate timeout previous thread count -1/0
11:55:09: S -> calculated ACL priority is 10
11:55:09: S -> new max priority is 10
11:55:09:   -> tcp_read(4854) using WSAEventSelect
11:55:09:   -> tcp_read(4854) immediate timeout previous thread count -1/0
11:55:09: S -> matched ACL user=_default_, branch=_default_, merge=(no acl_merge)
11:55:09: S -> calculated ACL priority is 0
11:55:09:   -> tcp_read(4724) using WSAEventSelect
11:55:09:   -> tcp_read(4724) immediate timeout previous thread count -1/0
11:55:09: S -> matched ACL user=_default_, branch=_default_, merge=(no acl_merge)
11:55:09:   -> tcp_read(4640) using WSAEventSelect
11:55:09:   -> tcp_read(4640) immediate timeout previous thread count -1/0
11:55:09: S -> calculated ACL priority is 0
11:55:09: S -> user_state = 1, group_state = 0
11:55:09:   -> tcp_read(4545) using WSAEventSelect
11:55:09:   -> tcp_read(4545) immediate timeout previous thread count -1/0
11:55:09: S -> Match found. user_state = 1, group_state = 0
11:55:09:   -> tcp_read(4483) using WSAEventSelect
11:55:09:   -> tcp_read(4483) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL
11:55:09:   -> tcp_read(4426) using WSAEventSelect
11:55:09:   -> tcp_read(4426) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- process_this_directory
11:55:09:   -> tcp_read(4369) using WSAEventSelect
11:55:09:   -> tcp_read(4369) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory
11:55:09: S -> do_dir_proc() after predirent call ParseTag_Dir() 
11:55:09:   -> tcp_read(4191) using WSAEventSelect
11:55:09:   -> tcp_read(4191) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir(CVSROOT,tag=<something>,<something>,nonbranch=0,<something>)
11:55:09:   -> tcp_read(4100) using WSAEventSelect
11:55:09:   -> tcp_read(4100) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09: S -> ParseTag_Dir() open fn="CVSROOT/CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(3916) using WSAEventSelect
11:55:09:   -> tcp_read(3916) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09:   -> tcp_read(3780) using WSAEventSelect
11:55:09:   -> tcp_read(3780) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT,CVSROOT,(null),(null),0,(null),0)
11:55:09:   -> tcp_read(3678) using WSAEventSelect
11:55:09:   -> tcp_read(3678) immediate timeout previous thread count -1/0
11:55:09: S -> current_directory is already set
11:55:09: S -> open_directory() directory_stack_size increased by one to 2
11:55:09:   -> tcp_read(3551) using WSAEventSelect
11:55:09:   -> tcp_read(3551) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory, (!remote) try and find a mapping file in this directory .
11:55:09:   -> tcp_read(3460) using WSAEventSelect
11:55:09:   -> tcp_read(3460) immediate timeout previous thread count -1/0
11:55:09: S -> RCS_parse(.directory_history,C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/.directory_history,v)
11:55:09:   -> tcp_read(3290) using WSAEventSelect
11:55:09:   -> tcp_read(3290) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/.directory_history,v) failed to open file 1 times
11:55:09:   -> tcp_read(3175) using WSAEventSelect
11:55:09:   -> tcp_read(3175) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/.directory_history,v) file does not exist, so return 0
11:55:09: S -> rcsbuf_open failed
11:55:09:   -> tcp_read(3019) using WSAEventSelect
11:55:09:   -> tcp_read(3019) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/Attic/.directory_history,v)
11:55:09:   -> tcp_read(2926) using WSAEventSelect
11:55:09:   -> tcp_read(2926) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/Attic/.directory_history,v) failed to open file 1 times
11:55:09:   -> tcp_read(2805) using WSAEventSelect
11:55:09:   -> tcp_read(2805) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/Attic/.directory_history,v) file does not exist, so return 0
11:55:09: S -> rcsbuf_open failed
11:55:09:   -> tcp_read(2643) using WSAEventSelect
11:55:09:   -> tcp_read(2643) immediate timeout previous thread count -1/0
11:55:09: S -> No mapping file in this directory.
11:55:09:   -> tcp_read(2591) using WSAEventSelect
11:55:09:   -> tcp_read(2591) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory copy the tag and date.
11:55:09: S -> open_directory copied the tag and date.
11:55:09:   -> tcp_read(2479) using WSAEventSelect
11:55:09:   -> tcp_read(2479) immediate timeout previous thread count -1/0
11:55:09: S -> Look for rename script file CVS/Rename
11:55:09:   -> tcp_read(2423) using WSAEventSelect
11:55:09:   -> tcp_read(2423) immediate timeout previous thread count -1/0
11:55:09: S -> Rename script file does not exist
11:55:09:   -> tcp_read(2372) using WSAEventSelect
11:55:09:   -> tcp_read(2372) immediate timeout previous thread count -1/0
11:55:09: S -> directory opened
11:55:09:   -> tcp_read(2338) using WSAEventSelect
11:55:09:   -> tcp_read(2338) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_startdir(C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT)
11:55:09: S -> do_dir_proc() -- call-back dir entry proc (if any)
11:55:09:   -> tcp_read(2198) using WSAEventSelect
11:55:09:   -> tcp_read(2198) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc.
CVSROOT
11:55:09:   -> tcp_read(2099) using WSAEventSelect
11:55:09:   -> tcp_read(2099) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL.
11:55:09: S -> more efficient - close directory in do_dir_proc
11:55:09:   -> tcp_read(1937) using WSAEventSelect
11:55:09:   -> tcp_read(1937) immediate timeout previous thread count -1/0
11:55:09: S -> close_directory()
11:55:09: S -> close_directory() directory_stack_size decreased by one to 1 
11:55:09:   -> tcp_read(1823) using WSAEventSelect
11:55:09:   -> tcp_read(1823) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_write()
11:55:09:   -> tcp_read(1789) using WSAEventSelect
11:55:09:   -> tcp_read(1789) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_free()
11:55:09: S -> do_dir_proc()
11:55:09:   -> tcp_read(1725) using WSAEventSelect
11:55:09:   -> tcp_read(1725) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() update_dir=
11:55:09:   -> tcp_read(1682) using WSAEventSelect
11:55:09:   -> tcp_read(1682) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- set up update_dir - skip dots if not at start 
11:55:09: S -> do_dir_proc() -- update_dir is dot? - set to dirp instead
11:55:09:   -> tcp_read(1526) using WSAEventSelect
11:55:09:   -> tcp_read(1526) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() after all that now update_dir=abc
11:55:09:   -> tcp_read(1461) using WSAEventSelect
11:55:09:   -> tcp_read(1461) immediate timeout previous thread count -1/0
11:55:09: S -> (server_active || !current_parsed_root->isremote) 2
11:55:09:   -> tcp_read(1392) using WSAEventSelect
11:55:09:   -> tcp_read(1392) immediate timeout previous thread count -1/0
11:55:09: S -> map_repository(C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09: S -> lookup_module2()
11:55:09:   -> tcp_read(1293) using WSAEventSelect
11:55:09:   -> tcp_read(1293) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(abc) after rename?
11:55:09:   -> tcp_read(1242) using WSAEventSelect
11:55:09:   -> tcp_read(1242) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() call _lookup_module2()
11:55:09:   -> tcp_read(1185) using WSAEventSelect
11:55:09:   -> tcp_read(1185) immediate timeout previous thread count -1/0
11:55:09: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:55:09:   -> tcp_read(1118) using WSAEventSelect
11:55:09:   -> tcp_read(1118) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2(abc,6)
11:55:09: S -> _lookup_module2 !p lookup_repository_module(abc)
11:55:09:   -> tcp_read(1013) using WSAEventSelect
11:55:09:   -> tcp_read(1013) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_repository_module(abc)
11:55:09:   -> tcp_read(966) using WSAEventSelect
11:55:09:   -> tcp_read(966) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() calls to lookup_repository_module() returned nothing
11:55:09: S -> _lookup_module2 !mod return 0 left,right(,abc)
11:55:09: S -> lookup_module2() call _lookup_module2 returned 0
11:55:09:   -> tcp_read(749) using WSAEventSelect
11:55:09:   -> tcp_read(749) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() no recursion permitted so give up now
11:55:09: S -> lookup_module2() ret=0, current_directory!=NULL, current_directory->directory_mappings===NULL
11:55:09:   -> tcp_read(566) using WSAEventSelect
11:55:09:   -> tcp_read(566) immediate timeout previous thread count -1/0
11:55:09: S -> lookup_module2() return ret=0 renamed=0
11:55:09:   -> tcp_read(509) using WSAEventSelect
11:55:09:   -> tcp_read(509) immediate timeout previous thread count -1/0
11:55:09: S -> map_repository - return(repository) "C:\PROGRA~3\MARCHH~1\myrepo/abc"
11:55:09: S -> do_dir_proc() -- server_active || !current_parsed_root->isremote
11:55:09:   -> tcp_read(340) using WSAEventSelect
11:55:09:   -> tcp_read(340) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag()
11:55:09:   -> tcp_read(312) using WSAEventSelect
11:55:09:   -> tcp_read(312) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir((null),tag=<something>,<something>,nonbranch=0,)
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09:   -> tcp_read(142) using WSAEventSelect
11:55:09:   -> tcp_read(142) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open fn="CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(57) using WSAEventSelect
11:55:09:   -> tcp_read(57) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09: S -> do_dir_proc() -- verify_access 
11:55:09:   -> tcp_read(4992) using WSAEventSelect
11:55:09:   -> tcp_read(4992) immediate timeout previous thread count -1/0
11:55:09: S -> verify_access C:\PROGRA~3\MARCHH~1\myrepo/abc,abc,(null)
11:55:09: S -> verify_perm(C:\PROGRA~3\MARCHH~1\myrepo/abc,(null),read,(null),(null))
11:55:09:   -> tcp_read(4830) using WSAEventSelect
11:55:09:   -> tcp_read(4830) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm: verify_owner_dir()
11:55:09: S -> verify_owner(C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09:   -> tcp_read(4718) using WSAEventSelect
11:55:09:   -> tcp_read(4718) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09:   -> tcp_read(4640) using WSAEventSelect
11:55:09:   -> tcp_read(4640) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read(C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09: S -> _fileattr_read() malloc [strlen(C:\PROGRA~3\MARCHH~1\myrepo/abc)=]31+[sizeof(CVS/fileattr.xml)=]4+20=55
11:55:09:   -> tcp_read(4455) using WSAEventSelect
11:55:09:   -> tcp_read(4455) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() xmalloc fname OK
11:55:09: S -> _fileattr_read() allocated memory for fname - will now sprintf()
11:55:09:   -> tcp_read(4323) using WSAEventSelect
11:55:09:   -> tcp_read(4323) immediate timeout previous thread count -1/0
11:55:09: S -> _fileattr_read() ReadXmlFile("C:\PROGRA~3\MARCHH~1\myrepo/abc/CVS/fileattr.xml")
11:55:09:   -> tcp_read(4225) using WSAEventSelect
11:55:09:   -> tcp_read(4225) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() ReadXmlFile()
11:55:09:   -> tcp_read(4178) using WSAEventSelect
11:55:09:   -> tcp_read(4178) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() close
11:55:09:   -> tcp_read(4139) using WSAEventSelect
11:55:09:   -> tcp_read(4139) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_read() xfree fname
11:55:09: S -> fileattr_read() OK
11:55:09:   -> tcp_read(4058) using WSAEventSelect
11:55:09:   -> tcp_read(4058) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() - cache directory(fileattr,)
11:55:09:   -> tcp_read(3982) using WSAEventSelect
11:55:09:   -> tcp_read(3982) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo/abc",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(3831) using WSAEventSelect
11:55:09:   -> tcp_read(3831) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() last_component=abc
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(3618) using WSAEventSelect
11:55:09:   -> tcp_read(3618) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3537) using WSAEventSelect
11:55:09:   -> tcp_read(3537) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() return
11:55:09: S -> verify_valid_name(admin)
11:55:09:   -> tcp_read(3441) using WSAEventSelect
11:55:09:   -> tcp_read(3441) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions(C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo/abc",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09:   -> tcp_read(3212) using WSAEventSelect
11:55:09:   -> tcp_read(3212) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() last_component=abc
11:55:09:   -> tcp_read(3146) using WSAEventSelect
11:55:09:   -> tcp_read(3146) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() fncmp(d="C:\PROGRA~3\MARCHH~1\myrepo",current_parsed_root->directory="C:\PROGRA~3\MARCHH~1\myrepo")
11:55:09: S -> cache_directory_permissions() free(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(2918) using WSAEventSelect
11:55:09:   -> tcp_read(2918) immediate timeout previous thread count -1/0
11:55:09: S -> cache_directory_permissions() return
11:55:09:   -> tcp_read(2864) using WSAEventSelect
11:55:09:   -> tcp_read(2864) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm() Read directory permission cache OK
11:55:09: S -> ACL lookup on directory C:\PROGRA~3\MARCHH~1\myrepo/abc
11:55:09:   -> tcp_read(2725) using WSAEventSelect
11:55:09:   -> tcp_read(2725) immediate timeout previous thread count -1/0
11:55:09: S -> verify_acl(read,HEAD,(null))
11:55:09:   -> tcp_read(2679) using WSAEventSelect
11:55:09:   -> tcp_read(2679) immediate timeout previous thread count -1/0
11:55:09: S -> user_state = 0, group_state = 0
11:55:09:   -> tcp_read(2630) using WSAEventSelect
11:55:09:   -> tcp_read(2630) immediate timeout previous thread count -1/0
11:55:09: S -> no match at this level
11:55:09: S -> ACL lookup on directory C:\PROGRA~3\MARCHH~1\myrepo
11:55:09:   -> tcp_read(2521) using WSAEventSelect
11:55:09:   -> tcp_read(2521) immediate timeout previous thread count -1/0
11:55:09: S -> verify_acl(read,HEAD,(null))
11:55:09:   -> tcp_read(2475) using WSAEventSelect
11:55:09:   -> tcp_read(2475) immediate timeout previous thread count -1/0
11:55:09: S -> user_state = 0, group_state = 0
11:55:09: S -> no match at this level
11:55:09:   -> tcp_read(2386) using WSAEventSelect
11:55:09:   -> tcp_read(2386) immediate timeout previous thread count -1/0
11:55:09: S -> Match found. user_state = 0, group_state = 0
11:55:09:   -> tcp_read(2324) using WSAEventSelect
11:55:09:   -> tcp_read(2324) immediate timeout previous thread count -1/0
11:55:09: S -> verify_perm() return
11:55:09: S -> do_dir_proc() -- dir_return!=R_SKIP_ALL
11:55:09:   -> tcp_read(2229) using WSAEventSelect
11:55:09:   -> tcp_read(2229) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- process_this_directory
11:55:09:   -> tcp_read(2172) using WSAEventSelect
11:55:09:   -> tcp_read(2172) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- dir_return != R_SKIP_ALL && dir_return != R_ERROR && process_this_directory
11:55:09: S -> do_dir_proc() after predirent call ParseTag_Dir() 
11:55:09:   -> tcp_read(1994) using WSAEventSelect
11:55:09:   -> tcp_read(1994) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir(abc,tag=<something>,<something>,nonbranch=0,<something>)
11:55:09:   -> tcp_read(1907) using WSAEventSelect
11:55:09:   -> tcp_read(1907) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() --- tag, date and version set to NULL. nonbranch set to 0.
11:55:09: S -> ParseTag_Dir() open fn="abc/CVS/Tag" cwd="C:\PROGRA~3\MARCHH~1\myrepo".
11:55:09:   -> tcp_read(1727) using WSAEventSelect
11:55:09:   -> tcp_read(1727) immediate timeout previous thread count -1/0
11:55:09: S -> ParseTag_Dir() open failed!
11:55:09: S -> ParseTag_Dir() open failed due to some error, but not an existence error!
11:55:09:   -> tcp_read(1591) using WSAEventSelect
11:55:09:   -> tcp_read(1591) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory(C:\PROGRA~3\MARCHH~1\myrepo/abc,abc,(null),(null),0,(null),0)
11:55:09: S -> current_directory is already set
11:55:09:   -> tcp_read(1447) using WSAEventSelect
11:55:09:   -> tcp_read(1447) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory() directory_stack_size increased by one to 2
11:55:09:   -> tcp_read(1370) using WSAEventSelect
11:55:09:   -> tcp_read(1370) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory, (!remote) try and find a mapping file in this directory .
11:55:09:   -> tcp_read(1279) using WSAEventSelect
11:55:09:   -> tcp_read(1279) immediate timeout previous thread count -1/0
11:55:09: S -> RCS_parse(.directory_history,C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/abc/.directory_history,v)
11:55:09:   -> tcp_read(1117) using WSAEventSelect
11:55:09:   -> tcp_read(1117) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/abc/.directory_history,v) failed to open file 1 times
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/abc/.directory_history,v) file does not exist, so return 0
11:55:09:   -> tcp_read(890) using WSAEventSelect
11:55:09:   -> tcp_read(890) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open failed
11:55:09:   -> tcp_read(854) using WSAEventSelect
11:55:09:   -> tcp_read(854) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/abc/Attic/.directory_history,v)
11:55:09:   -> tcp_read(765) using WSAEventSelect
11:55:09:   -> tcp_read(765) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/abc/Attic/.directory_history,v) failed to open file 1 times
11:55:09: S -> rcsbuf_open(C:\PROGRA~3\MARCHH~1\myrepo/abc/Attic/.directory_history,v) file does not exist, so return 0
11:55:09:   -> tcp_read(526) using WSAEventSelect
11:55:09:   -> tcp_read(526) immediate timeout previous thread count -1/0
11:55:09: S -> rcsbuf_open failed
11:55:09:   -> tcp_read(490) using WSAEventSelect
11:55:09:   -> tcp_read(490) immediate timeout previous thread count -1/0
11:55:09: S -> No mapping file in this directory.
11:55:09: S -> open_directory copy the tag and date.
11:55:09:   -> tcp_read(383) using WSAEventSelect
11:55:09:   -> tcp_read(383) immediate timeout previous thread count -1/0
11:55:09: S -> open_directory copied the tag and date.
11:55:09:   -> tcp_read(326) using WSAEventSelect
11:55:09:   -> tcp_read(326) immediate timeout previous thread count -1/0
11:55:09: S -> Look for rename script file CVS/Rename
11:55:09:   -> tcp_read(270) using WSAEventSelect
11:55:09:   -> tcp_read(270) immediate timeout previous thread count -1/0
11:55:09: S -> Rename script file does not exist
11:55:09:   -> tcp_read(219) using WSAEventSelect
11:55:09:   -> tcp_read(219) immediate timeout previous thread count -1/0
11:55:09: S -> directory opened
11:55:09:   -> tcp_read(185) using WSAEventSelect
11:55:09:   -> tcp_read(185) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_startdir(C:\PROGRA~3\MARCHH~1\myrepo/abc)
11:55:09: S -> do_dir_proc() -- call-back dir entry proc (if any)
11:55:09:   -> tcp_read(49) using WSAEventSelect
11:55:09:   -> tcp_read(49) immediate timeout previous thread count -1/0
11:55:09:   -> tcp_read(5120) using WSAEventSelect
11:55:09:   -> tcp_read(5120) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- If we're doing the actual processing, call direntproc.
abc
11:55:09: S -> do_dir_proc() -- cannot process the dir because the return code was R_SKIP_ALL.
11:55:09:   -> tcp_read(4977) using WSAEventSelect
11:55:09:   -> tcp_read(4977) immediate timeout previous thread count -1/0
11:55:09: S -> more efficient - close directory in do_dir_proc
11:55:09: S -> close_directory()
11:55:09:   -> tcp_read(4877) using WSAEventSelect
11:55:09:   -> tcp_read(4877) immediate timeout previous thread count -1/0
11:55:09: S -> close_directory() directory_stack_size decreased by one to 1 
11:55:09: S -> fileattr_write()
11:55:09:   -> tcp_read(4764) using WSAEventSelect
11:55:09:   -> tcp_read(4764) immediate timeout previous thread count -1/0
11:55:09: S -> fileattr_free()
11:55:09:   -> tcp_read(4731) using WSAEventSelect
11:55:09:   -> tcp_read(4731) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion() -- walklist() returns and now dirlist!=NULL 
11:55:09: S -> freenode() free the badbad DIRS
11:55:09:   -> tcp_read(4605) using WSAEventSelect
11:55:09:   -> tcp_read(4605) immediate timeout previous thread count -1/0
11:55:09: S -> freenode() free the badbad DIRS
11:55:09:   -> tcp_read(4556) using WSAEventSelect
11:55:09:   -> tcp_read(4556) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion: free the saved copy of the pointer if necessary 
11:55:09: S -> debug: 21
11:55:09:   -> tcp_read(4449) using WSAEventSelect
11:55:09:   -> tcp_read(4449) immediate timeout previous thread count -1/0
11:55:09: S -> debug: 22
11:55:09:   -> tcp_read(4422) using WSAEventSelect
11:55:09:   -> tcp_read(4422) immediate timeout previous thread count -1/0
11:55:09: S -> do_dir_proc() -- call-back dir leave proc (if any) process_this_directory 
11:55:09: S -> debug: 23
11:55:09:   -> tcp_read(4303) using WSAEventSelect
11:55:09:   -> tcp_read(4303) immediate timeout previous thread count -1/0
11:55:09: S -> close_directory()
11:55:09:   -> tcp_read(4268) using WSAEventSelect
11:55:09:   -> tcp_read(4268) immediate timeout previous thread count -1/0
11:55:09: S -> close_directory() directory_stack_size decreased by one to 0 
11:55:09:   -> tcp_read(4189) using WSAEventSelect
11:55:09:   -> tcp_read(4189) immediate timeout previous thread count -1/0
11:55:09: S -> do_recursion() -- walklist() returns and now dirlist!=NULL 
11:55:09: S -> do_recursion: free the saved copy of the pointer if necessary 
11:55:09:   -> tcp_read(4032) using WSAEventSelect
11:55:09:   -> tcp_read(4032) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion: Free the data which expand_wild allocated.
11:55:09:   -> tcp_read(3955) using WSAEventSelect
11:55:09:   -> tcp_read(3955) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion: Free the data - update_dir
11:55:09: S -> start_recursion: Free the data - update_repos
11:55:09:   -> tcp_read(3831) using WSAEventSelect
11:55:09:   -> tcp_read(3831) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion: (args_to_send_when_finished == NULL)
11:55:09:   -> tcp_read(3760) using WSAEventSelect
11:55:09:   -> tcp_read(3760) immediate timeout previous thread count -1/0
11:55:09: S -> start_recursion: return 0.
11:55:09: S -> open_module(C:\PROGRA~3\MARCHH~1\myrepo)
11:55:09:   -> tcp_read(3658) using WSAEventSelect
11:55:09:   -> tcp_read(3658) immediate timeout previous thread count -1/0
11:55:09: S -> run postmodule proc
11:55:09: S -> run_trigger()
11:55:09:   -> tcp_read(3590) using WSAEventSelect
11:55:09:   -> tcp_read(3590) immediate timeout previous thread count -1/0
11:55:09: S -> Call pre-loaded 'info.dll'
11:55:09:   -> tcp_read(3546) using WSAEventSelect
11:55:09:   -> tcp_read(3546) immediate timeout previous thread count -1/0
11:55:09: S -> postmodule_proc(.)
11:55:09: S -> default_trigger: parse_info(CVSROOT/postmodule,%r/%p %c %o,,.)
11:55:09:   -> tcp_read(3430) using WSAEventSelect
11:55:09:   -> tcp_read(3430) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::open('C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/postmodule','rb')
11:55:09:   -> tcp_read(3340) using WSAEventSelect
11:55:09:   -> tcp_read(3340) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::open() setvbuf(_IONBF)
11:55:09:   -> tcp_read(3287) using WSAEventSelect
11:55:09:   -> tcp_read(3287) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::close()
11:55:09: S -> CFileAccess::close()
11:55:09:   -> tcp_read(3211) using WSAEventSelect
11:55:09:   -> tcp_read(3211) immediate timeout previous thread count -1/0
11:55:09: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:55:09: S -> run_trigger returning 0
11:55:09:   -> tcp_read(3102) using WSAEventSelect
11:55:09:   -> tcp_read(3102) immediate timeout previous thread count -1/0
11:55:09: S -> do_module clean up.
11:55:09:   -> tcp_read(3065) using WSAEventSelect
11:55:09:   -> tcp_read(3065) immediate timeout previous thread count -1/0
11:55:09: S -> do_module return errors=0.
11:55:09: S -> Process warnings about client versions out of date...
11:55:09:   -> tcp_read(2950) using WSAEventSelect
11:55:09:   -> tcp_read(2950) immediate timeout previous thread count -1/0
11:55:09: S -> run postcommand proc server
11:55:09:   -> tcp_read(2905) using WSAEventSelect
11:55:09:   -> tcp_read(2905) immediate timeout previous thread count -1/0
11:55:09: S -> run_trigger()
11:55:09: S -> Call pre-loaded 'info.dll'
11:55:09:   -> tcp_read(2830) using WSAEventSelect
11:55:09:   -> tcp_read(2830) immediate timeout previous thread count -1/0
11:55:09: S -> postcommand_proc()
11:55:09:   -> tcp_read(2794) using WSAEventSelect
11:55:09:   -> tcp_read(2794) immediate timeout previous thread count -1/0
11:55:09: S -> default_trigger: parse_info(CVSROOT/postcommand,%r/%p %c,,)
11:55:09:   -> tcp_read(2717) using WSAEventSelect
11:55:09:   -> tcp_read(2717) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::open('C:\PROGRA~3\MARCHH~1\myrepo/CVSROOT/postcommand','rb')
11:55:09: S -> CFileAccess::open() setvbuf(_IONBF)
11:55:09:   -> tcp_read(2573) using WSAEventSelect
11:55:09:   -> tcp_read(2573) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::close()
11:55:09:   -> tcp_read(2535) using WSAEventSelect
11:55:09:   -> tcp_read(2535) immediate timeout previous thread count -1/0
11:55:09: S -> CFileAccess::close()
11:55:09: S -> Call pre-loaded 'info.dll' returned 0, total ret 0
11:55:09:   -> tcp_read(2429) using WSAEventSelect
11:55:09:   -> tcp_read(2429) immediate timeout previous thread count -1/0
11:55:09: S -> run_trigger returning 0
11:55:09:   -> tcp_read(2388) using WSAEventSelect
11:55:09:   -> tcp_read(2388) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading checkout.dll
11:55:09: S -> Unloading - about to destroy
11:55:09:   -> tcp_read(2302) using WSAEventSelect
11:55:09:   -> tcp_read(2302) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - destroyed
11:55:09: S -> Unloading - about to get lib
11:55:09:   -> tcp_read(2217) using WSAEventSelect
11:55:09:   -> tcp_read(2217) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to unload checkout.dll
11:55:09:   -> tcp_read(2159) using WSAEventSelect
11:55:09:   -> tcp_read(2159) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - unloaded
11:55:09:   -> tcp_read(2121) using WSAEventSelect
11:55:09:   -> tcp_read(2121) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to free
11:55:09: S -> Unloading - about to delete trigg
11:55:09:   -> tcp_read(2027) using WSAEventSelect
11:55:09:   -> tcp_read(2027) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to delete inf
11:55:09: S -> Unloading - complete
11:55:09:   -> tcp_read(1940) using WSAEventSelect
11:55:09:   -> tcp_read(1940) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading email.dll
11:55:09:   -> tcp_read(1903) using WSAEventSelect
11:55:09:   -> tcp_read(1903) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to destroy
11:55:09: S -> Unloading - destroyed
11:55:09:   -> tcp_read(1818) using WSAEventSelect
11:55:09:   -> tcp_read(1818) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to get lib
11:55:09:   -> tcp_read(1772) using WSAEventSelect
11:55:09:   -> tcp_read(1772) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to unload email.dll
11:55:09:   -> tcp_read(1717) using WSAEventSelect
11:55:09:   -> tcp_read(1717) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - unloaded
11:55:09:   -> tcp_read(1679) using WSAEventSelect
11:55:09:   -> tcp_read(1679) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to free
11:55:09:   -> tcp_read(1636) using WSAEventSelect
11:55:09:   -> tcp_read(1636) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to delete trigg
11:55:09: S -> Unloading - about to delete inf
11:55:09:   -> tcp_read(1536) using WSAEventSelect
11:55:09:   -> tcp_read(1536) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - complete
11:55:09:   -> tcp_read(1498) using WSAEventSelect
11:55:09:   -> tcp_read(1498) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading info.dll
11:55:09:   -> tcp_read(1462) using WSAEventSelect
11:55:09:   -> tcp_read(1462) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to close
11:55:09: S -> Unloading - closed
11:55:09:   -> tcp_read(1382) using WSAEventSelect
11:55:09:   -> tcp_read(1382) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to destroy
11:55:09:   -> tcp_read(1336) using WSAEventSelect
11:55:09:   -> tcp_read(1336) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - destroyed
11:55:09: S -> Unloading - about to get lib
11:55:09:   -> tcp_read(1251) using WSAEventSelect
11:55:09:   -> tcp_read(1251) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to unload info.dll
11:55:09:   -> tcp_read(1197) using WSAEventSelect
11:55:09:   -> tcp_read(1197) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - unloaded
11:55:09:   -> tcp_read(1159) using WSAEventSelect
11:55:09:   -> tcp_read(1159) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to free
11:55:09: S -> Unloading - about to delete trigg
11:55:09:   -> tcp_read(1065) using WSAEventSelect
11:55:09:   -> tcp_read(1065) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to delete inf
11:55:09: S -> Unloading - complete
11:55:09:   -> tcp_read(978) using WSAEventSelect
11:55:09:   -> tcp_read(978) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading script.dll
11:55:09:   -> tcp_read(940) using WSAEventSelect
11:55:09:   -> tcp_read(940) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to destroy
11:55:09: S -> Unloading - destroyed
11:55:09:   -> tcp_read(855) using WSAEventSelect
11:55:09:   -> tcp_read(855) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to get lib
11:55:09:   -> tcp_read(809) using WSAEventSelect
11:55:09:   -> tcp_read(809) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to unload script.dll
11:55:09:   -> tcp_read(753) using WSAEventSelect
11:55:09:   -> tcp_read(753) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - unloaded
11:55:09:   -> tcp_read(715) using WSAEventSelect
11:55:09:   -> tcp_read(715) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to free
11:55:09: S -> Unloading - about to delete trigg
11:55:09:   -> tcp_read(621) using WSAEventSelect
11:55:09:   -> tcp_read(621) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to delete inf
11:55:09: S -> Unloading - complete
11:55:09:   -> tcp_read(534) using WSAEventSelect
11:55:09:   -> tcp_read(534) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading sync.dll
11:55:09: S -> Unloading - about to destroy
11:55:09:   -> tcp_read(452) using WSAEventSelect
11:55:09:   -> tcp_read(452) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - destroyed
11:55:09: S -> Unloading - about to get lib
11:55:09:   -> tcp_read(367) using WSAEventSelect
11:55:09:   -> tcp_read(367) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to unload sync.dll
11:55:09:   -> tcp_read(313) using WSAEventSelect
11:55:09:   -> tcp_read(313) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - unloaded
11:55:09: S -> Unloading - about to free
11:55:09:   -> tcp_read(232) using WSAEventSelect
11:55:09:   -> tcp_read(232) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to delete trigg
11:55:09:   -> tcp_read(181) using WSAEventSelect
11:55:09:   -> tcp_read(181) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - about to delete inf
11:55:09:   -> tcp_read(132) using WSAEventSelect
11:55:09:   -> tcp_read(132) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - complete
11:55:09: S -> Unloading - about to clear list
11:55:09:   -> tcp_read(45) using WSAEventSelect
11:55:09:   -> tcp_read(45) immediate timeout previous thread count -1/0
11:55:09: S -> Unloading - cleared list
11:55:09:   -> Server: return from the requested function
11:55:09:   -> Unloading - about to clear list
11:55:09:   -> Unloading - cleared list
11:55:09:   -> Unloading sspi
11:55:09:   -> Process 2012 exiting
11:55:09:   -> free_directory()
11:55:09:   -> free_modules2() directory_stack_size and rubbish set to zero
11:55:09:   -> Free CVS_Username and cvs_locale
11:55:09:   -> Perform SYSTEM_CLEANUP ()
11:55:09:   -> Free global_session_time and global_real_session_time
11:55:09:   -> CCvsgui::Close
11:55:09:   -> main return OK
11:55:09:   -> Set the terminate semaphore
11:55:09:   -> Free trace_file