Skip to Content.
Sympa Menu

freetds - [freetds] intermittent TLS packet with unexpected length error

freetds AT lists.ibiblio.org

Subject: FreeTDS Development Group

List archive

[freetds] intermittent TLS packet with unexpected length error

Chronological Thread  
    < Chronological >       < Thread >
  • From: Scott Morken <scott.morken AT phoenixcollege.edu>
  • To: freetds AT lists.ibiblio.org
  • Subject: [freetds] intermittent TLS packet with unexpected length error
  • Date: Fri, 16 Sep 2016 07:54:15 -0700
I've recently (around the first part of August) starting getting random "
SQLSTATE[01002] Adaptive Server connection failed" errors from FreeTDS. It
seems to be related to the TLS handshake. It doesn't happen every time...
it took me about 45 minutes of clicking around to get the attached failure
log but the next time only took a couple of minutes to get another. It
happens from multiple Ubuntu 14.04.5 LTS servers connecting to the same MS
SQL Server. These are mostly PHP web apps but Python also has the same
issue - naturally since it's the same backend library :)

MS SQL Server 2012 (11.0.6020) on Windows Server 2008 R2 SP1

The error on SQL Server isn't super helpful but it gave me a starting point
to look into the TLS connection:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event";>
<System>
<Provider Name="Schannel" Guid="{1F678132-5938-4686-9FDC-C8FF68F15C85}" />
<EventID>36888</EventID>
<Version>0</Version>
<Level>2</Level>
<Task>0</Task>
<Opcode>0</Opcode>
<Keywords>0x8000000000000000</Keywords>
<TimeCreated SystemTime="2016-09-16T13:20:01.639041100Z" />
<EventRecordID>81739</EventRecordID>
<Correlation />
<Execution ProcessID="524" ThreadID="2464" />
<Channel>System</Channel>
<Computer>xxxx</Computer>
<Security UserID="xxxx" />
</System>
<EventData>
<Data Name="AlertDesc">20</Data>
<Data Name="ErrorState">960</Data>
</EventData>
</Event>

I'm connecting from multiple Ubuntu 14.04.5 LTS servers.
gnutls26 is installed (2.12.23-12ubuntu2.5)
freetds 0.91-5

I'm at a loss as to what would cause these errors. I've spent a lot of
time searching for an answer but haven't found anything that really fits.
Our server admins tried uninstalling the Microsoft TLS 1.2 patch that
apparently broke a few things but that didn't help:
http://www.infoworld.com/article/2848574/operating-systems/microsoft-botches-kb-2992611-schannel-patch-tls-alert-code-40-slow-sql-server-block-iis-sites.html

I've tried encryption = off but it seems it still tries to negotiate it and
fails.

I've attached a log of the debug output from freeTDS for a failure and a
success. Any thoughts or obvious things I've missed would be greatly
appreciated. I've run out of ideas.

Scott
11:47:52.435128 12996 (log.c:196):Starting log file for FreeTDS 0.91
	on 2016-09-15 11:47:52 with debug flags 0xffff.
11:47:52.435174 12996 (iconv.c:330):tds_iconv_open(0x7f2eb4ec95c0, ISO-8859-1)
11:47:52.435365 12996 (iconv.c:187):local name for ISO-8859-1 is ISO-8859-1
11:47:52.435382 12996 (iconv.c:187):local name for UTF-8 is UTF-8
11:47:52.435390 12996 (iconv.c:187):local name for UCS-2LE is UCS-2LE
11:47:52.435397 12996 (iconv.c:187):local name for UCS-2BE is UCS-2BE
11:47:52.435404 12996 (iconv.c:349):setting up conversions for client charset "ISO-8859-1"
11:47:52.435411 12996 (iconv.c:351):preparing iconv for "ISO-8859-1" <-> "UCS-2LE" conversion
11:47:52.435425 12996 (iconv.c:391):preparing iconv for "ISO-8859-1" <-> "UCS-2LE" conversion
11:47:52.435441 12996 (iconv.c:394):tds_iconv_open: done
11:47:52.435454 12996 (net.c:205):Connecting to xxx.xxx.xxx.xxx port 1433 (TDS version 7.1)
11:47:52.435550 12996 (net.c:270):tds_open_socket: connect(2) returned "Operation now in progress"
11:47:52.435746 12996 (net.c:310):tds_open_socket() succeeded
11:47:52.435769 12996 (util.c:156):Changed query state from DEAD to IDLE
11:47:52.435784 12996 (net.c:741):Sending packet
0000 12 01 00 34 00 00 00 00-00 00 15 00 06 01 00 1b |...4.... ........|
0010 00 01 02 00 1c 00 0c 03-00 28 00 04 ff 08 00 01 |........ .(......|
0020 55 00 00 01 4d 53 53 51-4c 53 65 72 76 65 72 00 |U...MSSQ LServer.|
0030 c4 32 00 00            -                        |.2..|

11:47:52.436180 12996 (net.c:555):Received header
0000 04 01 00 25 00 00 01 00-                        |...%....|

11:47:52.436206 12996 (net.c:609):Received packet
0000 04 01 00 25 00 00 01 00-00 00 15 00 06 01 00 1b |...%.... ........|
0010 00 01 02 00 1c 00 01 03-00 1d 00 00 ff 0b 00 17 |........ ........|
0020 84 00 00 01 00         -                        |.....|

11:47:52.436229 12996 (login.c:1057):detected flag 1
11:47:52.436302 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Allocating epoch #0
11:47:52.436318 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_constate.c:695
11:47:52.436327 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Allocating epoch #1
11:47:52.436445 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: RSA_AES_256_CBC_SHA1
11:47:52.436457 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: RSA_AES_128_CBC_SHA1
11:47:52.436466 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: RSA_3DES_EDE_CBC_SHA1
11:47:52.436475 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: RSA_ARCFOUR_SHA1
11:47:52.436484 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: RSA_ARCFOUR_MD5
11:47:52.436493 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_DSS_AES_256_CBC_SHA1
11:47:52.436501 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_DSS_AES_128_CBC_SHA1
11:47:52.436510 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_DSS_3DES_EDE_CBC_SHA1
11:47:52.436518 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_DSS_ARCFOUR_SHA1
11:47:52.436527 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_RSA_AES_256_CBC_SHA1
11:47:52.436535 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_RSA_AES_128_CBC_SHA1
11:47:52.436543 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Keeping ciphersuite: DHE_RSA_3DES_EDE_CBC_SHA1
11:47:52.436559 12996 (net.c:1257):GNUTLS: level 2:
  EXT[0x7f2eb4ec6550]: Sending extension SAFE RENEGOTIATION (1 bytes)
11:47:52.436609 12996 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (4.2) DSA-SHA256
11:47:52.436636 12996 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (4.1) RSA-SHA256
11:47:52.436646 12996 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (2.1) RSA-SHA1
11:47:52.436655 12996 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (2.2) DSA-SHA1
11:47:52.436674 12996 (net.c:1257):GNUTLS: level 2:
  EXT[0x7f2eb4ec6550]: Sending extension SIGNATURE ALGORITHMS (10 bytes)
11:47:52.436685 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: CLIENT HELLO was sent [88 bytes]
11:47:52.436694 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 88 bytes of Data
11:47:52.436703 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE: enqueued 88. Total 88 bytes.
11:47:52.436712 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE FLUSH: 88 bytes in buffer.
11:47:52.436721 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sending Packet[0] Handshake(22) with length: 88
11:47:52.436732 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 93 bytes for 0x7f2eb4ec95c0. Total 93 bytes.
11:47:52.436742 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sent Packet[1] Handshake(22) with length: 93
11:47:52.436750 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE: wrote 88 bytes, 0 bytes left.
11:47:52.436759 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 93 bytes in buffer.
11:47:52.436766 12996 (net.c:1240):in tds_push_func
11:47:52.436774 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 93 bytes, 0 bytes left.
11:47:52.436783 12996 (net.c:1199):in tds_pull_func
11:47:52.436790 12996 (net.c:741):Sending packet
0000 12 01 00 65 00 00 00 00-16 03 00 00 58 01 00 00 |...e.... ....X...|
0010 54 03 03 57 da ec d8 df-c9 86 49 87 d7 25 b0 9e |T..W.... ..I..%..|
0020 38 d3 86 67 17 93 2e fc-cc d0 f4 7d 05 c7 e7 ce |8..g.... ...}....|
0030 ca e8 51 00 00 18 00 35-00 2f 00 0a 00 05 00 04 |..Q....5 ./......|
0040 00 38 00 32 00 13 00 66-00 39 00 33 00 16 01 00 |.8.2...f .9.3....|
0050 00 13 ff 01 00 01 00 00-0d 00 0a 00 08 04 02 04 |........ ........|
0060 01 02 01 02 02         -                        |.....|

11:47:52.436859 12996 (net.c:1212):have 0
11:47:52.436898 12996 (net.c:1216):before read
11:47:52.443522 12996 (net.c:555):Received header
0000 12 01 05 fd 00 00 00 00-                        |........|

11:47:52.443556 12996 (net.c:609):Received packet
0000 12 01 05 fd 00 00 00 00-16 03 03 05 f0 02 00 00 |........ ........|
0010 4d 03 03 57 da ec d8 46-97 4a 86 0f 44 96 c6 a1 |M..W...F .J..D...|
0020 a3 60 20 69 5a e1 06 89-11 e3 b2 e9 eb 85 05 1a |.` iZ... ........|
0030 39 a4 3b 20 e5 3c 00 00-41 dc b3 da 9d d6 03 9d |9.; .<.. A.......|
0040 e8 9e d8 8a df 62 03 6e-f4 a0 ab c2 fc 28 d4 fc |.....b.n .....(..|
0050 ba 45 3d ea 00 39 00 00-05 ff 01 00 01 00 0b 00 |.E=..9.. ........|
0060 03 09 00 03 06 00 03 03-30 82 02 ff 30 82 01 e7 |........ 0...0...|
0070 a0 03 02 01 02 02 10 17-62 73 2d 10 da fc aa 43 |........ bs-....C|
0080 7c df 81 81 87 4a ca 30-0d 06 09 2a 86 48 86 f7 ||....J.0 ...*.H..|
0090 0d 01 01 0b 05 00 30 1e-31 1c 30 1a 06 03 55 04 |......0. 1.0...U.|
00a0 03 13 13 ff ff ff 2e 70-63 2e 6d 61 72 69 63 6f |...xxx.p c.marico|
00b0 70 61 2e 65 64 75 30 20-17 0d 31 36 30 34 30 38 |pa.edu0  ..160408|
00c0 31 32 34 39 35 39 5a 18-0f 32 31 31 36 30 33 31 |124959Z. .2116031|
00d0 36 31 32 34 39 35 39 5a-30 1e 31 1c 30 1a 06 03 |6124959Z 0.1.0...|
00e0 55 04 03 13 13 ff ff ff-2e 70 63 2e 6d 61 72 69 |U....xxx .pc.mari|
00f0 63 6f 70 61 2e 65 64 75-30 82 01 22 30 0d 06 09 |copa.edu 0.."0...|
0100 2a 86 48 86 f7 0d 01 01-01 05 00 03 82 01 0f 00 |*.H..... ........|
0110 30 82 01 0a 02 82 01 01-00 a9 e0 dc 0d b2 f9 0f |0....... ........|
0120 04 3e 83 68 98 24 a6 de-52 78 46 a2 70 6a 6a db |.>.h.$.. RxF.pjj.|
0130 5f 15 2c 41 e4 4c 65 c7-cf 5e b4 8c 9b be c9 5b |_.,A.Le. .^.....[|
0140 52 90 f2 e0 9f 07 60 a8-6a 6e 95 90 43 5e ae 3e |R.....`. jn..C^.>|
0150 1b 01 04 95 3c fa fa a3-f6 6b 63 59 f1 8e 08 28 |....<... .kcY...(|
0160 ea c1 46 f7 51 8e dd 33-d9 df 79 78 30 e6 0e b2 |..F.Q..3 ..yx0...|
0170 67 ff a1 66 15 1a 9d e9-b3 83 eb b8 7c f8 f7 56 |g..f.... ....|..V|
0180 b3 03 4b f5 b7 87 dc 91-77 3b cb aa 4d b8 5f 1e |..K..... w;..M._.|
0190 1d d5 c0 6e b7 f1 be 96-9c 24 3d 5a 31 4b 70 90 |...n.... .$=Z1Kp.|
01a0 4d 2c 41 9c 20 7f da 7f-3b 0b 5c 2d 5b 0c af 1d |M,A. ... ;.\-[...|
01b0 fc bb cf ac 25 8b 8f c7-1f 30 90 24 9b e5 32 7c |....%... .0.$..2||
01c0 cc d4 2a 5e 31 08 66 ed-72 75 f6 0d 60 4d f8 46 |..*^1.f. ru..`M.F|
01d0 9a 0c 66 7d 50 ac 88 dd-54 99 42 a3 70 f3 19 f6 |..f}P... T.B.p...|
01e0 c9 70 1c d8 69 d8 e7 e9-58 3f f8 e3 43 18 c6 16 |.p..i... X?..C...|
01f0 92 cd 10 4c 04 92 92 48-f5 fb 5b b7 5e c2 7c d1 |...L...H ..[.^.|.|
0200 a4 6e 28 2b 8d 97 2e dd-53 1f 14 9e 49 5f b1 1d |.n(+.... S...I_..|
0210 b5 cc 8e ff 41 be ff 47-8b 02 03 01 00 01 a3 37 |....A..G .......7|
0220 30 35 30 1e 06 03 55 1d-11 04 17 30 15 82 13 ff |050...U. ...0...x|
0230 ff ff 2e 70 63 2e 6d 61-72 69 63 6f 70 61 2e 65 |xx.pc.ma ricopa.e|
0240 64 75 30 13 06 03 55 1d-25 04 0c 30 0a 06 08 2b |du0...U. %..0...+|
0250 06 01 05 05 07 03 01 30-0d 06 09 2a 86 48 86 f7 |.......0 ...*.H..|
0260 0d 01 01 0b 05 00 03 82-01 01 00 89 2c 45 53 ae |........ ....,ES.|
0270 67 d3 65 7e f5 b6 8b 04-83 ca ae df 17 4a 19 cf |g.e~.... .....J..|
0280 7d 62 e9 ef 6a e6 4e a9-57 b2 7b 8c 58 e3 11 ad |}b..j.N. W.{.X...|
0290 84 77 49 6b 6d 13 eb 23-d9 5c 01 2e 8b 0e b7 8b |.wIkm..# .\......|
02a0 9c ed 31 57 96 4f cf 43-14 e7 f8 1c 23 c2 61 d2 |..1W.O.C ....#.a.|
02b0 36 78 07 c9 04 b0 f0 c2-f9 36 28 b7 79 f0 5b a8 |6x...... .6(.y.[.|
02c0 dc 86 7e ef ee 72 43 ba-a6 8e aa 6f 5e e0 5a c1 |..~..rC. ...o^.Z.|
02d0 e2 03 d3 e4 86 be 3a 44-22 f7 c6 d8 34 09 00 54 |......:D "...4..T|
02e0 7a 61 b1 94 2e 6a d6 41-a7 34 ea c0 42 44 0e 7d |za...j.A .4..BD.}|
02f0 e6 66 6d a8 42 0c 30 6a-41 5c e3 6b 94 1c da b1 |.fm.B.0j A\.k....|
0300 9d 4d 72 59 99 74 ad a8-44 78 c9 96 99 e0 bc 23 |.MrY.t.. Dx.....#|
0310 4c 7c db 81 b8 78 a5 be-be e4 ea 85 04 02 36 88 |L|...x.. ......6.|
0320 e4 f1 0d 0f 67 e7 ec 08-98 b4 00 6b 4a 70 73 36 |....g... ...kJps6|
0330 e7 7f a7 b2 14 06 bd 5a-c8 91 23 ad 3a f2 4f 1f |.......Z ..#.:.O.|
0340 58 03 ca 81 0e 58 09 9d-4b 45 3a 69 57 f6 af 5d |X....X.. KE:iW..]|
0350 62 c6 fb f9 67 4b 5e f9-be ef 76 3d 36 b9 71 60 |b...gK^. ..v=6.q`|
0360 0f 3c 0c 27 fa a2 a4 a4-bc b1 d4 0c 00 02 8a 00 |.<.'.... ........|
0370 80 ff ff ff ff ff ff ff-ff c9 0f da a2 21 68 c2 |........ .....!h.|
0380 34 c4 c6 62 8b 80 dc 1c-d1 29 02 4e 08 8a 67 cc |4..b.... .).N..g.|
0390 74 02 0b be a6 3b 13 9b-22 51 4a 08 79 8e 34 04 |t....;.. "QJ.y.4.|
03a0 dd ef 95 19 b3 cd 3a 43-1b 30 2b 0a 6d f2 5f 14 |......:C .0+.m._.|
03b0 37 4f e1 35 6d 6d 51 c2-45 e4 85 b5 76 62 5e 7e |7O.5mmQ. E...vb^~|
03c0 c6 f4 4c 42 e9 a6 37 ed-6b 0b ff 5c b6 f4 06 b7 |..LB..7. k..\....|
03d0 ed ee 38 6b fb 5a 89 9f-a5 ae 9f 24 11 7c 4b 1f |..8k.Z.. ...$.|K.|
03e0 e6 49 28 66 51 ec e6 53-81 ff ff ff ff ff ff ff |.I(fQ..S ........|
03f0 ff 00 80 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0400 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0410 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0420 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0430 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0440 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0450 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0460 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0470 00 00 02 00 80 3b a2 a9-c8 ce 27 9f 10 8d ab 12 |.....;.. ..'.....|
0480 3f 0e ae b9 e2 4e 59 99-d8 e8 3e 97 41 e6 15 ea |?....NY. ..>.A...|
0490 43 15 7b 64 50 46 8e 88-71 43 fc 2f d3 22 ce 91 |C.{dPF.. qC./."..|
04a0 06 0d 0d 51 cc f3 24 c9-3d fa 42 d4 89 10 71 3d |...Q..$. =.B...q=|
04b0 cc 4b ab 19 01 c8 b9 01-2c df 23 9c 20 42 01 20 |.K...... ,.#. B. |
04c0 b3 c1 d5 62 24 0c 00 29-5b fa a3 8e 85 ad 12 ee |...b$..) [.......|
04d0 67 78 eb 09 ee 4d 88 62-ea b1 00 7b 99 ed 55 7e |gx...M.b ...{..U~|
04e0 89 b3 8d a3 e7 a4 a2 d8-46 e0 63 9f 3a 78 2b 12 |........ F.c.:x+.|
04f0 65 ae fc e2 4f 02 01 01-00 4c b4 90 d7 d4 a9 33 |e...O... .L.....3|
0500 50 2f c1 3e 29 33 f3 8c-78 a3 62 80 1f 60 97 40 |P/.>)3.. x.b..`.@|
0510 75 bc f4 8c d0 3f be 3b-15 ec 10 2f d7 1f 25 f3 |u....?.; .../..%.|
0520 99 f2 3b 3c fe fc ea f6-78 de 39 44 91 fd 25 49 |..;<.... x.9D..%I|
0530 54 8b 72 fd 1a d6 a0 71-e6 ce af 56 0e a2 a2 78 |T.r....q ...V...x|
0540 73 9c b4 0c 32 1c d0 97-88 4f 5c d6 8e 54 16 4a |s...2... .O\..T.J|
0550 a2 22 70 9d 4c f4 bd 14-af aa 83 6e 70 12 d9 27 |."p.L... ...np..'|
0560 27 a9 0b 87 74 b4 a4 ec-5e bf 84 e2 42 2d f1 55 |'...t... ^...B-.U|
0570 57 df dc ab b1 5a 37 c3-99 5f 27 77 2c 4c 57 75 |W....Z7. ._'w,LWu|
0580 2a 75 9a 97 cd 64 d8 56-e4 96 3d 8d b2 22 b4 6b |*u...d.V ..=..".k|
0590 6b f6 6c ee 8a c1 65 9a-5a 0a 8f 28 f8 ff 18 6c |k.l...e. Z..(...l|
05a0 b8 57 33 ca c6 a4 f0 42-01 c6 21 90 98 19 8d 4e |.W3....B ..!....N|
05b0 df 6a 39 f6 16 a7 88 73-98 1e 27 6a 0b d2 da 6b |.j9....s ..'j...k|
05c0 fd 35 5f 52 62 66 81 cd-ad 44 09 11 ce f4 1a 61 |.5_Rbf.. .D.....a|
05d0 82 e4 36 61 c2 00 a4 48-96 29 d0 91 f6 00 01 91 |..6a...H .)......|
05e0 b1 ec fe fa 91 a6 36 3d-45 e5 89 40 75 c7 9a 14 |......6= E..@u...|
05f0 43 31 6c c3 73 05 8b 45-f6 0e 00 00 00          |C1l.s..E .....|

11:47:52.444111 12996 (net.c:1219):after read
11:47:52.444122 12996 (net.c:1212):have 1525
11:47:52.444129 12996 (net.c:1223):read 5 bytes
11:47:52.444138 12996 (net.c:1257):GNUTLS: level 7:
  READ: Got 5 bytes from 0x7f2eb4ec95c0
11:47:52.444147 12996 (net.c:1257):GNUTLS: level 7:
  READ: read 5 bytes from 0x7f2eb4ec95c0
11:47:52.444155 12996 (net.c:1257):GNUTLS: level 7:
  RB: Have 0 bytes into buffer. Adding 5 bytes.
11:47:52.444163 12996 (net.c:1257):GNUTLS: level 7:
  RB: Requested 5 bytes
11:47:52.444172 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Expected Packet[0] Handshake(22) with length: 1
11:47:52.444181 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Received Packet[0] Handshake(22) with length: 1520
11:47:52.444190 12996 (net.c:1199):in tds_pull_func
11:47:52.444197 12996 (net.c:1212):have 1520
11:47:52.444204 12996 (net.c:1223):read 1520 bytes
11:47:52.444212 12996 (net.c:1257):GNUTLS: level 7:
  READ: Got 1520 bytes from 0x7f2eb4ec95c0
11:47:52.444220 12996 (net.c:1257):GNUTLS: level 7:
  READ: read 1520 bytes from 0x7f2eb4ec95c0
11:47:52.444228 12996 (net.c:1257):GNUTLS: level 7:
  RB: Have 5 bytes into buffer. Adding 1520 bytes.
11:47:52.444236 12996 (net.c:1257):GNUTLS: level 7:
  RB: Requested 1525 bytes
11:47:52.444250 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Decrypted Packet[0] Handshake(22) with length: 1520
11:47:52.444259 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 1520 bytes of Data(22)
11:47:52.444269 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
11:47:52.444278 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
11:47:52.444287 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: SERVER HELLO was received [81 bytes]
11:47:52.444295 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 77 bytes of Data(22)
11:47:52.444303 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
11:47:52.444312 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 77 bytes of Data
11:47:52.444321 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Server's version: 3.3
11:47:52.444329 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: SessionID length: 32
11:47:52.444347 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: SessionID: e53c000041dcb3da9dd6039de89ed88adf62036ef4a0abc2fc28d4fcba453dea
11:47:52.444375 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Selected cipher suite: DHE_RSA_AES_256_CBC_SHA1
11:47:52.444386 12996 (net.c:1257):GNUTLS: level 2:
  EXT[0x7f2eb4ec6550]: Parsing extension 'SAFE RENEGOTIATION/65281' (1 bytes)
11:47:52.444395 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Safe renegotiation succeeded
11:47:52.444447 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
11:47:52.444458 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
11:47:52.444467 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: CERTIFICATE was received [781 bytes]
11:47:52.444476 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 777 bytes of Data(22)
11:47:52.444484 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 169 bytes of Data
11:47:52.444512 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
11:47:52.444522 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
11:47:52.444530 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 777 bytes of Data
11:47:52.444742 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
11:47:52.444761 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
11:47:52.444770 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: SERVER KEY EXCHANGE was received [654 bytes]
11:47:52.444779 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 650 bytes of Data(22)
11:47:52.444788 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 781 bytes of Data
11:47:52.444814 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
11:47:52.444823 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
11:47:52.444831 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 650 bytes of Data
11:47:52.444981 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: verify handshake data: using RSA-SHA1
11:47:52.445299 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
11:47:52.445315 12996 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
11:47:52.445324 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: SERVER HELLO DONE was received [4 bytes]
11:47:52.445333 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_handshake.c:1369
11:47:52.445341 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 654 bytes of Data
11:47:52.445366 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
11:47:52.445374 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
11:47:52.455289 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: CLIENT KEY EXCHANGE was sent [134 bytes]
11:47:52.455307 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 4 bytes of Data
11:47:52.455317 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
11:47:52.455331 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE: enqueued 134. Total 134 bytes.
11:47:52.455340 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE FLUSH: 134 bytes in buffer.
11:47:52.455349 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sending Packet[1] Handshake(22) with length: 134
11:47:52.455361 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 139 bytes for 0x7f2eb4ec95c0. Total 139 bytes.
11:47:52.455370 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sent Packet[2] Handshake(22) with length: 139
11:47:52.455379 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE: wrote 134 bytes, 0 bytes left.
11:47:52.455387 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 139 bytes in buffer.
11:47:52.455395 12996 (net.c:1240):in tds_push_func
11:47:52.455403 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 139 bytes, 0 bytes left.
11:47:52.455411 12996 (net.c:1257):GNUTLS: level 3:
  REC[0x7f2eb4ec6550]: Sent ChangeCipherSpec
11:47:52.455420 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sending Packet[2] Change Cipher Spec(20) with length: 1
11:47:52.455430 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 6 bytes for 0x7f2eb4ec95c0. Total 6 bytes.
11:47:52.455438 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 6 bytes in buffer.
11:47:52.455445 12996 (net.c:1240):in tds_push_func
11:47:52.455453 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 6 bytes, 0 bytes left.
11:47:52.455462 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sent Packet[3] Change Cipher Spec(20) with length: 6
11:47:52.455505 12996 (net.c:1257):GNUTLS: level 9:
  INT: PREMASTER SECRET[127]: 05d93cc1e9b02f062cf98776d51cd75e257eb3d879a66003d7b449f35473a1f911569fea3febef5b7b15124f8b649fa4c97afe5be7d5b2fe9723a9a570f1d90c32ee0f21ade187d17ad3d8257cdae31e41e22f427261baff58365e11201f5cf8cefa105e68b66b249bcbf1fd251dd67b08f69a1668586646ec2a33e8a194a8
11:47:52.455523 12996 (net.c:1257):GNUTLS: level 9:
  INT: CLIENT RANDOM[32]: 57daecd8dfc9864987d725b09e38d3866717932efcccd0f47d05c7e7cecae851
11:47:52.455550 12996 (net.c:1257):GNUTLS: level 9:
  INT: SERVER RANDOM[32]: 57daecd846974a860f4496c6a1a36020695ae1068911e3b2e9eb85051a39a43b
11:47:52.455803 12996 (net.c:1257):GNUTLS: level 9:
  INT: MASTER SECRET: 894440589bd978b0caa7b16a2899fed2294ae80030984a58b017154a645507402671e25d863f1590b6771174ff11d00a
11:47:52.455817 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Initializing epoch #1
11:47:52.456189 12996 (net.c:1257):GNUTLS: level 9:
  INT: KEY BLOCK[136]: 92a56d21bdda0b4108b87477e39933f3788a65397ce676c5dc3a22207b24a707
11:47:52.456210 12996 (net.c:1257):GNUTLS: level 9:
  INT: CLIENT WRITE KEY [32]: 55cee29c3123eacf5c4097fec115c83930e2268be880697826f853d27f402136
11:47:52.456227 12996 (net.c:1257):GNUTLS: level 9:
  INT: SERVER WRITE KEY [32]: 67a75e74c56f298d42649d8c6940f71cf3b9147dfff8c1e9f0c27e05531d3496
11:47:52.456248 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Epoch #1 ready
11:47:52.456258 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Cipher Suite: DHE_RSA_AES_256_CBC_SHA1
11:47:52.456267 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: Initializing internal [write] cipher sessions
11:47:52.456275 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Start of epoch cleanup
11:47:52.456283 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: End of epoch cleanup
11:47:52.456292 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 0 bytes of Data
11:47:52.456299 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
11:47:52.456408 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: recording tls-unique CB (send)
11:47:52.456419 12996 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec6550]: FINISHED was sent [16 bytes]
11:47:52.456428 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 0 bytes of Data
11:47:52.456436 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
11:47:52.456445 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE: enqueued 16. Total 16 bytes.
11:47:52.456453 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE FLUSH: 16 bytes in buffer.
11:47:52.456463 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sending Packet[0] Handshake(22) with length: 16
11:47:52.456496 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 69 bytes for 0x7f2eb4ec95c0. Total 69 bytes.
11:47:52.456507 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Sent Packet[1] Handshake(22) with length: 69
11:47:52.456516 12996 (net.c:1257):GNUTLS: level 7:
  HWRITE: wrote 16 bytes, 0 bytes left.
11:47:52.456524 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 69 bytes in buffer.
11:47:52.456532 12996 (net.c:1240):in tds_push_func
11:47:52.456540 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 69 bytes, 0 bytes left.
11:47:52.456549 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: ext_session_ticket.c:710
11:47:52.456556 12996 (net.c:1199):in tds_pull_func
11:47:52.456563 12996 (net.c:741):Sending packet
0000 12 01 00 de 00 00 00 00-16 03 03 00 86 10 00 00 |........ ........|
0010 82 00 80 a3 9b 18 15 fe-6d b8 38 c2 d7 86 52 95 |........ m.8...R.|
0020 33 87 c2 8a 72 17 2e 08-5c 0d f7 7f 4c 1a 2d 68 |3...r... \...L.-h|
0030 06 97 97 32 c8 8a 13 f4-0e fe fb c6 a7 fc 5d d0 |...2.... ......].|
0040 3f 05 cd 10 ee b7 ff 38-2d 6f c4 d2 14 9c 20 87 |?......8 -o.... .|
0050 29 31 7d c7 5a 13 93 81-47 ed 7e 6b e2 19 6b c1 |)1}.Z... G.~k..k.|
0060 be e6 64 52 f4 0f 1e 2c-14 49 5f 71 8f 51 ca 4c |..dR..., .I_q.Q.L|
0070 2b d7 16 d7 d1 a2 30 df-32 3d 5c 8c e3 59 d1 98 |+.....0. 2=\..Y..|
0080 d6 0b 99 38 74 71 b2 59-eb 76 40 36 61 a6 9e de |...8tq.Y .v@6a...|
0090 d1 d1 15 14 03 03 00 01-01 16 03 03 00 40 3e fd |........ .....@>.|
00a0 1b 08 f3 f1 2b 2b cf 30-5f 94 62 8f e7 c3 cf 9c |....++.0 _.b.....|
00b0 b8 59 03 fd 74 aa 1f 8f-7d 7a ff ad 0e 22 6d b5 |.Y..t... }z..."m.|
00c0 07 cf 2d 08 df 45 9a cf-2e 6a ae 1b 05 22 c9 9c |..-..E.. .j..."..|
00d0 29 53 c5 c5 19 de 65 58-15 9a ca 45 89 ca       |)S....eX ...E..|

11:47:52.456693 12996 (net.c:1212):have 0
11:47:52.456737 12996 (net.c:1216):before read
11:47:52.460409 12996 (util.c:331):tdserror(0x7f2eb4562900, 0x7f2eb4ec95c0, 20017, 115)
11:47:52.460449 12996 (dblib.c:7929):dbperror(0x7f2eb4ec9e30, 20017, 115)
11:47:52.460463 12996 (dblib.c:7981):20017: "Unexpected EOF from the server"
11:47:52.460473 12996 (dblib.c:5780):dbgetuserdata(0x7f2eb4ec9e30)
11:47:52.460488 12996 (dblib.c:8002):"Unexpected EOF from the server", client returns 2 (INT_CANCEL)
11:47:52.460497 12996 (util.c:361):tdserror: client library returned TDS_INT_CANCEL(2)
11:47:52.460504 12996 (util.c:384):tdserror: returning TDS_INT_CANCEL(2)
11:47:52.460607 12996 (util.c:156):Changed query state from IDLE to DEAD
11:47:52.460664 12996 (net.c:1257):GNUTLS: level 7:
  READ: -1 returned from 0x7f2eb4ec95c0, errno=115 gerrno=0
11:47:52.460677 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_buffers.c:344
11:47:52.460687 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_buffers.c:625
11:47:52.460695 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_record.c:969
11:47:52.460703 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_handshake.c:2933
11:47:52.460711 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_handshake.c:3139
11:47:52.460725 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Cleared Data from buffer
11:47:52.460735 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Cleared Data from buffer
11:47:52.460748 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Epoch #0 freed
11:47:52.460756 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec6550]: Epoch #1 freed
11:47:52.460778 12996 (net.c:1366):handshake failed: A TLS packet with unexpected length was received.
11:47:52.460787 12996 (login.c:466):login packet rejected
11:47:52.460794 12996 (util.c:331):tdserror(0x7f2eb4562900, 0x7f2eb4ec95c0, 20002, 0)
11:47:52.460803 12996 (dblib.c:7929):dbperror(0x7f2eb4ec9e30, 20002, 0)
11:47:52.460813 12996 (dblib.c:7981):20002: "Adaptive Server connection failed"
11:47:52.460821 12996 (dblib.c:5780):dbgetuserdata(0x7f2eb4ec9e30)
11:47:52.460829 12996 (dblib.c:8002):"Adaptive Server connection failed", client returns 2 (INT_CANCEL)
11:47:52.460837 12996 (util.c:361):tdserror: client library returned TDS_INT_CANCEL(2)
11:47:52.460844 12996 (util.c:384):tdserror: returning TDS_INT_CANCEL(2)
11:47:52.460853 12996 (dblib.c:1443):dbclose(0x7f2eb4ec9e30)
11:47:52.460860 12996 (dblib.c:258):dblib_del_connection(0x7f2ea328e840, 0x7f2eb4ec95c0)
11:47:52.460879 12996 (mem.c:615):tds_free_all_results()
11:47:52.460892 12996 (dblib.c:305):dblib_release_tds_ctx(1)
11:47:52.460902 12996 (dblib.c:5882):dbfreebuf(0x7f2eb4ec9e30)
11:47:52.696151 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4e29d90]: Sending Packet[3] Application Data(23) with length: 7
11:47:52.696227 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 69 bytes for 0x7f2eb4c55270. Total 69 bytes.
11:47:52.696236 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 69 bytes in buffer.
11:47:52.696278 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 69 bytes, 0 bytes left.
11:47:52.696308 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4e29d90]: Sent Packet[4] Application Data(23) with length: 69
11:47:52.696323 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 0 bytes in buffer.
11:47:52.696329 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_buffers.c:731
11:47:52.696338 12996 (net.c:1257):GNUTLS: level 4:
  REC: Sending Alert[1|0] - Close notify
11:47:52.696345 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4e29d90]: Sending Packet[4] Alert(21) with length: 2
11:47:52.696369 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 245 bytes for 0x7f2eb4c55270. Total 245 bytes.
11:47:52.696376 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 245 bytes in buffer.
11:47:52.696403 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 245 bytes, 0 bytes left.
11:47:52.696430 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4e29d90]: Sent Packet[5] Alert(21) with length: 245
11:47:52.696475 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 0 bytes in buffer.
11:47:52.696487 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_buffers.c:731
11:47:52.696494 12996 (net.c:1257):GNUTLS: level 4:
  REC: Sending Alert[1|0] - Close notify
11:47:52.696507 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4e29d90]: Sending Packet[5] Alert(21) with length: 2
11:47:52.696548 12996 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 117 bytes for 0x7f2eb4c55270. Total 117 bytes.
11:47:52.696555 12996 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 117 bytes in buffer.
11:47:52.696560 12996 (net.c:1257):GNUTLS: level 2:
  errno: 9
11:47:52.696589 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_buffers.c:431
11:47:52.696605 12996 (net.c:1257):GNUTLS: level 7:
  WRITE error: code -53, 117 bytes left.
11:47:52.696611 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_buffers.c:755
11:47:52.696616 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_record.c:491
11:47:52.696621 12996 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_record.c:248
11:47:52.696628 12996 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Cleared Data from buffer
11:47:52.696637 12996 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4e29d90]: Epoch #1 freed
11:47:52.698618 12996 (dblib.c:739):dbloginfree(0x7f2eb4ec9750)

13:41:16.742972 15113 (log.c:196):Starting log file for FreeTDS 0.91
	on 2016-09-15 13:41:16 with debug flags 0xffff.
13:41:16.743020 15113 (iconv.c:330):tds_iconv_open(0x7f2eb4e30200, ISO-8859-1)
13:41:16.743238 15113 (iconv.c:187):local name for ISO-8859-1 is ISO-8859-1
13:41:16.743256 15113 (iconv.c:187):local name for UTF-8 is UTF-8
13:41:16.743264 15113 (iconv.c:187):local name for UCS-2LE is UCS-2LE
13:41:16.743271 15113 (iconv.c:187):local name for UCS-2BE is UCS-2BE
13:41:16.743278 15113 (iconv.c:349):setting up conversions for client charset "ISO-8859-1"
13:41:16.743285 15113 (iconv.c:351):preparing iconv for "ISO-8859-1" <-> "UCS-2LE" conversion
13:41:16.743296 15113 (iconv.c:391):preparing iconv for "ISO-8859-1" <-> "UCS-2LE" conversion
13:41:16.743319 15113 (iconv.c:394):tds_iconv_open: done
13:41:16.743339 15113 (net.c:205):Connecting to xxx.xxx.xxx.xxx port 1433 (TDS version 7.1)
13:41:16.743427 15113 (net.c:270):tds_open_socket: connect(2) returned "Operation now in progress"
13:41:16.743718 15113 (net.c:310):tds_open_socket() succeeded
13:41:16.743742 15113 (util.c:156):Changed query state from DEAD to IDLE
13:41:16.743757 15113 (net.c:741):Sending packet
0000 12 01 00 34 00 00 00 00-00 00 15 00 06 01 00 1b |...4.... ........|
0010 00 01 02 00 1c 00 0c 03-00 28 00 04 ff 08 00 01 |........ .(......|
0020 55 00 00 01 4d 53 53 51-4c 53 65 72 76 65 72 00 |U...MSSQ LServer.|
0030 09 3b 00 00            -                        |.;..|

13:41:16.744153 15113 (net.c:555):Received header
0000 04 01 00 25 00 00 01 00-                        |...%....|

13:41:16.744179 15113 (net.c:609):Received packet
0000 04 01 00 25 00 00 01 00-00 00 15 00 06 01 00 1b |...%.... ........|
0010 00 01 02 00 1c 00 01 03-00 1d 00 00 ff 0b 00 17 |........ ........|
0020 84 00 00 01 00         -                        |.....|

13:41:16.744202 15113 (login.c:1057):detected flag 1
13:41:16.744215 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Allocating epoch #0
13:41:16.744230 15113 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_constate.c:695
13:41:16.744239 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Allocating epoch #1
13:41:16.744353 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: RSA_AES_256_CBC_SHA1
13:41:16.744365 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: RSA_AES_128_CBC_SHA1
13:41:16.744374 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: RSA_3DES_EDE_CBC_SHA1
13:41:16.744383 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: RSA_ARCFOUR_SHA1
13:41:16.744391 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: RSA_ARCFOUR_MD5
13:41:16.744400 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_DSS_AES_256_CBC_SHA1
13:41:16.744409 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_DSS_AES_128_CBC_SHA1
13:41:16.744417 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_DSS_3DES_EDE_CBC_SHA1
13:41:16.744425 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_DSS_ARCFOUR_SHA1
13:41:16.744434 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_RSA_AES_256_CBC_SHA1
13:41:16.744442 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_RSA_AES_128_CBC_SHA1
13:41:16.744451 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Keeping ciphersuite: DHE_RSA_3DES_EDE_CBC_SHA1
13:41:16.744474 15113 (net.c:1257):GNUTLS: level 2:
  EXT[0x7f2eb4ec50d0]: Sending extension SAFE RENEGOTIATION (1 bytes)
13:41:16.744486 15113 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (4.2) DSA-SHA256
13:41:16.744495 15113 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (4.1) RSA-SHA256
13:41:16.744504 15113 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (2.1) RSA-SHA1
13:41:16.744512 15113 (net.c:1257):GNUTLS: level 2:
  EXT[SIGA]: sent signature algo (2.2) DSA-SHA1
13:41:16.744530 15113 (net.c:1257):GNUTLS: level 2:
  EXT[0x7f2eb4ec50d0]: Sending extension SIGNATURE ALGORITHMS (10 bytes)
13:41:16.744541 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: CLIENT HELLO was sent [88 bytes]
13:41:16.744550 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 88 bytes of Data
13:41:16.744559 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE: enqueued 88. Total 88 bytes.
13:41:16.744606 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE FLUSH: 88 bytes in buffer.
13:41:16.744624 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sending Packet[0] Handshake(22) with length: 88
13:41:16.744635 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 93 bytes for 0x7f2eb4e30200. Total 93 bytes.
13:41:16.744657 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sent Packet[1] Handshake(22) with length: 93
13:41:16.744668 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE: wrote 88 bytes, 0 bytes left.
13:41:16.744676 15113 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 93 bytes in buffer.
13:41:16.744684 15113 (net.c:1240):in tds_push_func
13:41:16.744692 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 93 bytes, 0 bytes left.
13:41:16.744700 15113 (net.c:1199):in tds_pull_func
13:41:16.744708 15113 (net.c:741):Sending packet
0000 12 01 00 65 00 00 00 00-16 03 00 00 58 01 00 00 |...e.... ....X...|
0010 54 03 03 57 db 07 6c 22-ba 8c ae 51 b0 39 3a 70 |T..W..l" ...Q.9:p|
0020 38 86 68 09 03 7f 65 c9-1e 56 21 15 db 6b fe ba |8.h...e. .V!..k..|
0030 15 13 e5 00 00 18 00 35-00 2f 00 0a 00 05 00 04 |.......5 ./......|
0040 00 38 00 32 00 13 00 66-00 39 00 33 00 16 01 00 |.8.2...f .9.3....|
0050 00 13 ff 01 00 01 00 00-0d 00 0a 00 08 04 02 04 |........ ........|
0060 01 02 01 02 02         -                        |.....|

13:41:16.744788 15113 (net.c:1212):have 0
13:41:16.744803 15113 (net.c:1216):before read
13:41:16.755754 15113 (net.c:555):Received header
0000 12 01 05 fd 00 00 00 00-                        |........|

13:41:16.755787 15113 (net.c:609):Received packet
0000 12 01 05 fd 00 00 00 00-16 03 03 05 f0 02 00 00 |........ ........|
0010 4d 03 03 57 db 07 6d 91-83 44 0f 4a 89 3e 9b fb |M..W..m. .D.J.>..|
0020 8e 4f fd 11 66 74 6a 6b-c9 b2 86 11 49 a3 31 84 |.O..ftjk ....I.1.|
0030 d0 85 8d 20 d6 0f 00 00-b6 d5 78 45 9b 53 8f c1 |... .... ..xE.S..|
0040 67 13 66 cc 17 67 14 92-52 3c a5 20 3a a1 45 88 |g.f..g.. R<. :.E.|
0050 9e 35 32 88 00 39 00 00-05 ff 01 00 01 00 0b 00 |.52..9.. ........|
0060 03 09 00 03 06 00 03 03-30 82 02 ff 30 82 01 e7 |........ 0...0...|
0070 a0 03 02 01 02 02 10 17-62 73 2d 10 da fc aa 43 |........ bs-....C|
0080 7c df 81 81 87 4a ca 30-0d 06 09 2a 86 48 86 f7 ||....J.0 ...*.H..|
0090 0d 01 01 0b 05 00 30 1e-31 1c 30 1a 06 03 55 04 |......0. 1.0...U.|
00a0 03 13 13 ff ff ff 2e 70-63 2e 6d 61 72 69 63 6f |...xxx.p c.marico|
00b0 70 61 2e 65 64 75 30 20-17 0d 31 36 30 34 30 38 |pa.edu0  ..160408|
00c0 31 32 34 39 35 39 5a 18-0f 32 31 31 36 30 33 31 |124959Z. .2116031|
00d0 36 31 32 34 39 35 39 5a-30 1e 31 1c 30 1a 06 03 |6124959Z 0.1.0...|
00e0 55 04 03 13 13 ff ff ff-2e 70 63 2e 6d 61 72 69 |U....xxx .pc.mari|
00f0 63 6f 70 61 2e 65 64 75-30 82 01 22 30 0d 06 09 |copa.edu 0.."0...|
0100 2a 86 48 86 f7 0d 01 01-01 05 00 03 82 01 0f 00 |*.H..... ........|
0110 30 82 01 0a 02 82 01 01-00 a9 e0 dc 0d b2 f9 0f |0....... ........|
0120 04 3e 83 68 98 24 a6 de-52 78 46 a2 70 6a 6a db |.>.h.$.. RxF.pjj.|
0130 5f 15 2c 41 e4 4c 65 c7-cf 5e b4 8c 9b be c9 5b |_.,A.Le. .^.....[|
0140 52 90 f2 e0 9f 07 60 a8-6a 6e 95 90 43 5e ae 3e |R.....`. jn..C^.>|
0150 1b 01 04 95 3c fa fa a3-f6 6b 63 59 f1 8e 08 28 |....<... .kcY...(|
0160 ea c1 46 f7 51 8e dd 33-d9 df 79 78 30 e6 0e b2 |..F.Q..3 ..yx0...|
0170 67 ff a1 66 15 1a 9d e9-b3 83 eb b8 7c f8 f7 56 |g..f.... ....|..V|
0180 b3 03 4b f5 b7 87 dc 91-77 3b cb aa 4d b8 5f 1e |..K..... w;..M._.|
0190 1d d5 c0 6e b7 f1 be 96-9c 24 3d 5a 31 4b 70 90 |...n.... .$=Z1Kp.|
01a0 4d 2c 41 9c 20 7f da 7f-3b 0b 5c 2d 5b 0c af 1d |M,A. ... ;.\-[...|
01b0 fc bb cf ac 25 8b 8f c7-1f 30 90 24 9b e5 32 7c |....%... .0.$..2||
01c0 cc d4 2a 5e 31 08 66 ed-72 75 f6 0d 60 4d f8 46 |..*^1.f. ru..`M.F|
01d0 9a 0c 66 7d 50 ac 88 dd-54 99 42 a3 70 f3 19 f6 |..f}P... T.B.p...|
01e0 c9 70 1c d8 69 d8 e7 e9-58 3f f8 e3 43 18 c6 16 |.p..i... X?..C...|
01f0 92 cd 10 4c 04 92 92 48-f5 fb 5b b7 5e c2 7c d1 |...L...H ..[.^.|.|
0200 a4 6e 28 2b 8d 97 2e dd-53 1f 14 9e 49 5f b1 1d |.n(+.... S...I_..|
0210 b5 cc 8e ff 41 be ff 47-8b 02 03 01 00 01 a3 37 |....A..G .......7|
0220 30 35 30 1e 06 03 55 1d-11 04 17 30 15 82 13 ff |050...U. ...0...x|
0230 ff ff 2e 70 63 2e 6d 61-72 69 63 6f 70 61 2e 65 |xx.pc.ma ricopa.e|
0240 64 75 30 13 06 03 55 1d-25 04 0c 30 0a 06 08 2b |du0...U. %..0...+|
0250 06 01 05 05 07 03 01 30-0d 06 09 2a 86 48 86 f7 |.......0 ...*.H..|
0260 0d 01 01 0b 05 00 03 82-01 01 00 89 2c 45 53 ae |........ ....,ES.|
0270 67 d3 65 7e f5 b6 8b 04-83 ca ae df 17 4a 19 cf |g.e~.... .....J..|
0280 7d 62 e9 ef 6a e6 4e a9-57 b2 7b 8c 58 e3 11 ad |}b..j.N. W.{.X...|
0290 84 77 49 6b 6d 13 eb 23-d9 5c 01 2e 8b 0e b7 8b |.wIkm..# .\......|
02a0 9c ed 31 57 96 4f cf 43-14 e7 f8 1c 23 c2 61 d2 |..1W.O.C ....#.a.|
02b0 36 78 07 c9 04 b0 f0 c2-f9 36 28 b7 79 f0 5b a8 |6x...... .6(.y.[.|
02c0 dc 86 7e ef ee 72 43 ba-a6 8e aa 6f 5e e0 5a c1 |..~..rC. ...o^.Z.|
02d0 e2 03 d3 e4 86 be 3a 44-22 f7 c6 d8 34 09 00 54 |......:D "...4..T|
02e0 7a 61 b1 94 2e 6a d6 41-a7 34 ea c0 42 44 0e 7d |za...j.A .4..BD.}|
02f0 e6 66 6d a8 42 0c 30 6a-41 5c e3 6b 94 1c da b1 |.fm.B.0j A\.k....|
0300 9d 4d 72 59 99 74 ad a8-44 78 c9 96 99 e0 bc 23 |.MrY.t.. Dx.....#|
0310 4c 7c db 81 b8 78 a5 be-be e4 ea 85 04 02 36 88 |L|...x.. ......6.|
0320 e4 f1 0d 0f 67 e7 ec 08-98 b4 00 6b 4a 70 73 36 |....g... ...kJps6|
0330 e7 7f a7 b2 14 06 bd 5a-c8 91 23 ad 3a f2 4f 1f |.......Z ..#.:.O.|
0340 58 03 ca 81 0e 58 09 9d-4b 45 3a 69 57 f6 af 5d |X....X.. KE:iW..]|
0350 62 c6 fb f9 67 4b 5e f9-be ef 76 3d 36 b9 71 60 |b...gK^. ..v=6.q`|
0360 0f 3c 0c 27 fa a2 a4 a4-bc b1 d4 0c 00 02 8a 00 |.<.'.... ........|
0370 80 ff ff ff ff ff ff ff-ff c9 0f da a2 21 68 c2 |........ .....!h.|
0380 34 c4 c6 62 8b 80 dc 1c-d1 29 02 4e 08 8a 67 cc |4..b.... .).N..g.|
0390 74 02 0b be a6 3b 13 9b-22 51 4a 08 79 8e 34 04 |t....;.. "QJ.y.4.|
03a0 dd ef 95 19 b3 cd 3a 43-1b 30 2b 0a 6d f2 5f 14 |......:C .0+.m._.|
03b0 37 4f e1 35 6d 6d 51 c2-45 e4 85 b5 76 62 5e 7e |7O.5mmQ. E...vb^~|
03c0 c6 f4 4c 42 e9 a6 37 ed-6b 0b ff 5c b6 f4 06 b7 |..LB..7. k..\....|
03d0 ed ee 38 6b fb 5a 89 9f-a5 ae 9f 24 11 7c 4b 1f |..8k.Z.. ...$.|K.|
03e0 e6 49 28 66 51 ec e6 53-81 ff ff ff ff ff ff ff |.I(fQ..S ........|
03f0 ff 00 80 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0400 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0410 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0420 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0430 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0440 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0450 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0460 00 00 00 00 00 00 00 00-00 00 00 00 00 00 00 00 |........ ........|
0470 00 00 02 00 80 c5 98 d4-87 1a ef f3 e8 bf 11 6d |........ .......m|
0480 aa 04 0f cc 46 b1 0c 7a-68 7b 8e 40 e4 ba 83 57 |....F..z h{.@...W|
0490 52 be 22 cd 9d 6e a3 b4-50 de 62 16 35 c4 a8 cd |R."..n.. P.b.5...|
04a0 d2 e8 2a d6 d8 94 a8 34-79 61 a8 0d 7f 59 20 fd |..*....4 ya...Y .|
04b0 0b 8e f7 b7 0d 12 56 66-e8 1a b1 0d b1 ea f7 22 |......Vf ......."|
04c0 f8 81 39 d5 ef 35 e7 4d-6d 2a b9 5a bc 13 99 81 |..9..5.M m*.Z....|
04d0 c0 19 6d f3 41 fc 69 58-44 bd fa c5 33 1f 25 78 |..m.A.iX D...3.%x|
04e0 8e f9 16 d8 39 bc 2e 19-6c 4e 4c c2 4b be 65 b8 |....9... lNL.K.e.|
04f0 a9 1b cf ad c9 02 01 01-00 19 c4 78 76 02 58 b2 |........ ...xv.X.|
0500 51 5e 5a 8d 50 a3 30 c4-dc f8 37 fb 0f 30 a4 88 |Q^Z.P.0. ..7..0..|
0510 93 ff de 4f c4 37 fb a2-2d 10 32 5f e9 b8 2c 17 |...O.7.. -.2_..,.|
0520 35 b0 c9 4f 56 8c 56 02-ef 23 bb 05 5c c4 b9 8d |5..OV.V. .#..\...|
0530 b0 c2 d9 23 c0 b8 78 d2-5c 44 bb 56 82 5f ca 93 |...#..x. \D.V._..|
0540 d2 d3 02 9f 4c 07 7c aa-0d dc 9c 52 bf 22 12 ea |....L.|. ...R."..|
0550 03 2f 3d 7b 85 1d 7c 30-9b 49 03 51 ef 5f b7 7c |./={..|0 .I.Q._.||
0560 7a ea 32 51 34 a2 82 b8-ed d0 27 8b 3f b6 5c 17 |z.2Q4... ..'.?.\.|
0570 3e 61 e0 89 c9 9f e8 50-d3 20 63 3c 4d 93 97 71 |>a.....P . c<M..q|
0580 5f 3a ab d5 71 a5 48 2d-9d 76 8a d4 d9 18 06 c3 |_:..q.H- .v......|
0590 44 89 33 b2 9c 3a 46 b3-38 2b 4b d7 95 63 ab 28 |D.3..:F. 8+K..c.(|
05a0 2a b7 24 35 23 11 97 21-6f 37 6f 84 f9 83 96 5f |*.$5#..! o7o...._|
05b0 b2 f7 1b 37 c7 ec 86 5c-9c 25 ab 09 17 48 66 4b |...7...\ .%...HfK|
05c0 37 0d fe 54 f5 40 94 fe-2e 1e 3b 01 32 03 70 22 |7..T.@.. ..;.2.p"|
05d0 92 08 8a 98 3b fe 64 83-74 e2 99 db 4f 89 a8 24 |....;.d. t...O..$|
05e0 9e 60 28 1a 90 de f9 29-c6 b2 83 9e 9d 44 c8 8a |.`(....) .....D..|
05f0 57 60 6a 17 ee c6 4b 6c-8c 0e 00 00 00          |W`j...Kl .....|

13:41:16.756318 15113 (net.c:1219):after read
13:41:16.756327 15113 (net.c:1212):have 1525
13:41:16.756334 15113 (net.c:1223):read 5 bytes
13:41:16.756343 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 5 bytes from 0x7f2eb4e30200
13:41:16.756351 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 5 bytes from 0x7f2eb4e30200
13:41:16.756359 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 0 bytes into buffer. Adding 5 bytes.
13:41:16.756367 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 5 bytes
13:41:16.756377 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Expected Packet[0] Handshake(22) with length: 1
13:41:16.756386 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Received Packet[0] Handshake(22) with length: 1520
13:41:16.756394 15113 (net.c:1199):in tds_pull_func
13:41:16.756401 15113 (net.c:1212):have 1520
13:41:16.756408 15113 (net.c:1223):read 1520 bytes
13:41:16.756417 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 1520 bytes from 0x7f2eb4e30200
13:41:16.756425 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 1520 bytes from 0x7f2eb4e30200
13:41:16.756433 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 5 bytes into buffer. Adding 1520 bytes.
13:41:16.756441 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 1525 bytes
13:41:16.756458 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Decrypted Packet[0] Handshake(22) with length: 1520
13:41:16.756468 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 1520 bytes of Data(22)
13:41:16.756477 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
13:41:16.756486 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
13:41:16.756495 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: SERVER HELLO was received [81 bytes]
13:41:16.756504 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 77 bytes of Data(22)
13:41:16.756512 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
13:41:16.756521 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 77 bytes of Data
13:41:16.756530 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Server's version: 3.3
13:41:16.756538 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: SessionID length: 32
13:41:16.756555 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: SessionID: d60f0000b6d578459b538fc1671366cc17671492523ca5203aa145889e353288
13:41:16.756600 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Selected cipher suite: DHE_RSA_AES_256_CBC_SHA1
13:41:16.756614 15113 (net.c:1257):GNUTLS: level 2:
  EXT[0x7f2eb4ec50d0]: Parsing extension 'SAFE RENEGOTIATION/65281' (1 bytes)
13:41:16.756624 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Safe renegotiation succeeded
13:41:16.756674 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
13:41:16.756685 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
13:41:16.756694 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: CERTIFICATE was received [781 bytes]
13:41:16.756703 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 777 bytes of Data(22)
13:41:16.756711 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 169 bytes of Data
13:41:16.756735 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.756745 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
13:41:16.756753 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 777 bytes of Data
13:41:16.756964 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
13:41:16.756981 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
13:41:16.756990 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: SERVER KEY EXCHANGE was received [654 bytes]
13:41:16.757000 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 650 bytes of Data(22)
13:41:16.757008 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 781 bytes of Data
13:41:16.757035 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.757043 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
13:41:16.757051 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 650 bytes of Data
13:41:16.757202 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: verify handshake data: using RSA-SHA1
13:41:16.757517 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
13:41:16.757532 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
13:41:16.757542 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: SERVER HELLO DONE was received [4 bytes]
13:41:16.757551 15113 (net.c:1257):GNUTLS: level 2:
  ASSERT: gnutls_handshake.c:1369
13:41:16.757559 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 654 bytes of Data
13:41:16.757584 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.757593 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
13:41:16.767631 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: CLIENT KEY EXCHANGE was sent [134 bytes]
13:41:16.767653 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 4 bytes of Data
13:41:16.767663 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.767677 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE: enqueued 134. Total 134 bytes.
13:41:16.767686 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE FLUSH: 134 bytes in buffer.
13:41:16.767696 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sending Packet[1] Handshake(22) with length: 134
13:41:16.767707 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 139 bytes for 0x7f2eb4e30200. Total 139 bytes.
13:41:16.767716 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sent Packet[2] Handshake(22) with length: 139
13:41:16.767725 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE: wrote 134 bytes, 0 bytes left.
13:41:16.767733 15113 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 139 bytes in buffer.
13:41:16.767740 15113 (net.c:1240):in tds_push_func
13:41:16.767748 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 139 bytes, 0 bytes left.
13:41:16.767757 15113 (net.c:1257):GNUTLS: level 3:
  REC[0x7f2eb4ec50d0]: Sent ChangeCipherSpec
13:41:16.767766 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sending Packet[2] Change Cipher Spec(20) with length: 1
13:41:16.767775 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 6 bytes for 0x7f2eb4e30200. Total 6 bytes.
13:41:16.767784 15113 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 6 bytes in buffer.
13:41:16.767791 15113 (net.c:1240):in tds_push_func
13:41:16.767799 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 6 bytes, 0 bytes left.
13:41:16.767807 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sent Packet[3] Change Cipher Spec(20) with length: 6
13:41:16.767851 15113 (net.c:1257):GNUTLS: level 9:
  INT: PREMASTER SECRET[128]: f2f86cbc8aa39b2a7b9fe0d2ef831e238d3ccb8d8a420d09283ce3842808d991221db5a3f89a07473c54f4ae9184344bf851d439e34ad8de77d5ff765f405b9e1345a7a86a6fca465a8dae61b9f1f007d4a91705cded5f310e3713cf7eae16caf2ce1b37c320e87bc560849256f906e2b9f48582ecce9ab9e5e940dbb719cbd3
13:41:16.767868 15113 (net.c:1257):GNUTLS: level 9:
  INT: CLIENT RANDOM[32]: 57db076c22ba8cae51b0393a7038866809037f65c91e562115db6bfeba1513e5
13:41:16.767910 15113 (net.c:1257):GNUTLS: level 9:
  INT: SERVER RANDOM[32]: 57db076d9183440f4a893e9bfb8e4ffd1166746a6bc9b2861149a33184d0858d
13:41:16.768163 15113 (net.c:1257):GNUTLS: level 9:
  INT: MASTER SECRET: e85d95f43879ca85cb63d35d3b3f90117537a91058fc7c3e88d319e875d57dd923329198c92c0a17f13286249935d114
13:41:16.768176 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Initializing epoch #1
13:41:16.768540 15113 (net.c:1257):GNUTLS: level 9:
  INT: KEY BLOCK[136]: aa889b383a4fc21d8ec2a12e1ffea54a1296138f1ba5289c6a39afe65a3cd501
13:41:16.768559 15113 (net.c:1257):GNUTLS: level 9:
  INT: CLIENT WRITE KEY [32]: 9d4367810143f57ddafd4e86413e282f9b4779a9516996e056ecb9b62d32dffe
13:41:16.768592 15113 (net.c:1257):GNUTLS: level 9:
  INT: SERVER WRITE KEY [32]: a8c90ae35e183b32cab1c2af8e8cb03005cfc6c3b1f6cd3f0a38caf3e4376e43
13:41:16.768617 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Epoch #1 ready
13:41:16.768628 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Cipher Suite: DHE_RSA_AES_256_CBC_SHA1
13:41:16.768636 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Initializing internal [write] cipher sessions
13:41:16.768644 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Start of epoch cleanup
13:41:16.768652 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: End of epoch cleanup
13:41:16.768660 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 0 bytes of Data
13:41:16.768668 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.768777 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: recording tls-unique CB (send)
13:41:16.768789 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: FINISHED was sent [16 bytes]
13:41:16.768797 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 0 bytes of Data
13:41:16.768805 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.768814 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE: enqueued 16. Total 16 bytes.
13:41:16.768822 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE FLUSH: 16 bytes in buffer.
13:41:16.768831 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sending Packet[0] Handshake(22) with length: 16
13:41:16.768864 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: enqueued 69 bytes for 0x7f2eb4e30200. Total 69 bytes.
13:41:16.768876 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Sent Packet[1] Handshake(22) with length: 69
13:41:16.768884 15113 (net.c:1257):GNUTLS: level 7:
  HWRITE: wrote 16 bytes, 0 bytes left.
13:41:16.768892 15113 (net.c:1257):GNUTLS: level 7:
  WRITE FLUSH: 69 bytes in buffer.
13:41:16.768900 15113 (net.c:1240):in tds_push_func
13:41:16.768908 15113 (net.c:1257):GNUTLS: level 7:
  WRITE: wrote 69 bytes, 0 bytes left.
13:41:16.768916 15113 (net.c:1257):GNUTLS: level 2:
  ASSERT: ext_session_ticket.c:710
13:41:16.768924 15113 (net.c:1199):in tds_pull_func
13:41:16.768931 15113 (net.c:741):Sending packet
0000 12 01 00 de 00 00 00 00-16 03 03 00 86 10 00 00 |........ ........|
0010 82 00 80 22 31 e0 8f 41-05 8c ed 9a f2 8a e4 69 |..."1..A .......i|
0020 04 f8 ec 42 c8 6e ff e1-a6 78 55 8a 16 bb 3f 9c |...B.n.. .xU...?.|
0030 78 a1 9e 21 a1 28 36 93-00 68 53 ec c5 40 1d 5a |x..!.(6. .hS..@.Z|
0040 5c 75 4b 9d 82 a8 57 01-ff 73 c7 c2 d7 8e ef 15 |\uK...W. .s......|
0050 68 33 2e 51 07 72 28 d0-15 fd da 4b fa 28 27 06 |h3.Q.r(. ...K.('.|
0060 a4 a8 ee 72 a9 6b e9 35-df e2 24 6e 1d 14 49 d5 |...r.k.5 ..$n..I.|
0070 37 10 54 d1 47 1d 3e d1-a7 ef 82 17 18 8e c0 fa |7.T.G.>. ........|
0080 93 73 3f ce 88 52 3f a7-6c 0a 4d c0 92 62 ba 16 |.s?..R?. l.M..b..|
0090 5f 20 ff 14 03 03 00 01-01 16 03 03 00 40 ad 0b |_ ...... .....@..|
00a0 17 1d 24 6d a0 3f a7 47-84 02 8f 50 b1 7d a2 b6 |..$m.?.G ...P.}..|
00b0 69 0c 99 0f 5c f4 b3 64-e7 26 a8 45 e3 ba 3a 4a |i...\..d .&.E..:J|
00c0 31 0c f8 f0 e0 12 20 86-4d d9 76 fd 30 6a 10 67 |1..... . M.v.0j.g|
00d0 67 e8 a3 09 a5 1c b8 6e-7f c1 ab e6 1a 7e       |g......n .....~|

13:41:16.769060 15113 (net.c:1212):have 0
13:41:16.769076 15113 (net.c:1216):before read
13:41:16.772493 15113 (net.c:555):Received header
0000 12 01 00 53 00 00 00 00-                        |...S....|

13:41:16.772537 15113 (net.c:609):Received packet
0000 12 01 00 53 00 00 00 00-14 03 03 00 01 01 16 03 |...S.... ........|
0010 03 00 40 01 7e 33 de c5-3d ac 71 12 c0 c1 4d 92 |..@.~3.. =.q...M.|
0020 10 5d 9b 73 58 d9 e0 fa-7f 15 05 1f 5e 9e bb 88 |.].sX... ....^...|
0030 7e b9 84 5d 42 ff 33 fd-e1 33 ad 79 53 81 2d e5 |~..]B.3. .3.yS.-.|
0040 25 73 ce 4b ef e8 1c 7b-ee 73 8f 0d 6e 64 1f dc |%s.K...{ .s..nd..|
0050 2a f0 5e               -                        |*.^|

13:41:16.772594 15113 (net.c:1219):after read
13:41:16.772602 15113 (net.c:1212):have 75
13:41:16.772609 15113 (net.c:1223):read 5 bytes
13:41:16.772619 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 5 bytes from 0x7f2eb4e30200
13:41:16.772627 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 5 bytes from 0x7f2eb4e30200
13:41:16.772635 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 0 bytes into buffer. Adding 5 bytes.
13:41:16.772643 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 5 bytes
13:41:16.772652 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Expected Packet[1] Change Cipher Spec(20) with length: 1
13:41:16.772661 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Received Packet[1] Change Cipher Spec(20) with length: 1
13:41:16.772668 15113 (net.c:1199):in tds_pull_func
13:41:16.772675 15113 (net.c:1212):have 70
13:41:16.772682 15113 (net.c:1223):read 1 bytes
13:41:16.772689 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 1 bytes from 0x7f2eb4e30200
13:41:16.772697 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 1 bytes from 0x7f2eb4e30200
13:41:16.772706 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 5 bytes into buffer. Adding 1 bytes.
13:41:16.772714 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 6 bytes
13:41:16.772724 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: ChangeCipherSpec Packet was received
13:41:16.772735 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: Cipher Suite: DHE_RSA_AES_256_CBC_SHA1
13:41:16.772743 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Start of epoch cleanup
13:41:16.772751 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Epoch #0 freed
13:41:16.772760 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: End of epoch cleanup
13:41:16.772768 15113 (net.c:1199):in tds_pull_func
13:41:16.772775 15113 (net.c:1212):have 69
13:41:16.772781 15113 (net.c:1223):read 5 bytes
13:41:16.772789 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 5 bytes from 0x7f2eb4e30200
13:41:16.772797 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 5 bytes from 0x7f2eb4e30200
13:41:16.772805 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 0 bytes into buffer. Adding 5 bytes.
13:41:16.772813 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 5 bytes
13:41:16.772821 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Expected Packet[0] Handshake(22) with length: 1
13:41:16.772830 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Received Packet[0] Handshake(22) with length: 64
13:41:16.772838 15113 (net.c:1199):in tds_pull_func
13:41:16.772844 15113 (net.c:1212):have 64
13:41:16.772851 15113 (net.c:1223):read 64 bytes
13:41:16.772860 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 64 bytes from 0x7f2eb4e30200
13:41:16.772868 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 64 bytes from 0x7f2eb4e30200
13:41:16.772875 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 5 bytes into buffer. Adding 64 bytes.
13:41:16.772883 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 69 bytes
13:41:16.772955 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Decrypted Packet[0] Handshake(22) with length: 16
13:41:16.772977 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 16 bytes of Data(22)
13:41:16.772987 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 1 bytes of Data(22)
13:41:16.772996 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 3 bytes of Data(22)
13:41:16.773005 15113 (net.c:1257):GNUTLS: level 3:
  HSK[0x7f2eb4ec50d0]: FINISHED was received [16 bytes]
13:41:16.773032 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC][HD]: Read 12 bytes of Data(22)
13:41:16.773043 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Peeked 0 bytes of Data
13:41:16.773051 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Emptied buffer
13:41:16.773059 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 4 bytes of Data
13:41:16.773067 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Inserted 12 bytes of Data
13:41:16.773157 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Cleared Data from buffer
13:41:16.773169 15113 (net.c:1257):GNUTLS: level 6:
  BUF[HSK]: Cleared Data from buffer
13:41:16.773177 15113 (net.c:1370):handshake succeeded!!
13:41:16.773184 15113 (login.c:782):quietly sending TDS 7+ login packet
13:41:16.773299 15113 (token.c:328):tds_process_login_tokens()
13:41:16.773316 15113 (net.c:1199):in tds_pull_func
13:41:16.774696 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 5 bytes from 0x7f2eb4e30200
13:41:16.774715 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 5 bytes from 0x7f2eb4e30200
13:41:16.774724 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 0 bytes into buffer. Adding 5 bytes.
13:41:16.774732 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 5 bytes
13:41:16.774742 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Expected Packet[1] Application Data(23) with length: 8
13:41:16.774750 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Received Packet[1] Application Data(23) with length: 400
13:41:16.774758 15113 (net.c:1199):in tds_pull_func
13:41:16.774770 15113 (net.c:1257):GNUTLS: level 7:
  READ: Got 400 bytes from 0x7f2eb4e30200
13:41:16.774779 15113 (net.c:1257):GNUTLS: level 7:
  READ: read 400 bytes from 0x7f2eb4e30200
13:41:16.774788 15113 (net.c:1257):GNUTLS: level 7:
  RB: Have 5 bytes into buffer. Adding 400 bytes.
13:41:16.774795 15113 (net.c:1257):GNUTLS: level 7:
  RB: Requested 405 bytes
13:41:16.774868 15113 (net.c:1257):GNUTLS: level 4:
  REC[0x7f2eb4ec50d0]: Decrypted Packet[1] Application Data(23) with length: 357
13:41:16.774880 15113 (net.c:1257):GNUTLS: level 6:
  BUF[REC]: Inserted 357 bytes of Data(23)
13:41:16.774890 15113 (net.c:1257):GNUTLS: level 6:
  BUFFER[REC][AD]: Read 8 bytes of Data(23)
13:41:16.774897 15113 (net.c:555):Received header
0000 04 01 01 65 00 63 01 00-                        |...e.c..|

... and so on to the queries

Archive powered by MHonArc 2.6.24.

Top of Page