FreeTDS Development Group

[Stefan Ille <illes AT designa.de>]

Good Morning!

Frediano Ziglio wrote:

> Mmmm... this is a workaround, not the solution,
>
> $ cat include/tds.h  | grep 0x38
>         SYBINT4 = 56,           /* 0x38 */
>
> perhaps this means that mssql2k5 do not accept not-nullable types for
> parameters? Perhaps is an output parameter? Can you post a FreeTDS dump?
>
> freddy77

Enclosed the FreeTDS dump showing the problem with mssql2k5.
Afterwards I enclosed for comparison the dump with TDS version set to 
7.0 which works.

Regards
Stefan


08:53:55.628332 Starting log file for FreeTDS 0.63
        on 2006-03-01 08:53:55 with debug level 99.
08:53:55.647624 names for ISO-8859-1: ISO-8859-1
08:53:55.648042 names for UTF-8: UTF-8
08:53:55.648097 names for UCS-2LE: UCS-2LE
08:53:55.648126 names for UCS-2BE: UCS-2BE
08:53:55.648154 iconv to convert client-side data to the "ISO-8859-1" 
character set
08:53:55.650759 tds_iconv_info_init: converting "ISO-8859-1"->"UCS-2LE"
08:53:55.651508 tds_iconv_info_init: converting "ISO-8859-1"->"UCS-2LE"
08:53:55.652088 Connecting to 127.0.0.1 port 1433, TDS 8.0.
08:53:55.654260 tds_put_string converting 7 bytes of "quadras"
08:53:55.654424 tds_put_string wrote 14 bytes
08:53:55.654473 tds_put_string converting 3 bytes of "lcc"
08:53:55.654510 tds_put_string wrote 6 bytes
08:53:55.654560 tds_put_string converting 10 bytes of "LCC__H5_C5"
08:53:55.654595 tds_put_string wrote 20 bytes
08:53:55.654624 tds_put_string converting 10 bytes of "ABACUS-2K5"
08:53:55.654656 tds_put_string wrote 20 bytes
08:53:55.654684 tds_put_string converting 10 bytes of "DB-Library"
08:53:55.654730 tds_put_string wrote 20 bytes
08:53:55.654761 tds_put_string converting 10 bytes of "us_english"
08:53:55.654796 tds_put_string wrote 20 bytes
08:53:55.654823 tds_put_string wrote 0 bytes
08:53:55.655065 tds_process_login_tokens()
08:53:55.709657 Received header
0000 04 01 01 81 00 34 01 00-                        |.....4..|

08:53:55.709882 Received packet
0000 e3 1b 00 01 06 6d 00 61-00 73 00 74 00 65 00 72 |.....m.a .s.t.e.r|
0010 00 06 6d 00 61 00 73 00-74 00 65 00 72 00 ab 6a |..m.a.s. t.e.r..j|
0020 00 45 16 00 00 02 00 25-00 43 00 68 00 61 00 6e |.E.....% .C.h.a.n|
0030 00 67 00 65 00 64 00 20-00 64 00 61 00 74 00 61 |.g.e.d.  .d.a.t.a|
0040 00 62 00 61 00 73 00 65-00 20 00 63 00 6f 00 6e |.b.a.s.e . .c.o.n|
0050 00 74 00 65 00 78 00 74-00 20 00 74 00 6f 00 20 |.t.e.x.t . .t.o. |
0060 00 27 00 6d 00 61 00 73-00 74 00 65 00 72 00 27 |.'.m.a.s .t.e.r.'|
0070 00 2e 00 0a 41 00 42 00-41 00 43 00 55 00 53 00 |....A.B. A.C.U.S.|
0080 2d 00 32 00 4b 00 35 00-00 01 00 e3 08 00 07 05 |-.2.K.5. ........|
0090 09 04 d0 00 00 00 e3 17-00 02 0a 75 00 73 00 5f |........ ...u.s._|
00a0 00 65 00 6e 00 67 00 6c-00 69 00 73 00 68 00 00 |.e.n.g.l .i.s.h..|
00b0 ab 6e 00 47 16 00 00 01-00 27 00 43 00 68 00 61 |.n.G.... .'.C.h.a|
00c0 00 6e 00 67 00 65 00 64-00 20 00 6c 00 61 00 6e |.n.g.e.d . .l.a.n|
00d0 00 67 00 75 00 61 00 67-00 65 00 20 00 73 00 65 |.g.u.a.g .e. .s.e|
00e0 00 74 00 74 00 69 00 6e-00 67 00 20 00 74 00 6f |.t.t.i.n .g. .t.o|
00f0 00 20 00 75 00 73 00 5f-00 65 00 6e 00 67 00 6c |. .u.s._ .e.n.g.l|
0100 00 69 00 73 00 68 00 2e-00 0a 41 00 42 00 41 00 |.i.s.h.. ..A.B.A.|
0110 43 00 55 00 53 00 2d 00-32 00 4b 00 35 00 00 01 |C.U.S.-. 2.K.5...|
0120 00 ad 36 00 01 71 00 00-01 16 4d 00 69 00 63 00 |..6..q.. ..M.i.c.|
0130 72 00 6f 00 73 00 6f 00-66 00 74 00 20 00 53 00 |r.o.s.o. f.t. .S.|
0140 51 00 4c 00 20 00 53 00-65 00 72 00 76 00 65 00 |Q.L. .S. e.r.v.e.|
0150 72 00 00 00 00 00 09 00-05 77 e3 13 00 04 04 34 |r....... .w.....4|
0160 00 30 00 39 00 36 00 04-34 00 30 00 39 00 36 00 |.0.9.6.. 4.0.9.6.|
0170 fd 00 00 00 00 00 00 00-00                      |........ .|

08:53:55.711493 looking for login token, got  e3(ENVCHANGE)
08:53:55.711577 tds_process_default_tokens() marker is e3(ENVCHANGE)
08:53:55.711633 tds_get_string: reading 12 from wire to give 6 to client.
08:53:55.711736 tds_get_string: reading 12 from wire to give 6 to client.
08:53:55.711816 looking for login token, got  ab(INFO)
08:53:55.711855 tds_process_default_tokens() marker is ab(INFO)
08:53:55.711906 tds_get_string: reading 74 from wire to give 37 to client.
08:53:55.711949 tds_get_string: reading 20 from wire to give 10 to client.
08:53:55.712105 looking for login token, got  e3(ENVCHANGE)
08:53:55.712154 tds_process_default_tokens() marker is e3(ENVCHANGE)
08:53:55.717588 tds_iconv_info_init: converting "ISO-8859-1"->"CP1252"
08:53:55.717743 looking for login token, got  e3(ENVCHANGE)
08:53:55.717790 tds_process_default_tokens() marker is e3(ENVCHANGE)
08:53:55.717838 tds_get_string: reading 20 from wire to give 10 to client.
08:53:55.717956 looking for login token, got  ab(INFO)
08:53:55.718002 tds_process_default_tokens() marker is ab(INFO)
08:53:55.718053 tds_get_string: reading 78 from wire to give 39 to client.
08:53:55.718098 tds_get_string: reading 20 from wire to give 10 to client.
08:53:55.718258 looking for login token, got  ad(LOGINACK)
08:53:55.718319 tds_get_string: reading 44 from wire to give 22 to client.
08:53:55.718376 looking for login token, got  e3(ENVCHANGE)
08:53:55.718408 tds_process_default_tokens() marker is e3(ENVCHANGE)
08:53:55.718445 tds_get_string: reading 8 from wire to give 4 to client.
08:53:55.718481 tds_get_string: reading 8 from wire to give 4 to client.
08:53:55.718525 increasing block size from 4096 to 4096
08:53:55.718628 looking for login token, got  fd(DONE)
08:53:55.718672 tds_process_default_tokens() marker is fd(DONE)
08:53:55.718703 tds_process_end: more_results = 0
                was_cancelled = 0
                error = 0
                done_count_valid = 0
08:53:55.718732 tds_process_end() state set to TDS_IDLE
08:53:55.718772 leaving tds_process_login_tokens() returning 1
08:53:55.718895 dbuse()
08:53:55.718994 dbcmd() bufsz = 0
08:53:55.719064 in dbsqlexec()
08:53:55.719096 in dbsqlsend()
08:53:55.719133 tds_free_all_results()
08:53:55.719178 tds_put_string converting 13 bytes of "use AbacusDbs"
08:53:55.719223 tds_put_string wrote 26 bytes
08:53:55.719258 Sending packet
0000 01 01 00 22 00 00 01 00-75 00 73 00 65 00 20 00 |...".... u.s.e. .|
0010 41 00 62 00 61 00 63 00-75 00 73 00 44 00 62 00 |A.b.a.c. u.s.D.b.|
0020 73 00                  -                        |s.|

08:53:55.719647 in dbsqlok()
08:53:55.725095 Received header
0000 04 01 00 b3 00 34 01 00-                        |.....4..|

08:53:55.725252 Received packet
0000 e3 21 00 01 09 41 00 62-00 61 00 63 00 75 00 73 |.!...A.b .a.c.u.s|
0010 00 44 00 62 00 73 00 06-6d 00 61 00 73 00 74 00 |.D.b.s.. m.a.s.t.|
0020 65 00 72 00 ab 70 00 45-16 00 00 01 00 28 00 43 |e.r..p.E .....(.C|
0030 00 68 00 61 00 6e 00 67-00 65 00 64 00 20 00 64 |.h.a.n.g .e.d. .d|
0040 00 61 00 74 00 61 00 62-00 61 00 73 00 65 00 20 |.a.t.a.b .a.s.e. |
0050 00 63 00 6f 00 6e 00 74-00 65 00 78 00 74 00 20 |.c.o.n.t .e.x.t. |
0060 00 74 00 6f 00 20 00 27-00 41 00 62 00 61 00 63 |.t.o. .' .A.b.a.c|
0070 00 75 00 73 00 44 00 62-00 73 00 27 00 2e 00 0a |.u.s.D.b .s.'....|
0080 41 00 42 00 41 00 43 00-55 00 53 00 2d 00 32 00 |A.B.A.C. U.S.-.2.|
0090 4b 00 35 00 00 01 00 e3-08 00 07 05 09 04 d0 00 |K.5..... ........|
00a0 00 00 fd 00 00 e2 00 00-00 00 00                |........ ...|

08:53:55.725996 dbsqlok() marker is e3
08:53:55.726068 processing result tokens.  marker is  e3(ENVCHANGE)
08:53:55.726114 tds_process_default_tokens() marker is e3(ENVCHANGE)
08:53:55.726163 tds_get_string: reading 18 from wire to give 9 to client.
08:53:55.726219 tds_get_string: reading 12 from wire to give 6 to client.
08:53:55.726287 processing result tokens.  marker is  ab(INFO)
08:53:55.726323 tds_process_default_tokens() marker is ab(INFO)
08:53:55.726373 tds_get_string: reading 80 from wire to give 40 to client.
08:53:55.726427 tds_get_string: reading 20 from wire to give 10 to client.
08:53:55.727917 processing result tokens.  marker is  e3(ENVCHANGE)
08:53:55.728026 tds_process_default_tokens() marker is e3(ENVCHANGE)
08:53:55.728127 processing result tokens.  marker is  fd(DONE)
08:53:55.728172 tds_process_end: more_results = 0
                was_cancelled = 0
                error = 0
                done_count_valid = 0
08:53:55.728208 tds_process_end() state set to TDS_IDLE
08:53:55.728242 dbsqlok() end status was success
08:53:55.728274 dbresults()
08:53:55.728310 tds_process_row_tokens() state is COMPLETED
08:53:55.736120 dbrpcinit() added rpcname "procOctClientStatusTick"
08:53:55.736318 dbrpcparam() added parameter "@Client"
08:53:55.736411 dbrpcparam() added parameter "@Status"
08:53:55.736484 dbrpcparam() added parameter "@SequenceNumber"
08:53:55.736593 dbrpcsend()
08:53:55.736633 parm_info_alloc(): parameter null-ness = 0
08:53:55.736710 param_row_alloc, size = 4, offset = 4, row_size = 8
08:53:55.736747 param_row_alloc(): doing data from value
08:53:55.736820 param_row_alloc(): copying 4 bytes of data to parameter #0
08:53:55.736865 parm_info_alloc(): parameter null-ness = 0
08:53:55.736914 param_row_alloc, size = 4, offset = 8, row_size = 12
08:53:55.736943 param_row_alloc(): doing data from value
08:53:55.736967 param_row_alloc(): copying 4 bytes of data to parameter #1
08:53:55.737000 parm_info_alloc(): parameter null-ness = 0
08:53:55.737046 param_row_alloc, size = 4, offset = 12, row_size = 16
08:53:55.737072 param_row_alloc(): doing data from value
08:53:55.737095 param_row_alloc(): copying 4 bytes of data to parameter #2
08:53:55.737123 tds_free_all_results()
08:53:55.737172 tds_put_data_info putting param_name
08:53:55.737207 tds_put_data_info putting status
08:53:55.737237 tds_put_data: is_null = 0, colsize = 4
08:53:55.737264 tds_put_data: not null param varint_size = 0
08:53:55.737291 tds_put_data_info putting param_name
08:53:55.737319 tds_put_data_info putting status
08:53:55.737344 tds_put_data: is_null = 0, colsize = 4
08:53:55.737377 tds_put_data: not null param varint_size = 0
08:53:55.737405 tds_put_data_info putting param_name
08:53:55.737435 tds_put_data_info putting status
08:53:55.737460 tds_put_data: is_null = 0, colsize = 4
08:53:55.737484 tds_put_data: not null param varint_size = 0
08:53:55.737510 Sending packet
0000 03 01 00 89 00 00 01 00-17 00 70 00 72 00 6f 00 |........ ..p.r.o.|
0010 63 00 4f 00 63 00 74 00-43 00 6c 00 69 00 65 00 |c.O.c.t. C.l.i.e.|
0020 6e 00 74 00 53 00 74 00-61 00 74 00 75 00 73 00 |n.t.S.t. a.t.u.s.|
0030 54 00 69 00 63 00 6b 00-00 00 07 40 00 43 00 6c |T.i.c.k. ...@.C.l|
0040 00 69 00 65 00 6e 00 74-00 00 38 05 00 00 00 07 |.i.e.n.t ..8.....|
0050 40 00 53 00 74 00 61 00-74 00 75 00 73 00 00 38 |@.S.t.a. t.u.s..8|
0060 01 00 00 00 0f 40 00 53-00 65 00 71 00 75 00 65 |.....@.S .e.q.u.e|
0070 00 6e 00 63 00 65 00 4e-00 75 00 6d 00 62 00 65 |.n.c.e.N .u.m.b.e|
0080 00 72 00 00 38 00 00 00-00                      |.r..8... .|

08:53:55.738419 in dbsqlok()
08:53:55.801996 Received header
0000 04 01 01 5c 00 34 01 00-                        |...\.4..|

08:53:55.802189 Received packet
0000 aa 48 01 49 1f 00 00 01-10 94 00 54 00 68 00 65 |.H.I.... ...T.h.e|
0010 00 20 00 69 00 6e 00 63-00 6f 00 6d 00 69 00 6e |. .i.n.c .o.m.i.n|
0020 00 67 00 20 00 74 00 61-00 62 00 75 00 6c 00 61 |.g. .t.a .b.u.l.a|
0030 00 72 00 20 00 64 00 61-00 74 00 61 00 20 00 73 |.r. .d.a .t.a. .s|
0040 00 74 00 72 00 65 00 61-00 6d 00 20 00 28 00 54 |.t.r.e.a .m. .(.T|
0050 00 44 00 53 00 29 00 20-00 72 00 65 00 6d 00 6f |.D.S.).  .r.e.m.o|
0060 00 74 00 65 00 20 00 70-00 72 00 6f 00 63 00 65 |.t.e. .p .r.o.c.e|
0070 00 64 00 75 00 72 00 65-00 20 00 63 00 61 00 6c |.d.u.r.e . .c.a.l|
0080 00 6c 00 20 00 28 00 52-00 50 00 43 00 29 00 20 |.l. .(.R .P.C.). |
0090 00 70 00 72 00 6f 00 74-00 6f 00 63 00 6f 00 6c |.p.r.o.t .o.c.o.l|
00a0 00 20 00 73 00 74 00 72-00 65 00 61 00 6d 00 20 |. .s.t.r .e.a.m. |
00b0 00 69 00 73 00 20 00 69-00 6e 00 63 00 6f 00 72 |.i.s. .i .n.c.o.r|
00c0 00 72 00 65 00 63 00 74-00 2e 00 20 00 50 00 61 |.r.e.c.t ... .P.a|
00d0 00 72 00 61 00 6d 00 65-00 74 00 65 00 72 00 20 |.r.a.m.e .t.e.r. |
00e0 00 31 00 20 00 28 00 22-00 40 00 43 00 6c 00 69 |.1. .(." .@.C.l.i|
00f0 00 65 00 6e 00 74 00 22-00 29 00 3a 00 20 00 44 |.e.n.t." .).:. .D|
0100 00 61 00 74 00 61 00 20-00 74 00 79 00 70 00 65 |.a.t.a.  .t.y.p.e|
0110 00 20 00 30 00 78 00 33-00 38 00 20 00 69 00 73 |. .0.x.3 .8. .i.s|
0120 00 20 00 75 00 6e 00 6b-00 6e 00 6f 00 77 00 6e |. .u.n.k .n.o.w.n|
0130 00 2e 00 0a 41 00 42 00-41 00 43 00 55 00 53 00 |....A.B. A.C.U.S.|
0140 2d 00 32 00 4b 00 35 00-00 01 00 fd 02 00 fd 00 |-.2.K.5. ........|
0150 00 00 00 00            -                        |....|

08:53:55.803811 dbsqlok() marker is aa
08:53:55.803903 processing result tokens.  marker is  aa(ERROR)
08:53:55.803948 tds_process_default_tokens() marker is aa(ERROR)
08:53:55.804031 tds_get_string: reading 296 from wire to give 148 to client.
08:53:55.804113 tds_get_string: reading 20 from wire to give 10 to client.
08:53:55.805232 tds_client_msg: #20018: "General SQL Server error: Check 
messages from the SQL Server.".  Connection state is now 0.
08:53:55.805414 processing result tokens.  marker is  fd(DONE)
08:53:55.805471 tds_process_end: more_results = 0
                was_cancelled = 0
                error = 1
                done_count_valid = 0
08:53:55.805508 tds_process_end() state set to TDS_IDLE
08:53:55.805543 dbsqlok() end status was error
08:53:55.805628 dbresults()
08:53:55.805697 dbnumrets() finds 0 columns
08:53:55.805730 tds_process_trailing_tokens()


******************************************************************************
Here we have the dump with TDS version set to 7.0 which works.
******************************************************************************

09:04:00.858212 Starting log file for FreeTDS 0.63
        on 2006-03-01 09:04:00 with debug level 99.
09:04:00.877143 names for ISO-8859-1: ISO-8859-1
09:04:00.877554 names for UTF-8: UTF-8
09:04:00.877613 names for UCS-2LE: UCS-2LE
09:04:00.877641 names for UCS-2BE: UCS-2BE
09:04:00.877670 iconv to convert client-side data to the "ISO-8859-1" 
character set
09:04:00.880267 tds_iconv_info_init: converting "ISO-8859-1"->"UCS-2LE"
09:04:00.880727 tds_iconv_info_init: converting "ISO-8859-1"->"UCS-2LE"
09:04:00.881031 Connecting to 127.0.0.1 port 1433, TDS 7.0.
09:04:00.886314 tds_put_string converting 7 bytes of "quadras"
09:04:00.886482 tds_put_string wrote 14 bytes
09:04:00.886534 tds_put_string converting 3 bytes of "lcc"
09:04:00.886572 tds_put_string wrote 6 bytes
09:04:00.886622 tds_put_string converting 10 bytes of "LCC__H5_C5"
09:04:00.886658 tds_put_string wrote 20 bytes
09:04:00.886687 tds_put_string converting 10 bytes of "ABACUS-2K5"
09:04:00.886719 tds_put_string wrote 20 bytes
09:04:00.886746 tds_put_string converting 10 bytes of "DB-Library"
09:04:00.886793 tds_put_string wrote 20 bytes
09:04:00.886823 tds_put_string converting 10 bytes of "us_english"
09:04:00.886852 tds_put_string wrote 20 bytes
09:04:00.886878 tds_put_string wrote 0 bytes
09:04:00.887124 tds_process_login_tokens()
09:04:00.937893 Received header
0000 04 01 01 a8 00 3c 01 00-                        |.....<..|

09:04:00.938111 Received packet
0000 e3 1b 00 01 06 6d 00 61-00 73 00 74 00 65 00 72 |.....m.a .s.t.e.r|
0010 00 06 6d 00 61 00 73 00-74 00 65 00 72 00 ab 6a |..m.a.s. t.e.r..j|
0020 00 45 16 00 00 02 00 25-00 43 00 68 00 61 00 6e |.E.....% .C.h.a.n|
0030 00 67 00 65 00 64 00 20-00 64 00 61 00 74 00 61 |.g.e.d.  .d.a.t.a|
0040 00 62 00 61 00 73 00 65-00 20 00 63 00 6f 00 6e |.b.a.s.e . .c.o.n|
0050 00 74 00 65 00 78 00 74-00 20 00 74 00 6f 00 20 |.t.e.x.t . .t.o. |
0060 00 27 00 6d 00 61 00 73-00 74 00 65 00 72 00 27 |.'.m.a.s .t.e.r.'|
0070 00 2e 00 0a 41 00 42 00-41 00 43 00 55 00 53 00 |....A.B. A.C.U.S.|
0080 2d 00 32 00 4b 00 35 00-00 01 00 e3 17 00 02 0a |-.2.K.5. ........|
0090 75 00 73 00 5f 00 65 00-6e 00 67 00 6c 00 69 00 |u.s._.e. n.g.l.i.|
00a0 73 00 68 00 00 ab 6e 00-47 16 00 00 01 00 27 00 |s.h...n. G.....'.|
00b0 43 00 68 00 61 00 6e 00-67 00 65 00 64 00 20 00 |C.h.a.n. g.e.d. .|
00c0 6c 00 61 00 6e 00 67 00-75 00 61 00 67 00 65 00 |l.a.n.g. u.a.g.e.|
00d0 20 00 73 00 65 00 74 00-74 00 69 00 6e 00 67 00 | .s.e.t. t.i.n.g.|
00e0 20 00 74 00 6f 00 20 00-75 00 73 00 5f 00 65 00 | .t.o. . u.s._.e.|
00f0 6e 00 67 00 6c 00 69 00-73 00 68 00 2e 00 0a 41 |n.g.l.i. s.h....A|
0100 00 42 00 41 00 43 00 55-00 53 00 2d 00 32 00 4b |.B.A.C.U .S.-.2.K|
0110 00 35 00 00 01 00 e3 0f-00 03 05 69 00 73 00 6f |.5...... ...i.s.o|
0120 00 5f 00 31 00 01 00 00-e3 0b 00 05 04 31 00 30 |._.1.... .....1.0|
0130 00 33 00 33 00 00 e3 0f-00 06 06 31 00 39 00 36 |.3.3.... ...1.9.6|
0140 00 36 00 30 00 39 00 00-ad 36 00 01 07 00 00 00 |.6.0.9.. .6......|
0150 16 4d 00 69 00 63 00 72-00 6f 00 73 00 6f 00 66 |.M.i.c.r .o.s.o.f|
0160 00 74 00 20 00 53 00 51-00 4c 00 20 00 53 00 65 |.t. .S.Q .L. .S.e|
0170 00 72 00 76 00 65 00 72-00 00 00 00 00 09 00 05 |.r.v.e.r ........|
0180 77 e3 13 00 04 04 34 00-30 00 39 00 36 00 04 34 |w.....4. 0.9.6..4|
0190 00 30 00 39 00 36 00 fd-00 00 00 00 00 00 00 00 |.0.9.6.. ........|

09:04:00.939871 looking for login token, got  e3(ENVCHANGE)
09:04:00.939953 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.940012 tds_get_string: reading 12 from wire to give 6 to client.
09:04:00.940115 tds_get_string: reading 12 from wire to give 6 to client.
09:04:00.940198 looking for login token, got  ab(INFO)
09:04:00.940238 tds_process_default_tokens() marker is ab(INFO)
09:04:00.940287 tds_get_string: reading 74 from wire to give 37 to client.
09:04:00.940331 tds_get_string: reading 20 from wire to give 10 to client.
09:04:00.940485 looking for login token, got  e3(ENVCHANGE)
09:04:00.940529 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.940569 tds_get_string: reading 20 from wire to give 10 to client.
09:04:00.940644 looking for login token, got  ab(INFO)
09:04:00.940708 tds_process_default_tokens() marker is ab(INFO)
09:04:00.940758 tds_get_string: reading 78 from wire to give 39 to client.
09:04:00.940801 tds_get_string: reading 20 from wire to give 10 to client.
09:04:00.941281 looking for login token, got  e3(ENVCHANGE)
09:04:00.941361 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.941413 tds_get_string: reading 10 from wire to give 5 to client.
09:04:00.941465 tds_get_string: reading 2 from wire to give 1 to client.
09:04:00.941678 looking for login token, got  e3(ENVCHANGE)
09:04:00.941724 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.941764 tds_get_string: reading 8 from wire to give 4 to client.
09:04:00.941818 looking for login token, got  e3(ENVCHANGE)
09:04:00.941851 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.941884 tds_get_string: reading 12 from wire to give 6 to client.
09:04:00.941934 looking for login token, got  ad(LOGINACK)
09:04:00.942017 tds_get_string: reading 44 from wire to give 22 to client.
09:04:00.942095 looking for login token, got  e3(ENVCHANGE)
09:04:00.942130 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.942167 tds_get_string: reading 8 from wire to give 4 to client.
09:04:00.942203 tds_get_string: reading 8 from wire to give 4 to client.
09:04:00.942245 increasing block size from 4096 to 4096
09:04:00.942309 looking for login token, got  fd(DONE)
09:04:00.942340 tds_process_default_tokens() marker is fd(DONE)
09:04:00.942369 tds_process_end: more_results = 0
                was_cancelled = 0
                error = 0
                done_count_valid = 0
09:04:00.942397 tds_process_end() state set to TDS_IDLE
09:04:00.942424 leaving tds_process_login_tokens() returning 1
09:04:00.942538 dbuse()
09:04:00.942635 dbcmd() bufsz = 0
09:04:00.942701 in dbsqlexec()
09:04:00.942733 in dbsqlsend()
09:04:00.942768 tds_free_all_results()
09:04:00.942812 tds_put_string converting 13 bytes of "use AbacusDbs"
09:04:00.942860 tds_put_string wrote 26 bytes
09:04:00.942895 Sending packet
0000 01 01 00 22 00 00 01 00-75 00 73 00 65 00 20 00 |...".... u.s.e. .|
0010 41 00 62 00 61 00 63 00-75 00 73 00 44 00 62 00 |A.b.a.c. u.s.D.b.|
0020 73 00                  -                        |s.|

09:04:00.943285 in dbsqlok()
09:04:00.948684 Received header
0000 04 01 00 a8 00 3c 01 00-                        |.....<..|

09:04:00.948834 Received packet
0000 e3 21 00 01 09 41 00 62-00 61 00 63 00 75 00 73 |.!...A.b .a.c.u.s|
0010 00 44 00 62 00 73 00 06-6d 00 61 00 73 00 74 00 |.D.b.s.. m.a.s.t.|
0020 65 00 72 00 ab 70 00 45-16 00 00 01 00 28 00 43 |e.r..p.E .....(.C|
0030 00 68 00 61 00 6e 00 67-00 65 00 64 00 20 00 64 |.h.a.n.g .e.d. .d|
0040 00 61 00 74 00 61 00 62-00 61 00 73 00 65 00 20 |.a.t.a.b .a.s.e. |
0050 00 63 00 6f 00 6e 00 74-00 65 00 78 00 74 00 20 |.c.o.n.t .e.x.t. |
0060 00 74 00 6f 00 20 00 27-00 41 00 62 00 61 00 63 |.t.o. .' .A.b.a.c|
0070 00 75 00 73 00 44 00 62-00 73 00 27 00 2e 00 0a |.u.s.D.b .s.'....|
0080 41 00 42 00 41 00 43 00-55 00 53 00 2d 00 32 00 |A.B.A.C. U.S.-.2.|
0090 4b 00 35 00 00 01 00 fd-00 00 e2 00 00 00 00 00 |K.5..... ........|

09:04:00.949524 dbsqlok() marker is e3
09:04:00.949598 processing result tokens.  marker is  e3(ENVCHANGE)
09:04:00.949644 tds_process_default_tokens() marker is e3(ENVCHANGE)
09:04:00.949694 tds_get_string: reading 18 from wire to give 9 to client.
09:04:00.949751 tds_get_string: reading 12 from wire to give 6 to client.
09:04:00.949821 processing result tokens.  marker is  ab(INFO)
09:04:00.949857 tds_process_default_tokens() marker is ab(INFO)
09:04:00.949911 tds_get_string: reading 80 from wire to give 40 to client.
09:04:00.949956 tds_get_string: reading 20 from wire to give 10 to client.
09:04:00.951980 processing result tokens.  marker is  fd(DONE)
09:04:00.952087 tds_process_end: more_results = 0
                was_cancelled = 0
                error = 0
                done_count_valid = 0
09:04:00.952127 tds_process_end() state set to TDS_IDLE
09:04:00.952162 dbsqlok() end status was success
09:04:00.952196 dbresults()
09:04:00.952236 tds_process_row_tokens() state is COMPLETED
09:04:00.958691 dbrpcinit() added rpcname "procOctClientStatusTick"
09:04:00.958893 dbrpcparam() added parameter "@Client"
09:04:00.958988 dbrpcparam() added parameter "@Status"
09:04:00.959060 dbrpcparam() added parameter "@SequenceNumber"
09:04:00.959168 dbrpcsend()
09:04:00.959251 parm_info_alloc(): parameter null-ness = 0
09:04:00.959339 param_row_alloc, size = 4, offset = 4, row_size = 8
09:04:00.959377 param_row_alloc(): doing data from value
09:04:00.959403 param_row_alloc(): copying 4 bytes of data to parameter #0
09:04:00.959438 parm_info_alloc(): parameter null-ness = 0
09:04:00.959486 param_row_alloc, size = 4, offset = 8, row_size = 12
09:04:00.959514 param_row_alloc(): doing data from value
09:04:00.959537 param_row_alloc(): copying 4 bytes of data to parameter #1
09:04:00.959565 parm_info_alloc(): parameter null-ness = 0
09:04:00.959609 param_row_alloc, size = 4, offset = 12, row_size = 16
09:04:00.959636 param_row_alloc(): doing data from value
09:04:00.959658 param_row_alloc(): copying 4 bytes of data to parameter #2
09:04:00.959706 tds_free_all_results()
09:04:00.959761 tds_put_data_info putting param_name
09:04:00.959798 tds_put_data_info putting status
09:04:00.959826 tds_put_data: is_null = 0, colsize = 4
09:04:00.959853 tds_put_data: not null param varint_size = 0
09:04:00.959879 tds_put_data_info putting param_name
09:04:00.959908 tds_put_data_info putting status
09:04:00.959932 tds_put_data: is_null = 0, colsize = 4
09:04:00.959956 tds_put_data: not null param varint_size = 0
09:04:00.959980 tds_put_data_info putting param_name
09:04:00.960009 tds_put_data_info putting status
09:04:00.960032 tds_put_data: is_null = 0, colsize = 4
09:04:00.960056 tds_put_data: not null param varint_size = 0
09:04:00.960081 Sending packet
0000 03 01 00 89 00 00 01 00-17 00 70 00 72 00 6f 00 |........ ..p.r.o.|
0010 63 00 4f 00 63 00 74 00-43 00 6c 00 69 00 65 00 |c.O.c.t. C.l.i.e.|
0020 6e 00 74 00 53 00 74 00-61 00 74 00 75 00 73 00 |n.t.S.t. a.t.u.s.|
0030 54 00 69 00 63 00 6b 00-00 00 07 40 00 43 00 6c |T.i.c.k. ...@.C.l|
0040 00 69 00 65 00 6e 00 74-00 00 38 05 00 00 00 07 |.i.e.n.t ..8.....|
0050 40 00 53 00 74 00 61 00-74 00 75 00 73 00 00 38 |@.S.t.a. t.u.s..8|
0060 01 00 00 00 0f 40 00 53-00 65 00 71 00 75 00 65 |.....@.S .e.q.u.e|
0070 00 6e 00 63 00 65 00 4e-00 75 00 6d 00 62 00 65 |.n.c.e.N .u.m.b.e|
0080 00 72 00 00 38 00 00 00-00                      |.r..8... .|

09:04:00.960996 in dbsqlok()
09:04:00.982345 Received header
0000 04 01 00 16 00 3c 01 00-                        |.....<..|

09:04:00.982531 Received packet
0000 79 00 00 00 00 fe 00 00-e0 00 00 00 00 00       |y....... ......|

09:04:00.982635 dbsqlok() marker is 79
09:04:00.982686 dbsqlok() found result token
09:04:00.989696 dbresults()
09:04:00.989816 tds_free_all_results()
09:04:00.989859 processing result tokens.  marker is  79(RETURNSTATUS)
09:04:00.989895 tds_process_result_tokens: return status is 0
09:04:00.989924 dbresults() process_result_tokens returned result_type = 
4043 retcode = 1
09:04:00.989951 processing result tokens.  marker is  fe(DONEPROC)
09:04:00.989997 tds_process_end: more_results = 0
                was_cancelled = 0
                error = 0
                done_count_valid = 0
09:04:00.990030 tds_process_end() state set to TDS_IDLE
09:04:00.990057 dbresults() process_result_tokens returned result_type = 
4053 retcode = 1
09:04:00.990085 tds_process_result_tokens() state is COMPLETED
09:04:00.990107 dbresults() process_result_tokens returned result_type = 
4052 retcode = 2
09:04:00.990184 dbnumrets() finds 0 columns
09:04:00.990217 tds_process_trailing_tokens()
09:04:00.990253 dbnumrets() finds 0 columns
09:04:00.990279 tds_process_trailing_tokens()