11:27:09:   -> Tracelevel set to 4.  PID is 3460
11:27:09:   -> Session ID is d84484ffcdd5cf8
11:27:09:   -> Session time is Wed Jun 11 16:27:09 2008
11:27:09:   -> Client was compiled Jun  9 2008 12:56:20
11:27:09:   -> Client was compiled with MSVC C/C++ 1310
11:27:09:   -> Client build platform is microsoft-windows-x86
11:27:09:   -> Client running on "Microsoft Windows XP Professional Service Pack 2 (Build 2600) [5.1.2600] (Service Pack 2)"
11:27:09:   -> /3GB is not enabled, highest user address is 7ffeffff
11:27:09:   -> Client has      38 percent of memory in use.
11:27:09:   -> Client has 2086988 total Kbytes of physical memory*.
11:27:09:   -> Client has 1282920 free Kbytes of physical memory*.
11:27:09:   -> Client has 2086988 total Kbytes of physical memory.
11:27:09:   -> Client has 1282920 free Kbytes of physical memory.
11:27:09:   -> Client has 4025300 total Kbytes of paging file.
11:27:09:   -> Client has 3397380 free Kbytes of paging file.
11:27:09:   -> Client has 2097024 total Kbytes of virtual memory.
11:27:09:   -> Client has 2054368 free Kbytes of virtual memory.
11:27:09:   -> Client has       0 free Kbytes of extended memory.
11:27:09:   -> Client Hardware information: 
11:27:09:   ->   Number of processors: 2
11:27:09:   ->   Processor type: 586
11:27:09:   ->   processor architecture: x32
11:27:09:   ->   processor 386/486 model: 1024 step: 4
11:27:09:   -> Free space in C:\DOCUME~1\gstarret\LOCALS~1\Temp 2587586560
11:27:09:   -> Total space in C:\DOCUME~1\gstarret\LOCALS~1\Temp 4283957248
11:27:09:   -> Total Free space in C:\DOCUME~1\gstarret\LOCALS~1\Temp 2587586560
11:27:09:   -> Loading protocol sserver as sserver.dll
11:27:09:   -> CLibraryAccess::Load loading c:\PROGRA~1\CVSSUI~1\cvsnt/protocols/sserver.dll
11:27:09:   -> main loop with CVSROOT=:sserver:stanley:/test2
11:27:09:   -> main - about to start the server
11:27:09:   -> start_server(0)
11:27:09:   -> client start - client_protocol->connect
11:27:09:   -> Buffer size is 5120
11:27:09:   -> tcp_write(23)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> tcp_read(1)
11:27:09:   -> client start - Loop, getting responses from the server.
11:27:09:   -> client start - got "I LOVE YOU"
11:27:09:   -> client start - continue login.
11:27:09:   -> client start - server started.
11:27:09:   -> client start - send Valid-responses to server.
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=0
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=15
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=2 nbytes=16
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=18
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=5 nbytes=19
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=24
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=25
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=39
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=10 nbytes=40
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=50
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=9 nbytes=51
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=60
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=61
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=69
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=9 nbytes=70
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=79
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=80
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=87
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=88
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=95
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=96
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=111
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=6 nbytes=112
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=118
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=119
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=126
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=127
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=135
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=136
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=150
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=4 nbytes=151
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=155
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=156
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=164
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=165
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=172
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=173
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=180
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=181
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=193
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=20 nbytes=194
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=214
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=22 nbytes=215
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=237
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=10 nbytes=238
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=248
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=249
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=261
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=262
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=270
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=8 nbytes=271
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=279
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=280
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=296
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=297
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=314
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=315
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=327
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=6 nbytes=328
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=334
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=335
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=347
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=348
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=349
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=350
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=357
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=358
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=359
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=360
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=361
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=2 nbytes=362
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=364
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=365
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=381
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=382
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=396
11:27:09:   -> client start - send valid-requests to server.
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=397
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=412
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=424
11:27:09:   -> client start - rootless_encryption.
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=5 nbytes=425
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=6 nbytes=430
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=436
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=437
11:27:09:   -> Server codepage is UTF-8
11:27:09:   -> Client codepage is CP1252
11:27:09:   -> Server->Client codepage translation is active
11:27:09:   -> Transcode client-version 
11:27:09:   -> Transcode returned client-version 
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=453
11:27:09:   -> Transcode CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)
11:27:09:   -> Transcode returned CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=40 nbytes=468
11:27:09:   -> Transcode 

11:27:09:   -> Transcode returned 

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=508
11:27:09:   -> Transcode CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)
11:27:09:   -> Transcode returned CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)
11:27:09:   -> Server version is CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)
11:27:09:   -> Client version is CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)
11:27:09:   -> Transcode Global_option -t

11:27:09:   -> Transcode returned Global_option -t

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=509
11:27:09:   -> Transcode Global_option -t

11:27:09:   -> Transcode returned Global_option -t

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=526
11:27:09:   -> Transcode Global_option -t

11:27:09:   -> Transcode returned Global_option -t

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=543
11:27:09:   -> Transcode Global_option -t

11:27:09:   -> Transcode returned Global_option -t

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=560
11:27:09:   -> Transcode Valid-RcsOptions 
11:27:09:   -> Transcode returned Valid-RcsOptions 
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=577
11:27:09:   -> Transcode b
11:27:09:   -> Transcode returned b
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=594
11:27:09:   -> Transcode B
11:27:09:   -> Transcode returned B
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=595
11:27:09:   -> Transcode u
11:27:09:   -> Transcode returned u
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=596
11:27:09:   -> Transcode t
11:27:09:   -> Transcode returned t
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=597
11:27:09:   -> Transcode {
11:27:09:   -> Transcode returned {
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=598
11:27:09:   -> Transcode c
11:27:09:   -> Transcode returned c
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=599
11:27:09:   -> Transcode x
11:27:09:   -> Transcode returned x
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=600
11:27:09:   -> Transcode k
11:27:09:   -> Transcode returned k
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=601
11:27:09:   -> Transcode v
11:27:09:   -> Transcode returned v
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=602
11:27:09:   -> Transcode V
11:27:09:   -> Transcode returned V
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=603
11:27:09:   -> Transcode l
11:27:09:   -> Transcode returned l
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=604
11:27:09:   -> Transcode L
11:27:09:   -> Transcode returned L
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=605
11:27:09:   -> Transcode M
11:27:09:   -> Transcode returned M
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=606
11:27:09:   -> Transcode D
11:27:09:   -> Transcode returned D
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=607
11:27:09:   -> Transcode o
11:27:09:   -> Transcode returned o
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=608
11:27:09:   -> Transcode z
11:27:09:   -> Transcode returned z
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=609
11:27:09:   -> Transcode s
11:27:09:   -> Transcode returned s
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=610
11:27:09:   -> Transcode 1
11:27:09:   -> Transcode returned 1
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=611
11:27:09:   -> Transcode 

11:27:09:   -> Transcode returned 

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=612
11:27:09:   -> Requesting server cvsignore
11:27:09:   -> Transcode read-cvsignore

11:27:09:   -> Transcode returned read-cvsignore

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=15 nbytes=613
11:27:09:   -> Transcode 0
11:27:09:   -> Transcode returned 0
11:27:09:   -> wrap_add(*.a -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.a"
11:27:09:   -> wrap_add(*.avi -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.avi"
11:27:09:   -> wrap_add(*.bin -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.bin"
11:27:09:   -> wrap_add(*.bmp -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.bmp"
11:27:09:   -> wrap_add(*.bz2 -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.bz2"
11:27:09:   -> wrap_add(*.chm -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.chm"
11:27:09:   -> wrap_add(*.class -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.class"
11:27:09:   -> wrap_add(*.dll -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.dll"
11:27:09:   -> wrap_add(*.doc -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.doc"
11:27:09:   -> wrap_add(*.mpp -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.mpp"
11:27:09:   -> wrap_add(*.dvi -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.dvi"
11:27:09:   -> wrap_add(*.exe -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.exe"
11:27:09:   -> wrap_add(*.gif -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.gif"
11:27:09:   -> wrap_add(*.gz -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.gz"
11:27:09:   -> wrap_add(*.hqx -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.hqx"
11:27:09:   -> wrap_add(*.ico -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.ico"
11:27:09:   -> wrap_add(*.ilk -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.ilk"
11:27:09:   -> wrap_add(*.iso -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.iso"
11:27:09:   -> wrap_add(*.lib -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.lib"
11:27:09:   -> wrap_add(*.jar -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.jar"
11:27:09:   -> wrap_add(*.jpg -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.jpg"
11:27:09:   -> wrap_add(*.jpeg -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.jpeg"
11:27:09:   -> wrap_add(*.lnk -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.lnk"
11:27:09:   -> wrap_add(*.mpg -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.mpg"
11:27:09:   -> wrap_add(*.mpeg -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.mpeg"
11:27:09:   -> wrap_add(*.mov -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.mov"
11:27:09:   -> wrap_add(*.mp3 -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.mp3"
11:27:09:   -> wrap_add(*.ncb -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.ncb"
11:27:09:   -> wrap_add(*.o -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.o"
11:27:09:   -> wrap_add(*.ogg -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.ogg"
11:27:09:   -> wrap_add(*.obj -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.obj"
11:27:09:   -> wrap_add(*.pdb -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.pdb"
11:27:09:   -> wrap_add(*.pdf -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.pdf"
11:27:09:   -> wrap_add(*.png -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.png"
11:27:09:   -> wrap_add(*.ppt -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.ppt"
11:27:09:   -> wrap_add(*.res -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.res"
11:27:09:   -> wrap_add(*.rpm -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.rpm"
11:27:09:   -> wrap_add(*.sit -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.sit"
11:27:09:   -> wrap_add(*.so -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.so"
11:27:09:   -> wrap_add(*.tar -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.tar"
11:27:09:   -> wrap_add(*.tga -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.tga"
11:27:09:   -> wrap_add(*.tgz -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.tgz"
11:27:09:   -> wrap_add(*.tif -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.tif"
11:27:09:   -> wrap_add(*.tiff -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.tiff"
11:27:09:   -> wrap_add(*.wav -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.wav"
11:27:09:   -> wrap_add(*.wmv -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.wmv"
11:27:09:   -> wrap_add(*.xls -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.xls"
11:27:09:   -> wrap_add(*.zip -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.zip"
11:27:09:   -> wrap_add(*.Z -kb, 0, 0, 0, 0)
11:27:09:   -> Call CFileAccess::mimetype: "*.Z"
11:27:09:   -> Requesting server cvswrappers
11:27:09:   -> Transcode read-cvswrappers

11:27:09:   -> Transcode returned read-cvswrappers

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=17 nbytes=628
11:27:09:   -> Transcode 515
11:27:09:   -> Transcode returned 515
11:27:09:   -> The first item in the sorted list is "b" with wildcard "*.a"
11:27:09:   -> Requesting server cvsrc (read-cvsrc2)
11:27:09:   -> Transcode read-cvsrc2

11:27:09:   -> Transcode returned read-cvsrc2

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=12 nbytes=645
11:27:09:   -> Transcode 0
11:27:09:   -> Transcode returned 0
11:27:09:   -> Parsing global cvsrc started
11:27:09:   -> Parsing global cvsrc finished
11:27:09:   -> start_recursion((null),(null))
11:27:09:   -> start_recursion() dirleaveproc is NULL
11:27:09:   -> start_recursion() update_dir set to ""
11:27:09:   -> start_recursion - argument count (argc) == 1
11:27:09:   -> * There were arguments, so we have to handle them by hand. To do
11:27:09:   -> * that, we set up the filelist and dirlist with the arguments and
11:27:09:   -> * call do_recursion.  do_recursion recognizes the fact that the
11:27:09:   -> * lists are non-null when it starts and doesn't update them.
11:27:09:   -> 
11:27:09:   -> * explicitly named directories are stored in dirlist.
11:27:09:   -> * explicitly named files are stored in filelist.
11:27:09:   -> * other possibility is named entities whicha are not currently in
11:27:09:   -> * the working directory.
11:27:09:   -> * if this argument is a directory, then add it to the list of directories.
11:27:09:   -> * otherwise, split argument into directory and component names.
11:27:09:   -> * Now break out argv[i] into directory part (DIR) and file part (COMP).
11:27:09:   ->   DIR and COMP will each point to a newly malloc'd string. 
11:27:09:   -> ParseTag_Dir(.,<something>,<something>,0,)
11:27:09:   -> start_recursion: (1) addfile(files_by_dir,".","A'pos_test.xls")
11:27:09:   -> * At this point we have looped over all named arguments and built
11:27:09:   ->   a coupla lists.  Now we unroll the lists, setting up and
11:27:09:   ->   calling do_recursion.
11:27:09:   -> call walklist() with unroll_files_proc and files_by_dir
11:27:09:   -> unroll_files_proc() begin
11:27:09:   -> * if this dir was also an explicitly named argument, then skip
11:27:09:   ->   it.  We'll catch it later when we do dirs.
11:27:09:   -> * otherwise, call dorecusion for this list of files.
11:27:09:   -> Name_Repository((null),)
11:27:09:   -> Name_Repository dup tmp is len
11:27:09:   -> Name_Repository dup tmp is now len
11:27:09:   -> Name_Repository open CVS/Repository
11:27:09:   -> Name_Repository opened CVS/Repository ok so read a line
11:27:09:   -> Name_Repository closed CVS/Repository
11:27:09:   -> Name_Repository read 1 AposTests

11:27:09:   -> Name_Repository (stripped the newline) read 2 AposTests
11:27:09:   -> Name_Repository isabsolute( AposTests )?
11:27:09:   -> Name_Repository isabsolute( AposTests )!
11:27:09:   -> Name_Repository Sanitize_Repository_Name( /test2/AposTests )!
11:27:09:   -> Name_Repository return ( /test2/AposTests )!
11:27:09:   -> ParseTag()
11:27:09:   -> ParseTag_Dir((null),<something>,<something>,0,<something>)
11:27:09:   -> open_directory(/test2/AposTests,.,(null),(null),0,(null),1)
11:27:09:   -> open_directory() directory_stack_size increased by one to 1
11:27:09:   -> Look for rename script file CVS/Rename
11:27:09:   -> Rename script file does not exist
11:27:09:   -> directory opened
11:27:09:   -> do_recursion: begin
11:27:09:   -> do_recursion: Check the value in CVSADM_ROOT 
11:27:09:   -> do_recursion: server does not appear to be active, update_dir=
11:27:09:   -> do_recursion: Fill in repository with the current repository
11:27:09:   -> do_recursion: update_dir=
11:27:09:   -> Name_Repository((null),)
11:27:09:   -> Name_Repository dup tmp is len
11:27:09:   -> Name_Repository dup tmp is now len
11:27:09:   -> Name_Repository open CVS/Repository
11:27:09:   -> Name_Repository opened CVS/Repository ok so read a line
11:27:09:   -> Name_Repository closed CVS/Repository
11:27:09:   -> Name_Repository read 1 AposTests

11:27:09:   -> Name_Repository (stripped the newline) read 2 AposTests
11:27:09:   -> Name_Repository isabsolute( AposTests )?
11:27:09:   -> Name_Repository isabsolute( AposTests )!
11:27:09:   -> Name_Repository Sanitize_Repository_Name( /test2/AposTests )!
11:27:09:   -> Name_Repository return ( /test2/AposTests )!
11:27:09:   -> do_recursion: update_dir= repository=/test2/AposTests
11:27:09:   -> !(repository && (server_active || !current_parsed_root->isremote)) - so just strdup
11:27:09:   -> Repository = /test2/AposTests
11:27:09:   -> Mapped repository = /test2/AposTests
11:27:09:   -> * The filesdoneproc needs to be called for each directory where files
11:27:09:   -> * processed, or each directory that is processed by a call where no
11:27:09:   -> * directories were passed in.  In fact, the only time we don't want to
11:27:09:   -> * call back the filesdoneproc is when we are processing directories that
11:27:09:   -> * were passed in on the command line (or in the special case of `.' when
11:27:09:   -> * we were called with no args
11:27:09:   -> do_recursion: dirlist == NULL && filelist == NULL
11:27:09:   -> do_recursion: dodoneproc=1
11:27:09:   -> * If filelist or dirlist is already set, we don't look again. Otherwise,
11:27:09:   -> * find the files and directories
11:27:09:   -> do_recursion: something was passed on the command line 
11:27:09:   -> do_recursion: we will process files, so pre-parse entries 
11:27:09:   -> Entries_Open()
11:27:09:   -> Entries_Open ParseTag()
11:27:09:   -> ParseTag()
11:27:09:   -> ParseTag_Dir((null),<something>,<something>,0,)
11:27:09:   -> Entries_Open aflag==0 && dirtag==0 && dirdate==0
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries","r")
11:27:09:   -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode
11:27:09:   -> Entnode_Create()
11:27:09:   -> Entnode_Create() finished
11:27:09:   -> AddEntryNode()
11:27:09:   -> AddEntryNode() get a node and fill in the regular stuff 
11:27:09:   -> AddEntryNode() this one gets a key of the name for hashing 
11:27:09:   -> AddEntryNode() put the node into the list 
11:27:09:   -> AddEntryNode() finished
11:27:09:   -> Entries_Open fclose() CVSADM_ENT 
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r")
11:27:09:   -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle
11:27:09:   -> Entries_Open fclose() CVSADM_ENTEXT 
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r")
11:27:09:   -> Entries_Open clean up and return 
11:27:09:   -> Entries_Open done
11:27:09:   -> do_recursion: process the files (if any) 
11:27:09:   -> do_recursion: read lock it if necessary 
11:27:09:   -> * For the server, we handle notifications in a completely different
11:27:09:   ->   place (server_notify).  For local, we can't do them here--we don't
11:27:09:   ->   have writelocks in place, and there is no way to get writelocks here
11:27:09:   -> do_recursion: process the files by calling walklist()
11:27:09:   -> do_file_proc: process the files "A'pos_test.xls"
11:27:09:   -> !(server_active || !current_parsed_root->isremote) - so just strdup
11:27:09:   -> do_file_proc: mapped name of file "A'pos_test.xls"
11:27:09:   -> do_file_proc: call findnode_fn()
11:27:09:   -> do_file_proc: got a result from findnode_fn()
11:27:09:   -> do_file_proc: verify_access() returned ok
11:27:09:   -> Register(A'pos_test.xls, 1.2, Wed Jun 11 16:23:57 2008, bx,  , , , , )
11:27:09:   -> Entnode_Create()
11:27:09:   -> Entnode_Create() finished
11:27:09:   -> AddEntryNode()
11:27:09:   -> AddEntryNode() was it already there? -- apparently yes
11:27:09:   -> AddEntryNode() take it out 
11:27:09:   -> AddEntryNode() get a node and fill in the regular stuff 
11:27:09:   -> AddEntryNode() this one gets a key of the name for hashing 
11:27:09:   -> AddEntryNode() put the node into the list 
11:27:09:   -> AddEntryNode() finished
11:27:09:   -> Register(): !noexec
11:27:09:   -> Register(): finished
11:27:09:   -> xchmod(A'pos_test.xls,0)
11:27:09:   -> do_file_proc: finished fileproc()
11:27:09:   -> do_file_proc: return
11:27:09:   -> do_recursion: unlock it 
11:27:09:   -> do_recursion: clean up by calling dellist(filelist)
11:27:09:   -> do_recursion: call-back files done proc (if any) 
11:27:09:   -> do_recursion: no: don't call-back files done proc (process_this_directorydodoneprocframe->filesdoneproc == NULL) 
11:27:09:   -> do_recursion: at skip_directory 
11:27:09:   -> do_recursion: process the directories (if necessary) 
11:27:09:   -> do_recursion: cannot process the directories (dirlist == NULL) 
11:27:09:   -> Entries_Close - isfile ("CVS/Entries.Log")
11:27:09:   -> Entries_Close - write_entries (list)
11:27:09:   -> write_entries() CVSADM_ENTBAK entfilename="CVS/Entries.Log"
11:27:09:   -> write_entries() CVSADM_ENTBAK ok
11:27:09:   -> write_entries() CVSADM_ENTEXBAK ok
11:27:09:   -> write_entries() !sawdir
11:27:09:   -> write_entries() now, atomically (on systems that support it) rename it 
11:27:09:   -> rename(CVS/Entries.Backup,CVS/Entries)
11:27:09:   -> wnt_rename(CVS/Entries.Backup,CVS/Entries)
11:27:09:   -> rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra)
11:27:09:   -> wnt_rename(CVS/Entries.Extra.Backup,CVS/Entries.Extra)
11:27:09:   -> write_entries() now, remove the log file 
11:27:09:   -> unlink_file(CVS/Entries.Log)
11:27:09:   -> unlink_file(CVS/Entries.Extra.Log)
11:27:09:   -> write_entries() done
11:27:09:   -> Entries_Close - dellist (list)
11:27:09:   -> do_recursion: free the saved copy of the pointer if necessary 
11:27:09:   -> more efficient - close directory in unroll_files_proc?
11:27:09:   -> close_directory()
11:27:09:   -> close_directory() directory_stack_size decreased by one to 0 
11:27:09:   -> unroll_files_proc() end
11:27:09:   ->   walklist call with unroll_files_proc done. err=0
11:27:09:   -> freenode() free the badbad DIRS
11:27:09:   ->   dellist after unroll_files_proc done.
11:27:09:   -> start_recursion: cannot do_recursion on the dirlist.
11:27:09:   -> start_recursion: Free the data which expand_wild allocated.
11:27:09:   -> start_recursion: Free the data - update_dir
11:27:09:   -> start_recursion: Free the data - update_repos
11:27:09:   -> start_recursion: (args_to_send_when_finished == NULL)
11:27:09:   -> start_recursion: return 0.
11:27:09:   -> start_recursion((null),(null))
11:27:09:   -> start_recursion() dirleaveproc is NULL
11:27:09:   -> start_recursion() update_dir set to ""
11:27:09:   -> start_recursion - argument count (argc) == 1
11:27:09:   -> * There were arguments, so we have to handle them by hand. To do
11:27:09:   -> * that, we set up the filelist and dirlist with the arguments and
11:27:09:   -> * call do_recursion.  do_recursion recognizes the fact that the
11:27:09:   -> * lists are non-null when it starts and doesn't update them.
11:27:09:   -> 
11:27:09:   -> * explicitly named directories are stored in dirlist.
11:27:09:   -> * explicitly named files are stored in filelist.
11:27:09:   -> * other possibility is named entities whicha are not currently in
11:27:09:   -> * the working directory.
11:27:09:   -> * if this argument is a directory, then add it to the list of directories.
11:27:09:   -> * otherwise, split argument into directory and component names.
11:27:09:   -> * Now break out argv[i] into directory part (DIR) and file part (COMP).
11:27:09:   ->   DIR and COMP will each point to a newly malloc'd string. 
11:27:09:   -> ParseTag_Dir(.,<something>,<something>,0,)
11:27:09:   -> start_recursion: (1) addfile(files_by_dir,".","A'pos_test.xls")
11:27:09:   -> * At this point we have looped over all named arguments and built
11:27:09:   ->   a coupla lists.  Now we unroll the lists, setting up and
11:27:09:   ->   calling do_recursion.
11:27:09:   -> call walklist() with unroll_files_proc and files_by_dir
11:27:09:   -> unroll_files_proc() begin
11:27:09:   -> * if this dir was also an explicitly named argument, then skip
11:27:09:   ->   it.  We'll catch it later when we do dirs.
11:27:09:   -> * otherwise, call dorecusion for this list of files.
11:27:09:   -> Name_Repository((null),)
11:27:09:   -> Name_Repository dup tmp is len
11:27:09:   -> Name_Repository dup tmp is now len
11:27:09:   -> Name_Repository open CVS/Repository
11:27:09:   -> Name_Repository opened CVS/Repository ok so read a line
11:27:09:   -> Name_Repository closed CVS/Repository
11:27:09:   -> Name_Repository read 1 AposTests

11:27:09:   -> Name_Repository (stripped the newline) read 2 AposTests
11:27:09:   -> Name_Repository isabsolute( AposTests )?
11:27:09:   -> Name_Repository isabsolute( AposTests )!
11:27:09:   -> Name_Repository Sanitize_Repository_Name( /test2/AposTests )!
11:27:09:   -> Name_Repository return ( /test2/AposTests )!
11:27:09:   -> ParseTag()
11:27:09:   -> ParseTag_Dir((null),<something>,<something>,0,<something>)
11:27:09:   -> open_directory(/test2/AposTests,.,(null),(null),0,(null),1)
11:27:09:   -> open_directory() directory_stack_size increased by one to 1
11:27:09:   -> Look for rename script file CVS/Rename
11:27:09:   -> Rename script file does not exist
11:27:09:   -> directory opened
11:27:09:   -> do_recursion: begin
11:27:09:   -> do_recursion: Check the value in CVSADM_ROOT 
11:27:09:   -> do_recursion: server does not appear to be active, update_dir=
11:27:09:   -> do_recursion: Fill in repository with the current repository
11:27:09:   -> do_recursion: update_dir=
11:27:09:   -> Name_Repository((null),)
11:27:09:   -> Name_Repository dup tmp is len
11:27:09:   -> Name_Repository dup tmp is now len
11:27:09:   -> Name_Repository open CVS/Repository
11:27:09:   -> Name_Repository opened CVS/Repository ok so read a line
11:27:09:   -> Name_Repository closed CVS/Repository
11:27:09:   -> Name_Repository read 1 AposTests

11:27:09:   -> Name_Repository (stripped the newline) read 2 AposTests
11:27:09:   -> Name_Repository isabsolute( AposTests )?
11:27:09:   -> Name_Repository isabsolute( AposTests )!
11:27:09:   -> Name_Repository Sanitize_Repository_Name( /test2/AposTests )!
11:27:09:   -> Name_Repository return ( /test2/AposTests )!
11:27:09:   -> do_recursion: update_dir= repository=/test2/AposTests
11:27:09:   -> !(repository && (server_active || !current_parsed_root->isremote)) - so just strdup
11:27:09:   -> Repository = /test2/AposTests
11:27:09:   -> Mapped repository = /test2/AposTests
11:27:09:   -> * The filesdoneproc needs to be called for each directory where files
11:27:09:   -> * processed, or each directory that is processed by a call where no
11:27:09:   -> * directories were passed in.  In fact, the only time we don't want to
11:27:09:   -> * call back the filesdoneproc is when we are processing directories that
11:27:09:   -> * were passed in on the command line (or in the special case of `.' when
11:27:09:   -> * we were called with no args
11:27:09:   -> do_recursion: dirlist == NULL && filelist == NULL
11:27:09:   -> do_recursion: dodoneproc=1
11:27:09:   -> * If filelist or dirlist is already set, we don't look again. Otherwise,
11:27:09:   -> * find the files and directories
11:27:09:   -> do_recursion: something was passed on the command line 
11:27:09:   -> do_recursion: we will process files, so pre-parse entries 
11:27:09:   -> Entries_Open()
11:27:09:   -> Entries_Open ParseTag()
11:27:09:   -> ParseTag()
11:27:09:   -> ParseTag_Dir((null),<something>,<something>,0,)
11:27:09:   -> Entries_Open aflag==0 && dirtag==0 && dirdate==0
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries","r")
11:27:09:   -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode
11:27:09:   -> Entnode_Create()
11:27:09:   -> Entnode_Create() finished
11:27:09:   -> AddEntryNode()
11:27:09:   -> AddEntryNode() get a node and fill in the regular stuff 
11:27:09:   -> AddEntryNode() this one gets a key of the name for hashing 
11:27:09:   -> AddEntryNode() put the node into the list 
11:27:09:   -> AddEntryNode() finished
11:27:09:   -> Entries_Open fclose() CVSADM_ENT 
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r")
11:27:09:   -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle
11:27:09:   -> Entries_Open fclose() CVSADM_ENTEXT 
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r")
11:27:09:   -> Entries_Open clean up and return 
11:27:09:   -> Entries_Open done
11:27:09:   -> do_recursion: process the files (if any) 
11:27:09:   -> do_recursion: read lock it if necessary 
11:27:09:   -> * For the server, we handle notifications in a completely different
11:27:09:   ->   place (server_notify).  For local, we can't do them here--we don't
11:27:09:   ->   have writelocks in place, and there is no way to get writelocks here
11:27:09:   -> send_a_repository(,/test2/AposTests,)
11:27:09:   -> send_repository(,/test2/AposTests,)
11:27:09:   -> allocate adm_name * 80 is large enough for any of CVSADM_*.  
11:27:09:   -> Transcode Directory 
11:27:09:   -> Transcode returned Directory 
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=10 nbytes=657
11:27:09:   -> * Send the directory name.  
11:27:09:   -> Transcode .��|u�|d
11:27:09:   -> Transcode returned .
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=667
11:27:09:   -> Transcode 

11:27:09:   -> Transcode returned 

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=668
11:27:09:   -> Transcode /test2/AposTests
11:27:09:   -> Transcode returned /test2/AposTests
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=16 nbytes=669
11:27:09:   -> Transcode 

11:27:09:   -> Transcode returned 

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=685
11:27:09:   -> Transcode Notify 
11:27:09:   -> Transcode returned Notify 
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=7 nbytes=686
11:27:09:   -> Transcode A'pos_test.xls
11:27:09:   -> Transcode returned A'pos_test.xls
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=14 nbytes=693
11:27:09:   -> Transcode 

11:27:09:   -> Transcode returned 

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=707
11:27:09:   -> Transcode U
11:27:09:   -> Transcode returned U
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=708
11:27:09:   -> Transcode 	
11:27:09:   -> Transcode returned 	
11:27:09:   -> send_to_server_untranslated() calls buf_output() len=1 nbytes=709
11:27:09:   -> Transcode Wed Jun 11 16:27:09 2008 GMT	mercury	C:\My_Documents\Test\stanley-test2\AposTests					

11:27:09:   -> Transcode returned Wed Jun 11 16:27:09 2008 GMT	mercury	C:\My_Documents\Test\stanley-test2\AposTests					

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=87 nbytes=710
11:27:09:   -> do_recursion: process the files by calling walklist()
11:27:09:   -> do_file_proc: process the files "A'pos_test.xls"
11:27:09:   -> !(server_active || !current_parsed_root->isremote) - so just strdup
11:27:09:   -> do_file_proc: mapped name of file "A'pos_test.xls"
11:27:09:   -> do_file_proc: call findnode_fn()
11:27:09:   -> do_file_proc: got a result from findnode_fn()
11:27:09:   -> do_file_proc: verify_access() returned ok
11:27:09:   -> do_file_proc: finished fileproc()
11:27:09:   -> do_file_proc: return
11:27:09:   -> do_recursion: unlock it 
11:27:09:   -> do_recursion: clean up by calling dellist(filelist)
11:27:09:   -> do_recursion: call-back files done proc (if any) 
11:27:09:   -> do_recursion: no: don't call-back files done proc (process_this_directorydodoneprocframe->filesdoneproc == NULL) 
11:27:09:   -> do_recursion: at skip_directory 
11:27:09:   -> do_recursion: process the directories (if necessary) 
11:27:09:   -> do_recursion: cannot process the directories (dirlist == NULL) 
11:27:09:   -> Entries_Close - isfile ("CVS/Entries.Log")
11:27:09:   -> Entries_Close - dellist (list)
11:27:09:   -> do_recursion: free the saved copy of the pointer if necessary 
11:27:09:   -> more efficient - close directory in unroll_files_proc?
11:27:09:   -> close_directory()
11:27:09:   -> close_directory() directory_stack_size decreased by one to 0 
11:27:09:   -> unroll_files_proc() end
11:27:09:   ->   walklist call with unroll_files_proc done. err=0
11:27:09:   -> freenode() free the badbad DIRS
11:27:09:   ->   dellist after unroll_files_proc done.
11:27:09:   -> start_recursion: cannot do_recursion on the dirlist.
11:27:09:   -> start_recursion: Free the data which expand_wild allocated.
11:27:09:   -> start_recursion: Free the data - update_dir
11:27:09:   -> start_recursion: Free the data - update_repos
11:27:09:   -> start_recursion: (args_to_send_when_finished == NULL)
11:27:09:   -> start_recursion: return 0.
11:27:09:   -> Transcode noop

11:27:09:   -> Transcode returned noop

11:27:09:   -> send_to_server_untranslated() calls buf_output() len=5 nbytes=797
11:27:09:   -> Transcode E 11:27:08: S -> serve_notify(type = U, time = Wed Jun 11 16:27:09 2008 GMT, hostname = mercury, bug = (null), tag = (null), message = )
11:27:09:   -> Transcode returned E 11:27:08: S -> serve_notify(type = U, time = Wed Jun 11 16:27:09 2008 GMT, hostname = mercury, bug = (null), tag = (null), message = )
11:27:08: S -> serve_notify(type = U, time = Wed Jun 11 16:27:09 2008 GMT, hostname = mercury, bug = (null), tag = (null), message = )
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository((null),(null))
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository((null),(null))
11:27:08: S -> Name_Repository((null),(null))
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository dup tmp is len
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository dup tmp is len
11:27:08: S -> Name_Repository dup tmp is len
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository dup tmp is now len
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository dup tmp is now len
11:27:08: S -> Name_Repository dup tmp is now len
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository open CVS/Repository
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository open CVS/Repository
11:27:08: S -> Name_Repository open CVS/Repository
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository opened CVS/Repository ok so read a line
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository opened CVS/Repository ok so read a line
11:27:08: S -> Name_Repository opened CVS/Repository ok so read a line
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository closed CVS/Repository
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository closed CVS/Repository
11:27:08: S -> Name_Repository closed CVS/Repository
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository read 1 /var/cvs/test2/AposTests
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository read 1 /var/cvs/test2/AposTests
11:27:08: S -> Name_Repository read 1 /var/cvs/test2/AposTests
11:27:09:   -> Transcode E 
11:27:09:   -> Transcode returned E 

11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository (stripped the newline) read 2 /var/cvs/test2/AposTests
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository (stripped the newline) read 2 /var/cvs/test2/AposTests
11:27:08: S -> Name_Repository (stripped the newline) read 2 /var/cvs/test2/AposTests
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository isabsolute( /var/cvs/test2/AposTests )?
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository isabsolute( /var/cvs/test2/AposTests )?
11:27:08: S -> Name_Repository isabsolute( /var/cvs/test2/AposTests )?
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository not isabsolute( /var/cvs/test2/AposTests )
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository not isabsolute( /var/cvs/test2/AposTests )
11:27:08: S -> Name_Repository not isabsolute( /var/cvs/test2/AposTests )
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository Sanitize_Repository_Name( /var/cvs/test2/AposTests )!
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository Sanitize_Repository_Name( /var/cvs/test2/AposTests )!
11:27:08: S -> Name_Repository Sanitize_Repository_Name( /var/cvs/test2/AposTests )!
11:27:09:   -> Transcode E 11:27:08: S -> Name_Repository return ( /var/cvs/test2/AposTests )!
11:27:09:   -> Transcode returned E 11:27:08: S -> Name_Repository return ( /var/cvs/test2/AposTests )!
11:27:08: S -> Name_Repository return ( /var/cvs/test2/AposTests )!
11:27:09:   -> Transcode E 11:27:08: S -> map_repository(/var/cvs/test2/AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> map_repository(/var/cvs/test2/AposTests)
11:27:08: S -> map_repository(/var/cvs/test2/AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2()
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2()
11:27:08: S -> lookup_module2()
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2(AposTests) after rename?
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2(AposTests) after rename?
11:27:08: S -> lookup_module2(AposTests) after rename?
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2() call _lookup_module2()
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2() call _lookup_module2()
11:27:08: S -> lookup_module2() call _lookup_module2()
11:27:09:   -> Transcode E 11:27:08: S -> Loading modules2 from /var/cvs/test2/CVSROOT/modules2
11:27:09:   -> Transcode returned E 11:27:08: S -> Loading modules2 from /var/cvs/test2/CVSROOT/modules2
11:27:08: S -> Loading modules2 from /var/cvs/test2/CVSROOT/modules2
11:27:09:   -> Transcode E 11:27:08: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:27:09:   -> Transcode returned E 11:27:08: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:27:08: S -> _lookup_module2 - continue_if_no_mod2=1 (default)
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2(AposTests,1)
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2(AposTests,1)
11:27:08: S -> lookup_module2(AposTests,1)
11:27:09:   -> Transcode E 11:27:08: S -> _lookup_module2 !p lookup_repository_module(AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> _lookup_module2 !p lookup_repository_module(AposTests)
11:27:08: S -> _lookup_module2 !p lookup_repository_module(AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> lookup_repository_module(AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_repository_module(AposTests)
11:27:08: S -> lookup_repository_module(AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2() calls to lookup_repository_module() returned nothing
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2() calls to lookup_repository_module() returned nothing
11:27:08: S -> lookup_module2() calls to lookup_repository_module() returned nothing
11:27:09:   -> Transcode E 11:27:08: S -> _lookup_module2 !mod return 0 left,right(,AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> _lookup_module2 !mod return 0 left,right(,AposTests)
11:27:08: S -> _lookup_module2 !mod return 0 left,right(,AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2() call _lookup_module2 returned 0
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2() call _lookup_module2 returned 0
11:27:08: S -> lookup_module2() call _lookup_module2 returned 0
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2() no recursion permitted so give up now
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2() no recursion permitted so give up now
11:27:08: S -> lookup_module2() no recursion permitted so give up now
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2() ret=0, current_directory==NULL, current_directory->directory_mappings=!!!!
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2() ret=0, current_directory==NULL, current_directory->directory_mappings=!!!!
11:27:08: S -> lookup_module2() ret=0, current_directory==NULL, current_directory->directory_mappings=!!!!
11:27:09:   -> Transcode E 11:27:08: S -> lookup_module2() return ret=0 renamed=0
11:27:09:   -> Transcode returned E 11:27:08: S -> lookup_module2() return ret=0 renamed=0
11:27:08: S -> lookup_module2() return ret=0 renamed=0
11:27:09:   -> Transcode E 11:27:08: S -> map_repository - return(repository) "/var/cvs/test2/AposTests"
11:27:09:   -> Transcode returned E 11:27:08: S -> map_repository - return(repository) "/var/cvs/test2/AposTests"
11:27:08: S -> map_repository - return(repository) "/var/cvs/test2/AposTests"
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_startdir(/var/cvs/test2/AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_startdir(/var/cvs/test2/AposTests)
11:27:08: S -> fileattr_startdir(/var/cvs/test2/AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> notify_do (U, A'pos_test.xls, gstarret, Wed Jun 11 16:27:09 2008 GMT, mercury, C:\My_Documents\Test\stanley-test2\AposTests, , /var/cvs/test2/AposTests, (null), , (null), )
11:27:09:   -> Transcode returned E 11:27:08: S -> notify_do (U, A'pos_test.xls, gstarret, Wed Jun 11 16:27:09 2008 GMT, mercury, C:\My_Documents\Test\stanley-test2\AposTests, , /var/cvs/test2/AposTests, (null), , (null), )
11:27:08: S -> notify_do (U, A'pos_test.xls, gstarret, Wed Jun 11 16:27:09 2008 GMT, mercury, C:\My_Documents\Test\stanley-test2\AposTests, , /var/cvs/test2/AposTests, (null), , (null), )
11:27:09:   -> Transcode E 11:27:08: S -> editor_set(U,A'pos_test.xls,gstarret,Wed Jun 11 16:27:09 2008 GMT,mercury,C:\My_Documents\Test\stanley-test2\AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> editor_set(U,A'pos_test.xls,gstarret,Wed Jun 11 16:27:09 2008 GMT,mercury,C:\My_Documents\Test\stanley-test2\AposTests)
11:27:08: S -> editor_set(U,A'pos_test.xls,gstarret,Wed Jun 11 16:27:09 2008 GMT,mercury,C:\My_Documents\Test\stanley-test2\AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_find(file[@name=F'%s'])
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_find(file[@name=F'%s'])
11:27:08: S -> fileattr_find(file[@name=F'%s'])
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_read(/var/cvs/test2/AposTests)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_read(/var/cvs/test2/AposTests)
11:27:08: S -> fileattr_read(/var/cvs/test2/AposTests)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_find(editor[@name=U'%s'])
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_find(editor[@name=U'%s'])
11:27:08: S -> fileattr_find(editor[@name=U'%s'])
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::CFileAccess()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::CFileAccess()
11:27:08: S -> CFileAccess::CFileAccess()
11:27:09:   -> Transcode E 11:27:08: S -> history_write(u,C:\My_Documents\Test\stanley-test2\AposTests,(null),A'pos_test.xls,/var/cvs/test2/AposTests,(null),)
11:27:09:   -> Transcode returned E 11:27:08: S -> history_write(u,C:\My_Documents\Test\stanley-test2\AposTests,(null),A'pos_test.xls,/var/cvs/test2/AposTests,(null),)
11:27:08: S -> history_write(u,C:\My_Documents\Test\stanley-test2\AposTests,(null),A'pos_test.xls,/var/cvs/test2/AposTests,(null),)
11:27:09:   -> Transcode E 11:27:08: S -> run history trigger
11:27:09:   -> Transcode returned E 11:27:08: S -> run history trigger
11:27:08: S -> run history trigger
11:27:09:   -> Transcode E 11:27:08: S -> run_trigger()
11:27:09:   -> Transcode returned E 11:27:08: S -> run_trigger()
11:27:08: S -> run_trigger()
11:27:09:   -> Transcode E 11:27:08: S -> Checking admin file /var/cvs/test2/CVSROOT/admin for user gstarret
11:27:09:   -> Transcode returned E 11:27:08: S -> Checking admin file /var/cvs/test2/CVSROOT/admin for user gstarret
11:27:08: S -> Checking admin file /var/cvs/test2/CVSROOT/admin for user gstarret
11:27:09:   -> Transcode E 11:27:08: S -> Checking started in admin file /var/cvs/test2/CVSROOT/admin for user gstarret
11:27:09:   -> Transcode returned E 11:27:08: S -> Checking started in admin file /var/cvs/test2/CVSROOT/admin for user gstarret
11:27:08: S -> Checking started in admin file /var/cvs/test2/CVSROOT/admin for user gstarret
11:27:09:   -> Transcode E 11:27:08: S -> Checked admin file (null) for user gstarret they are an admin.
11:27:09:   -> Transcode returned E 11:27:08: S -> Checked admin file (null) for user gstarret they are an admin.
11:27:08: S -> Checked admin file (null) for user gstarret they are an admin.
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(info.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(info.la)
11:27:08: S -> LoadTrigger(info.la)
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(make.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(make.la)
11:27:08: S -> LoadTrigger(make.la)
11:27:09:   -> Transcode E 11:27:08: S -> Make trigger not enabled.
11:27:09:   -> Transcode returned E 11:27:08: S -> Make trigger not enabled.
11:27:08: S -> Make trigger not enabled.
11:27:09:   -> Transcode E 
11:27:09:   -> Transcode returned E 

11:27:09:   -> Transcode E 11:27:08: S -> Couldn't load trigger make.la
11:27:09:   -> Transcode returned E 11:27:08: S -> Couldn't load trigger make.la
11:27:08: S -> Couldn't load trigger make.la
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(bug.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(bug.la)
11:27:08: S -> LoadTrigger(bug.la)
11:27:09:   -> Transcode E 11:27:08: S -> Not loading disabled trigger bug.la.
11:27:09:   -> Transcode returned E 11:27:08: S -> Not loading disabled trigger bug.la.
11:27:08: S -> Not loading disabled trigger bug.la.
11:27:09:   -> Transcode E 11:27:08: S -> Couldn't load trigger bug.la
11:27:09:   -> Transcode returned E 11:27:08: S -> Couldn't load trigger bug.la
11:27:08: S -> Couldn't load trigger bug.la
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(bug_trigger.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(bug_trigger.la)
11:27:08: S -> LoadTrigger(bug_trigger.la)
11:27:09:   -> Transcode E 11:27:08: S -> LibraryAccess::Load failed for '/usr/lib/cvsnt/triggers/bug_trigger.la', error = Success
11:27:09:   -> Transcode returned E 11:27:08: S -> LibraryAccess::Load failed for '/usr/lib/cvsnt/triggers/bug_trigger.la', error = Success
11:27:08: S -> LibraryAccess::Load failed for '/usr/lib/cvsnt/triggers/bug_trigger.la', error = Success
11:27:09:   -> Transcode E 11:27:08: S -> Unable to load bug_trigger.la: error 0
11:27:09:   -> Transcode returned E 11:27:08: S -> Unable to load bug_trigger.la: error 0
11:27:08: S -> Unable to load bug_trigger.la: error 0
11:27:09:   -> Transcode E 11:27:08: S -> Couldn't load trigger bug_trigger.la
11:27:09:   -> Transcode returned E 11:27:08: S -> Couldn't load trigger bug_trigger.la
11:27:08: S -> Couldn't load trigger bug_trigger.la
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(email.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(email.la)
11:27:08: S -> LoadTrigger(email.la)
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(checkout.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(checkout.la)
11:27:08: S -> LoadTrigger(checkout.la)
11:27:09:   -> Transcode E 11:27:08: S -> Checkout trigger is enabled.
11:27:09:   -> Transcode returned E 11:27:08: S -> Checkout trigger is enabled.
11:27:08: S -> Checkout trigger is enabled.
11:27:09:   -> Transcode E 11:27:08: S -> LoadTrigger(audit.la)
11:27:09:   -> Transcode returned E 11:27:08: S -> LoadTrigger(audit.la)
11:27:08: S -> LoadTrigger(audit.la)
11:27:09:   -> Transcode E 11:27:08: S -> Connecting to MySql
11:27:09:   -> Transcode returned E 11:27:08: S -> Connecting to MySql
11:27:08: S -> Connecting to MySql
11:27:09:   -> Transcode E 11:27:08: S -> Select Version From SchemaVersion
11:27:09:   -> Transcode returned E 11:27:08: S -> Select Version From SchemaVersion
11:27:08: S -> Select Version From SchemaVersion
11:27:09:   -> Transcode E 11:27:08: S -> Audit SchemaVersion=3
11:27:09:   -> Transcode returned E 11:27:08: S -> Audit SchemaVersion=3
11:27:08: S -> Audit SchemaVersion=3
11:27:09:   -> Transcode E 11:27:08: S -> Insert Into SessionLog (Command, StartTime, Hostname, Username, SessionId, VirtRepos, PhysRepos, Client) Values (?,'2008-06-11 16:27:07',?,?,'7330484ffcdb2cd2',?,?,'CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)')
11:27:09:   -> Transcode returned E 11:27:08: S -> Insert Into SessionLog (Command, StartTime, Hostname, Username, SessionId, VirtRepos, PhysRepos, Client) Values (?,'2008-06-11 16:27:07',?,?,'7330484ffcdb2cd2',?,?,'CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)')
11:27:08: S -> Insert Into SessionLog (Command, StartTime, Hostname, Username, SessionId, VirtRepos, PhysRepos, Client) Values (?,'2008-06-11 16:27:07',?,?,'7330484ffcdb2cd2',?,?,'CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)')
11:27:09:   -> Transcode E 11:27:08: S -> Insert Into SessionLog (Command, StartTime, Hostname, Username, SessionId, VirtRepos, PhysRepos, Client) Values ('unedit','2008-06-11 16:27:07','mercury.sol.starretthome.net','gstarret','7330484ffcdb2cd2','/test2','/var/cvs/test2','CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)')
11:27:09:   -> Transcode returned E 11:27:08: S -> Insert Into SessionLog (Command, StartTime, Hostname, Username, SessionId, VirtRepos, PhysRepos, Client) Values ('unedit','2008-06-11 16:27:07','mercury.sol.starretthome.net','gstarret','7330484ffcdb2cd2','/test2','/var/cvs/test2','CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)')
11:27:08: S -> Insert Into SessionLog (Command, StartTime, Hostname, Username, SessionId, VirtRepos, PhysRepos, Client) Values ('unedit','2008-06-11 16:27:07','mercury.sol.starretthome.net','gstarret','7330484ffcdb2cd2','/test2','/var/cvs/test2','CVSNT 2.5.03 (Scorpio) Build 3145 (RC 4)')
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'audit.la'
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'audit.la'
11:27:08: S -> Call pre-loaded 'audit.la'
11:27:09:   -> Transcode E 11:27:08: S -> Audit HistoryLog message="".
11:27:09:   -> Transcode returned E 11:27:08: S -> Audit HistoryLog message="".
11:27:08: S -> Audit HistoryLog message="".
11:27:09:   -> Transcode E 11:27:08: S -> Insert Into HistoryLog (SessionId, Type, Workdir, Revs, Name, BugId, Message) Values (8427, 'u',?,'',?,'', ? )
11:27:09:   -> Transcode returned E 11:27:08: S -> Insert Into HistoryLog (SessionId, Type, Workdir, Revs, Name, BugId, Message) Values (8427, 'u',?,'',?,'', ? )
11:27:08: S -> Insert Into HistoryLog (SessionId, Type, Workdir, Revs, Name, BugId, Message) Values (8427, 'u',?,'',?,'', ? )
11:27:09:   -> Transcode E 11:27:08: S -> Insert Into HistoryLog (SessionId, Type, Workdir, Revs, Name, BugId, Message) Values (8427, 'u','mercury.sol.starretthome.net/C:\\My_Documents\\Test\\stanley-test2\\AposTests','','A\'pos_test.xls','', '' )
11:27:09:   -> Transcode returned E 11:27:08: S -> Insert Into HistoryLog (SessionId, Type, Workdir, Revs, Name, BugId, Message) Values (8427, 'u','mercury.sol.starretthome.net/C:\\My_Documents\\Test\\stanley-test2\\AposTests','','A\'pos_test.xls','', '' )
11:27:08: S -> Insert Into HistoryLog (SessionId, Type, Workdir, Revs, Name, BugId, Message) Values (8427, 'u','mercury.sol.starretthome.net/C:\\My_Documents\\Test\\stanley-test2\\AposTests','','A\'pos_test.xls','', '' )
11:27:09:   -> Transcode E 11:27:08: S -> Audit HistoryLog returned from execute.
11:27:09:   -> Transcode returned E 11:27:08: S -> Audit HistoryLog returned from execute.
11:27:08: S -> Audit HistoryLog returned from execute.
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'audit.la' returned 0, total ret 0
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'audit.la' returned 0, total ret 0
11:27:08: S -> Call pre-loaded 'audit.la' returned 0, total ret 0
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'checkout.la'
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'checkout.la'
11:27:08: S -> Call pre-loaded 'checkout.la'
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'checkout.la' returned 0, total ret 0
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'checkout.la' returned 0, total ret 0
11:27:08: S -> Call pre-loaded 'checkout.la' returned 0, total ret 0
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'email.la'
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'email.la'
11:27:08: S -> Call pre-loaded 'email.la'
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'email.la' returned 0, total ret 0
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'email.la' returned 0, total ret 0
11:27:08: S -> Call pre-loaded 'email.la' returned 0, total ret 0
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'info.la'
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'info.la'
11:27:08: S -> Call pre-loaded 'info.la'
11:27:09:   -> Transcode E 11:27:08: S -> default_trigger: parse_info(CVSROOT/historyinfo,%t|%d|%u|%w|%s|%v,,<null>)
11:27:09:   -> Transcode returned E 11:27:08: S -> default_trigger: parse_info(CVSROOT/historyinfo,%t|%d|%u|%w|%s|%v,,<null>)
11:27:08: S -> default_trigger: parse_info(CVSROOT/historyinfo,%t|%d|%u|%w|%s|%v,,<null>)
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::CFileAccess()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::CFileAccess()
11:27:08: S -> CFileAccess::CFileAccess()
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::open('/var/cvs/test2/CVSROOT/historyinfo','rb')
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::open('/var/cvs/test2/CVSROOT/historyinfo','rb')
11:27:08: S -> CFileAccess::open('/var/cvs/test2/CVSROOT/historyinfo','rb')
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::open() setvbuf(_IONBF)
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::open() setvbuf(_IONBF)
11:27:08: S -> CFileAccess::open() setvbuf(_IONBF)
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::close()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::close()
11:27:08: S -> CFileAccess::close()
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::~CFileAccess()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::~CFileAccess()
11:27:08: S -> CFileAccess::~CFileAccess()
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::close()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::close()
11:27:08: S -> CFileAccess::close()
11:27:09:   -> Transcode E 11:27:08: S -> Call pre-loaded 'info.la' returned 0, total ret 0
11:27:09:   -> Transcode returned E 11:27:08: S -> Call pre-loaded 'info.la' returned 0, total ret 0
11:27:08: S -> Call pre-loaded 'info.la' returned 0, total ret 0
11:27:09:   -> Transcode E 11:27:08: S -> run_trigger returning 0
11:27:09:   -> Transcode returned E 11:27:08: S -> run_trigger returning 0
11:27:08: S -> run_trigger returning 0
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::~CFileAccess()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::~CFileAccess()
11:27:08: S -> CFileAccess::~CFileAccess()
11:27:09:   -> Transcode E 11:27:08: S -> CFileAccess::close()
11:27:09:   -> Transcode returned E 11:27:08: S -> CFileAccess::close()
11:27:08: S -> CFileAccess::close()
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_find(file[@name=F'%s']/watcher)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_find(file[@name=F'%s']/watcher)
11:27:08: S -> fileattr_find(file[@name=F'%s']/watcher)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_getvalue(@name)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_getvalue(@name)
11:27:08: S -> fileattr_getvalue(@name)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_find(unedit)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_find(unedit)
11:27:08: S -> fileattr_find(unedit)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_find(temp_unedit)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_find(temp_unedit)
11:27:08: S -> fileattr_find(temp_unedit)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_next()
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_next()
11:27:08: S -> fileattr_next()
11:27:09:   -> Transcode E 11:27:08: S -> watch_modify_watchers(A'pos_test.xls,gstarret)
11:27:09:   -> Transcode returned E 11:27:08: S -> watch_modify_watchers(A'pos_test.xls,gstarret)
11:27:08: S -> watch_modify_watchers(A'pos_test.xls,gstarret)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_find(file[@name=F'%s']/watcher[@name=U'%s'])
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_find(file[@name=F'%s']/watcher[@name=U'%s'])
11:27:08: S -> fileattr_find(file[@name=F'%s']/watcher[@name=U'%s'])
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_delete(temp_edit)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_delete(temp_edit)
11:27:08: S -> fileattr_delete(temp_edit)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_delete(temp_commit)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_delete(temp_commit)
11:27:08: S -> fileattr_delete(temp_commit)
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_delete(temp_unedit)
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_delete(temp_unedit)
11:27:08: S -> fileattr_delete(temp_unedit)
11:27:09:   -> Transcode Notified ./
11:27:09:   -> Transcode returned Notified ./
11:27:09:   -> handle_notified(notified_a_file)
11:27:09:   -> Transcode /var/cvs/test2/AposTests/A'pos_test.xls
11:27:09:   -> Transcode returned /var/cvs/test2/AposTests/A'pos_test.xls
11:27:09:   -> call_in_directory /var/cvs/test2/AposTests/A'pos_test.xls,./
11:27:09:   -> call_in_directory -- handle "." /var/cvs/test2/AposTests/A'pos_test.xls,./
11:27:09:   -> call_in_directory dir_name(.) created from pathname(./) no prune
11:27:09:   -> call_in_directory compare last_dir_name((null)) dir_name(.)
11:27:09:   -> call_in_directory dir_name!=last_dir_name, or no last_dir_name
11:27:09:   -> call_in_directory chdir(toplevel_wd=C:\My_Documents\Test\stanley-test2\AposTests)
11:27:09:   -> call_in_directory chdir(dir_name=.)
11:27:09:   -> Entries_Open()
11:27:09:   -> Entries_Open ParseTag()
11:27:09:   -> ParseTag()
11:27:09:   -> ParseTag_Dir((null),<something>,<something>,3,)
11:27:09:   -> Entries_Open aflag==0 && dirtag==0 && dirdate==0
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries","r")
11:27:09:   -> Entries_Open CVS_FOPEN CVSADM_ENT returned a file handle, now call the (slow) fgetentent() / AddEntryNode
11:27:09:   -> Entnode_Create()
11:27:09:   -> Entnode_Create() finished
11:27:09:   -> AddEntryNode()
11:27:09:   -> AddEntryNode() get a node and fill in the regular stuff 
11:27:09:   -> AddEntryNode() this one gets a key of the name for hashing 
11:27:09:   -> AddEntryNode() put the node into the list 
11:27:09:   -> AddEntryNode() finished
11:27:09:   -> Entries_Open fclose() CVSADM_ENT 
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries.Extra","r")
11:27:09:   -> Entries_Open CVS_FOPEN CVSADM_ENTEXT returned a file handle
11:27:09:   -> Entries_Open fclose() CVSADM_ENTEXT 
11:27:09:   -> Entries_Open CVS_FOPEN("CVS/Entries.Log","r")
11:27:09:   -> Entries_Open clean up and return 
11:27:09:   -> Entries_Open done
11:27:09:   -> call_in_directory - call the function with (./A'pos_test.xls,A'pos_test.xls)
11:27:09:   -> handle_notified(notified_a_file) done
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_write()
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_write()
11:27:08: S -> fileattr_write()
11:27:09:   -> Transcode E 11:27:08: S -> fileattr_free()
11:27:09:   -> Transcode returned E 11:27:08: S -> fileattr_free()
11:27:08: S -> fileattr_free()
11:27:09:   -> Transcode ok
11:27:09:   -> Transcode returned ok
11:27:09:   -> Entries_Close - isfile ("CVS/Entries.Log")
11:27:09:   -> Entries_Close - dellist (list)
11:27:09:   -> Unloading - about to clear list
11:27:09:   -> Unloading - cleared list
11:27:09:   -> free_directory()
11:27:09:   -> free_modules2() directory_stack_size and rubbish set to zero