最近安装了win10系统，安装PW后，开始感觉一切正常，后来import一个1.5G的文件到PW上，出现了问题，PW上只创建了一个文件名一样，文件大小为0KB的文件。
后来又连续尝试import其它大小的文件，发现当文件为600多M以下的文件都能正常上传，800M以上的文件都不能import。
操作系统：win10 x64 企业版
ProjectWise：SS4 或 CONNECT都测试了，一样的问题。
另外，我发现：如果点击上传失败创建的0KB的文件，选择属性->advanced->import,选择要上传的大文件，点save，文件能正常上传。
附件是debug级别的PW客户端日志
2016-04-20 16:39:44,510 INFO [0x000024cc] pwise.ft.connection - creating connection cache for DMS session 0x6c03b26b 2016-04-20 16:39:44,510 INFO [0x000024cc] pwise.ft.connection - connection cache does not exist for DMS session 0x6c03b26b, enabling now 2016-04-20 16:39:44,510 INFO [0x000024cc] pwise.ft.connection - connection cache refcount will be increased from 0 -> 1 for DMS session 0x6c03b26b 2016-04-20 16:39:44,510 INFO [0x000024cc] pwise.ft.connection - connection cache created for DMS session 0x6c03b26b, refcount = 1 2016-04-20 16:39:46,176 INFO [0x000024cc] pwise.dms - Sending request 3300. Request size: 64 2016-04-20 16:39:46,176 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,176 INFO [0x000024cc] pwise.netapi - sending request (5,3300), server session = 0xc44f2508 2016-04-20 16:39:46,176 DEBUG [0x000024cc] pwise.netapi - request compressed, input 104, output 64, ratio 38.46 2016-04-20 16:39:46,176 DEBUG [0x000024cc] pwise.netapi - sending 136 bytes on socket 3304 2016-04-20 16:39:46,176 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 136 flags: 0 2016-04-20 16:39:46,177 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 136 2016-04-20 16:39:46,177 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,177 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 189, timeout = 0 ms 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 189 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 173, output 318, ratio 45.60 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.netapi - 318 data bytes received 2016-04-20 16:39:46,180 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,180 INFO [0x000024cc] pwise.dms - Request 3300 return code 0. Response size: 278 2016-04-20 16:39:46,180 INFO [0x000024cc] pwise.dms - Sending request 1505. Request size: 132 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,180 INFO [0x000024cc] pwise.netapi - sending request (5,1505), server session = 0xc44f2508 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.netapi - request compressed, input 172, output 78, ratio 54.65 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.netapi - sending 150 bytes on socket 3304 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 150 flags: 0 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 150 2016-04-20 16:39:46,180 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,180 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 62, timeout = 0 ms 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 62 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 46, output 94, ratio 51.06 2016-04-20 16:39:46,185 DEBUG [0x000024cc] pwise.netapi - 94 data bytes received 2016-04-20 16:39:46,185 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,185 INFO [0x000024cc] pwise.dms - Request 1505 return code 0. Response size: 54 2016-04-20 16:39:46,186 INFO [0x000024cc] pwise.dms - Sending request 1307. Request size: 620 2016-04-20 16:39:46,186 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,186 INFO [0x000024cc] pwise.netapi - sending request (5,1307), server session = 0xc44f2508 2016-04-20 16:39:46,186 DEBUG [0x000024cc] pwise.netapi - request compressed, input 660, output 128, ratio 80.61 2016-04-20 16:39:46,186 DEBUG [0x000024cc] pwise.netapi - sending 200 bytes on socket 3304 2016-04-20 16:39:46,186 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 200 flags: 0 2016-04-20 16:39:46,186 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 200 2016-04-20 16:39:46,186 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,186 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 266, timeout = 0 ms 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 266 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 250, output 782, ratio 68.03 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.netapi - 782 data bytes received 2016-04-20 16:39:46,212 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,212 INFO [0x000024cc] pwise.dms - Request 1307 return code 0. Response size: 742 2016-04-20 16:39:46,212 INFO [0x000024cc] pwise.dms - Sending request 1316. Request size: 80 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,212 INFO [0x000024cc] pwise.netapi - sending request (5,1316), server session = 0xc44f2508 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.netapi - request compressed, input 120, output 83, ratio 30.83 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.netapi - sending 155 bytes on socket 3304 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 155 flags: 0 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 155 2016-04-20 16:39:46,212 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,212 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 293, timeout = 0 ms 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 293 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 277, output 364, ratio 23.90 2016-04-20 16:39:46,238 DEBUG [0x000024cc] pwise.netapi - 364 data bytes received 2016-04-20 16:39:46,238 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,238 INFO [0x000024cc] pwise.dms - Request 1316 return code 0. Response size: 324 2016-04-20 16:39:46,239 INFO [0x000024cc] pwise.ft.connection - creating connection cache for DMS session 0x6c03b26b 2016-04-20 16:39:46,239 INFO [0x000024cc] pwise.ft.connection - connection cache refcount will be increased from 1 -> 2 for DMS session 0x6c03b26b 2016-04-20 16:39:46,239 INFO [0x000024cc] pwise.ft.connection - connection cache created for DMS session 0x6c03b26b, refcount = 2 2016-04-20 16:39:46,239 INFO [0x000024cc] pwise.ft - dmsFTSendFileToServer(0x1, '10.219.10.63','C:/Users/suwz/AppData/Local/Temp/PWT3AC8.tmp/ms10000030zh.exe','E:/storage/MSDITB/dms75802/ms10000030zh.exe') 2016-04-20 16:39:46,239 INFO [0x00000ca4] pwise.ft.connection - getting connection for DMS session 0x6c03b26b, hostname = '10.219.10.63' 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.ft.connection - FtConnectionCache::GetConnection:249 - getting connection for 10.219.10.63 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.session - 28c0 ca4 creating new session 'ftca4_10.219.10.63' 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.session - created new transient session 'ftca4_10.219.10.63', 0x246354a4 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.ft.connection - FtConnectionCache::GetConnection:305 - cached connection created for host '10.219.10.63': session = 0x246354a4 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.netapi - Connecting to server '10.219.10.63' 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.netapi.routecache - searching route cache for a gateway for endpoint '10.219.10.63' 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.netapi.routecache - no gateway mapping found for endpoint '10.219.10.63' 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.dns.cache - resolving host '10.219.10.63' from cache 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.dns.cache - walking cache for hostname '10.219.10.63', address family 2 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.dns.cache - searching for address '10.219.10.63' in cache 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.dns.cache - found entry 'addr:10.219.10.63 port:5800 pub:0.0.0.0 src:ISNR hstnms:[pw01.msdi.cn, pw01, ]' 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.dns.cache - searching for address '10.219.10.63' in cache 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.dns.cache - found entry 'addr:10.219.10.63 port:5800 pub:0.0.0.0 src:ISNR hstnms:[pw01.msdi.cn, pw01, ]' 2016-04-20 16:39:46,240 DEBUG [0x00000ca4] pwise.dns.cache - hostname '10.219.10.63' resolves to '10.219.10.63:5800' 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.netapi - connecting to endpoint '10.219.10.63' via 10.219.10.63:5800, flags: 0x1 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.socket - socket_createSocket: created socket 3432 2016-04-20 16:39:46,240 INFO [0x00000ca4] pwise.socket - connecting socket:3432 to addr:'10.219.10.63' port:5800 flags:0 2016-04-20 16:39:46,242 INFO [0x00000ca4] pwise.socket - negotiating secure sockets connection 2016-04-20 16:39:46,242 INFO [0x00000ca4] pwise.dns.cache - getting host name for address '10.219.10.63' 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.dns.cache - searching for address '10.219.10.63' in cache 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.dns.cache - found entry 'addr:10.219.10.63 port:5800 pub:0.0.0.0 src:ISNR hstnms:[pw01.msdi.cn, pw01, ]' 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket - socket_send: starting send() bytes: 124 flags: 0 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket - socket_send: send() done, result = 124 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - socket 3432 [10.219.70.60] : sent message @ call 1 (offset: 0), 124 bytes: 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0000 16 03 01 00 77 01 00 00:73 03 01 57 17 40 52 9f ....w...s..W.@R. 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0010 e0 85 87 d3 82 50 98 33:9a f6 0a 94 82 25 17 3a .....P.3.......: 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0020 2c 35 98 90 72 c0 d4 54:3b 75 f2 00 00 18 c0 0a ,5..r..T;u...... 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0030 c0 09 c0 14 c0 13 00 35:00 2f 00 0a 00 38 00 32 .......5./...8.2 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0040 00 13 00 05 00 04 01 00:00 32 00 00 00 11 00 0f .........2...... 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0050 00 00 0c 70 77 30 31 2e:6d 73 64 69 2e 63 6e 00 ...pw01.msdi.cn. 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0060 0a 00 06 00 04 00 17 00:18 00 0b 00 02 01 00 00 ................ 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - 0070 23 00 00 00 17 00 00 ff:01 00 01 00 #........... 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket.ssl - ======================================================================= 2016-04-20 16:39:46,242 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 3, timeout = 30000 ms 2016-04-20 16:39:46,244 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 3 2016-04-20 16:39:46,244 DEBUG [0x00000ca4] pwise.socket.ssl - socket 3432 [10.219.70.60] : received message @ call 2 (offset: 0), 3 bytes: 2016-04-20 16:39:46,244 DEBUG [0x00000ca4] pwise.socket.ssl - 0000 41 43 4b ACK 2016-04-20 16:39:46,244 DEBUG [0x00000ca4] pwise.socket.ssl - ======================================================================= 2016-04-20 16:39:46,244 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 00000000, bufsize = 5120, timeout = 30000 ms 2016-04-20 16:39:46,299 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 5 2016-04-20 16:39:46,299 DEBUG [0x00000ca4] pwise.socket.ssl - socket 3432 [10.219.70.60] : received message @ call 3 (offset: 0), 5 bytes: 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.socket.ssl - 0000 4e 4f 53 45 43 NOSEC 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.socket.ssl - ======================================================================= 2016-04-20 16:39:46,300 INFO [0x00000ca4] pwise.socket.ssl - secure socket mode not negotiated on socket 3432 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.socket.ssl - context inserted for socket 3432, 2 entries in context map 2016-04-20 16:39:46,300 INFO [0x00000ca4] pwise.socket - secure sockets mode not negotiated 2016-04-20 16:39:46,300 INFO [0x00000ca4] pwise.socket - connected socket:3432 to addr:'10.219.10.63' port:5800 flags:0 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.netapi - session 0x246354a4 (610489508) gets server session 0 (0) 2016-04-20 16:39:46,300 INFO [0x00000ca4] pwise.netapi - sending request (4,1), server session = 0 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.netapi - sending 158 bytes on socket 3432 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.socket - socket_send: starting send() bytes: 158 flags: 0 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.socket - socket_send: send() done, result = 158 2016-04-20 16:39:46,300 INFO [0x00000ca4] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,300 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,301 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,301 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.netapi - 40 data bytes received 2016-04-20 16:39:46,302 INFO [0x00000ca4] pwise.netapi - response received, status = 0, server session = 0x2ebaf85c 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.netapi - session 0x246354a4 (610489508) sets server session 0x2ebaf85c (784005212) 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.netapi - session 0x246354a4 (610489508) gets server session 0x2ebaf85c (784005212) 2016-04-20 16:39:46,302 INFO [0x00000ca4] pwise.netapi - sending request (4,16), server session = 0x2ebaf85c 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.netapi - sending 56 bytes on socket 3432 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.socket - socket_send: starting send() bytes: 56 flags: 0 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.socket - socket_send: send() done, result = 56 2016-04-20 16:39:46,302 INFO [0x00000ca4] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,302 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 128, timeout = 0 ms 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 128 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.netapi - 168 data bytes received 2016-04-20 16:39:46,303 INFO [0x00000ca4] pwise.netapi - response received, status = 0, server session = 0x2ebaf85c 2016-04-20 16:39:46,303 INFO [0x00000ca4] pwise.netapi - Connected to server '10.219.10.63' 2016-04-20 16:39:46,303 INFO [0x00000ca4] pwise.ft.connection - got file transfer connection 0x246354a4 for DMS session 0x6c03b26b, host '10.219.10.63' 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.ft - starting file commmand 2 to host '10.219.10.63' 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.netapi - session 0x246354a4 (610489508) gets server session 0x2ebaf85c (784005212) 2016-04-20 16:39:46,303 INFO [0x00000ca4] pwise.netapi - sending request (8,2), server session = 0x2ebaf85c 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.netapi - sending 700 bytes on socket 3432 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_send: starting send() bytes: 700 flags: 0 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_send: send() done, result = 700 2016-04-20 16:39:46,303 INFO [0x00000ca4] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,303 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 110, timeout = 0 ms 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 110 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.netapi - 150 data bytes received 2016-04-20 16:39:46,305 INFO [0x00000ca4] pwise.netapi - response received, status = 0, server session = 0x2ebaf85c 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.ft - file commmand 2 to host '10.219.10.63' succeeds with status 0 2016-04-20 16:39:46,305 INFO [0x00000ca4] pwise.ft - waiting for server upload confirmation 2016-04-20 16:39:46,305 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 158, timeout = 0 ms 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.socket - socket_recvData: recv() done, result = 158 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.netapi - 198 data bytes received 2016-04-20 16:39:46,359 INFO [0x00000ca4] pwise.ft - server upload status = 0 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.ft - mime type returned from server is 'application/x-msdownload' 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.ft - ending file commmand 2016-04-20 16:39:46,359 INFO [0x00000ca4] pwise.ft.connection - releasing connection 0x246354a4 for DMS session 0x6c03b26b 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.ft.connection - FtConnectionCache::ReleaseConnection:332 - releasing connection, session = 0x246354a4 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.ft.connection - FtConnectionCache::ReleaseConnection:315 - releasing connection, session = 0x246354a4 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.ft.connection - FtConnectionCache::ReleaseConnection:322 - connection released, session = 0x246354a4 2016-04-20 16:39:46,359 DEBUG [0x00000ca4] pwise.ft.connection - FtConnectionCache::ReleaseConnection:368 - connection released, session = 0x246354a4 2016-04-20 16:39:46,359 INFO [0x00000ca4] pwise.ft - sent file 'C:/Users/suwz/AppData/Local/Temp/PWT3AC8.tmp/ms10000030zh.exe' to server through normal FT, total time: 120 2016-04-20 16:39:46,360 INFO [0x000024cc] pwise.ft.connection - freeing connection cache for DMS session 0x6c03b26b 2016-04-20 16:39:46,360 INFO [0x000024cc] pwise.ft.connection - connection cache refcount will be decreased from 2 -> 1 for DMS session 0x6c03b26b 2016-04-20 16:39:46,360 INFO [0x000024cc] pwise.dms - Sending request 1309. Request size: 226 2016-04-20 16:39:46,360 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,360 INFO [0x000024cc] pwise.netapi - sending request (5,1309), server session = 0xc44f2508 2016-04-20 16:39:46,360 DEBUG [0x000024cc] pwise.netapi - request compressed, input 266, output 180, ratio 32.33 2016-04-20 16:39:46,360 DEBUG [0x000024cc] pwise.netapi - sending 252 bytes on socket 3304 2016-04-20 16:39:46,360 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 252 flags: 0 2016-04-20 16:39:46,360 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 252 2016-04-20 16:39:46,360 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,360 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 59, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 59 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 43, output 94, ratio 54.26 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - 94 data bytes received 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.dms - Request 1309 return code 0. Response size: 54 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.dms - Sending request 3300. Request size: 64 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - sending request (5,3300), server session = 0xc44f2508 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - request compressed, input 104, output 64, ratio 38.46 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - sending 136 bytes on socket 3304 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 136 flags: 0 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 136 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 189, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 189 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 173, output 318, ratio 45.60 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - 318 data bytes received 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.dms - Request 3300 return code 0. Response size: 278 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.dms - Sending request 3300. Request size: 64 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - sending request (5,3300), server session = 0xc44f2508 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - request compressed, input 104, output 64, ratio 38.46 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - sending 136 bytes on socket 3304 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 136 flags: 0 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 136 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 189, timeout = 0 ms 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 189 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 173, output 318, ratio 45.60 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - 318 data bytes received 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.dms - Request 3300 return code 0. Response size: 278 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.dms - Sending request 5709. Request size: 104 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - sending request (5,5709), server session = 0xc44f2508 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - request compressed, input 144, output 77, ratio 46.53 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.netapi - sending 149 bytes on socket 3304 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 149 flags: 0 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 149 2016-04-20 16:39:46,363 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,363 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 62, timeout = 0 ms 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 62 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 46, output 94, ratio 51.06 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - 94 data bytes received 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.dms - Request 5709 return code 0. Response size: 54 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.rulesengine - Executing Link Data post hook... 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.database - Executing statement 'select id from wre_operation_type' 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.database - Sending request 11263. Request size: 80 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.netapi - sending request (6,11263), server session = 0xc44f2508 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - request compressed, input 120, output 90, ratio 25.00 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - sending 162 bytes on socket 3304 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 162 flags: 0 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 162 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 116, timeout = 0 ms 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 116 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 100, output 304, ratio 67.11 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - 304 data bytes received 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.database - Request 11263 return code 0. Response size: 80 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.database - Executing statement 'select o_projectno,o_itemno,a_attrno,????,????,??,????,?? from MSDIEnvironment where a_attrno=33045 order by o_projectno,o_itemno,a_attrno' 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.database - Sending request 11263. Request size: 354 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.netapi - sending request (6,11263), server session = 0xc44f2508 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - request compressed, input 394, output 207, ratio 47.46 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.netapi - sending 279 bytes on socket 3304 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 279 flags: 0 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 279 2016-04-20 16:39:46,402 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,402 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,418 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 255, timeout = 0 ms 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 255 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 239, output 1554, ratio 84.62 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.netapi - 1554 data bytes received 2016-04-20 16:39:46,419 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,419 INFO [0x000024cc] pwise.database - Request 11263 return code 0. Response size: 354 2016-04-20 16:39:46,419 INFO [0x000024cc] pwise.dms - Sending request 5702. Request size: 256 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,419 INFO [0x000024cc] pwise.netapi - sending request (5,5702), server session = 0xc44f2508 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.netapi - request compressed, input 296, output 81, ratio 72.64 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.netapi - sending 153 bytes on socket 3304 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 153 flags: 0 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 153 2016-04-20 16:39:46,419 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,419 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 100, timeout = 0 ms 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 100 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 84, output 286, ratio 70.63 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.netapi - 286 data bytes received 2016-04-20 16:39:46,435 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,435 INFO [0x000024cc] pwise.dms - Request 5702 return code 0. Response size: 246 2016-04-20 16:39:46,435 INFO [0x000024cc] pwise.dms - Sending request 1300. Request size: 64 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,435 INFO [0x000024cc] pwise.netapi - sending request (5,1300), server session = 0xc44f2508 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.netapi - request compressed, input 104, output 65, ratio 37.50 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.netapi - sending 137 bytes on socket 3304 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 137 flags: 0 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 137 2016-04-20 16:39:46,435 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,435 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 256, timeout = 0 ms 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 256 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 240, output 698, ratio 65.62 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - 698 data bytes received 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.dms - Request 1300 return code 0. Response size: 658 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.rulesengine - Skipped processing of attribute records. Rules engine is not enabled for workflow. 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.dms - Sending request 1300. Request size: 64 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.netapi - sending request (5,1300), server session = 0xc44f2508 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - request compressed, input 104, output 65, ratio 37.50 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - sending 137 bytes on socket 3304 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 137 flags: 0 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 137 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 256, timeout = 0 ms 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 256 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 240, output 698, ratio 65.62 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - 698 data bytes received 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.dms - Request 1300 return code 0. Response size: 658 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.dms - Sending request 1300. Request size: 64 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.netapi - sending request (5,1300), server session = 0xc44f2508 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - request compressed, input 104, output 65, ratio 37.50 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.netapi - sending 137 bytes on socket 3304 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 137 flags: 0 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 137 2016-04-20 16:39:46,442 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,442 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 256, timeout = 0 ms 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 256 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 240, output 698, ratio 65.62 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.netapi - 698 data bytes received 2016-04-20 16:39:46,459 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,459 INFO [0x000024cc] pwise.dms - Request 1300 return code 0. Response size: 658 2016-04-20 16:39:46,459 INFO [0x000024cc] pwise.dms - Sending request 5701. Request size: 256 2016-04-20 16:39:46,459 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,460 INFO [0x000024cc] pwise.netapi - sending request (5,5701), server session = 0xc44f2508 2016-04-20 16:39:46,460 DEBUG [0x000024cc] pwise.netapi - request compressed, input 296, output 72, ratio 75.68 2016-04-20 16:39:46,460 DEBUG [0x000024cc] pwise.netapi - sending 144 bytes on socket 3304 2016-04-20 16:39:46,460 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 144 flags: 0 2016-04-20 16:39:46,460 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 144 2016-04-20 16:39:46,460 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,460 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 100, timeout = 0 ms 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 100 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 84, output 286, ratio 70.63 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - 286 data bytes received 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.dms - Request 5701 return code 0. Response size: 246 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.database - Executing statement 'select a_attrno, ????, ????, ??, ????, ?? from MSDIEnvironment where a_attrno = 33045' 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.database - Sending request 11263. Request size: 196 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.netapi - sending request (6,11263), server session = 0xc44f2508 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - request compressed, input 236, output 158, ratio 33.05 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - sending 230 bytes on socket 3304 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 230 flags: 0 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 230 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 197, timeout = 0 ms 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 197 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 181, output 1164, ratio 84.45 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - 1164 data bytes received 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.database - Request 11263 return code 0. Response size: 196 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.dms - Sending request 5406. Request size: 490 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - session 0x6c03b26b (1812181611) gets server session 0xc44f2508 (3293521160) 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.netapi - sending request (5,5406), server session = 0xc44f2508 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - request compressed, input 530, output 261, ratio 50.75 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.netapi - sending 333 bytes on socket 3304 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 333 flags: 0 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 333 2016-04-20 16:39:46,462 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,462 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 0 ms 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 0 ms 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 63, timeout = 0 ms 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 63 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.netapi - request decompressed, input 47, output 106, ratio 55.66 2016-04-20 16:39:46,487 DEBUG [0x000024cc] pwise.netapi - 106 data bytes received 2016-04-20 16:39:46,487 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0xc44f2508 2016-04-20 16:39:46,487 INFO [0x000024cc] pwise.dms - Request 5406 return code 0. Response size: 66 2016-04-20 16:39:46,502 INFO [0x000024cc] pwise.ft.connection - freeing connection cache for DMS session 0x6c03b26b 2016-04-20 16:39:46,502 INFO [0x000024cc] pwise.ft.connection - connection cache refcount will be decreased from 1 -> 0 for DMS session 0x6c03b26b 2016-04-20 16:39:46,502 INFO [0x000024cc] pwise.ft.connection - connection cache disabled for DMS session 0x6c03b26b, refcount = 0 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.ft.connection - FtConnectionCache::CloseAllConnections:378 - releasing all connections 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.ft.connection - FtConnectionCache::CloseAllConnections:399 - releasing connection 0x246354a4 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.ft.connection - FtConnection::~FtConnection:59 - destroying FtConnection for session 0x246354a4 2016-04-20 16:39:46,502 INFO [0x000024cc] pwise.netapi - Disconnecting from server 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.netapi - session 0x246354a4 (610489508) gets server session 0x2ebaf85c (784005212) 2016-04-20 16:39:46,502 INFO [0x000024cc] pwise.netapi - sending request (4,2), server session = 0x2ebaf85c 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.netapi - sending 56 bytes on socket 3432 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.socket - socket_send: starting send() bytes: 56 flags: 0 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.socket - socket_send: send() done, result = 56 2016-04-20 16:39:46,502 INFO [0x000024cc] pwise.netapi - request sent, status = 0 2016-04-20 16:39:46,502 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 16, timeout = 2000 ms 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 16 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.socket - socket_recvData: starting recv(), flags = 0x000100, bufsize = 40, timeout = 2000 ms 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.socket - socket_recvData: recv() done, result = 40 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.netapi - 40 data bytes received 2016-04-20 16:39:46,521 INFO [0x000024cc] pwise.netapi - response received, status = 0, server session = 0x2ebaf85c 2016-04-20 16:39:46,521 INFO [0x000024cc] pwise.socket - socket_shutdown: shutting down socket 3432, flags = 0x1 2016-04-20 16:39:46,521 INFO [0x000024cc] pwise.socket - socket_close: closing socket 3432, flags = 0 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.socket.ssl - context deleted for socket 3432, 1 entries in context map 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.netapi - session 0x246354a4 (610489508) sets server session 0 (0) 2016-04-20 16:39:46,521 INFO [0x000024cc] pwise.netapi - Disconnected from server 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.session - 28c0 24cc freeing session 0x246354a4 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.session - Freeing session: 0x246354a4 2016-04-20 16:39:46,521 DEBUG [0x000024cc] pwise.ft - 28c0 24cc freeFtConnectionContext for session 0x246354a4, type 11
Answer Verified By: suwz