freetds AT lists.ibiblio.org

Subject: FreeTDS Development Group
List archive

Re: [freetds] Invalid Cursor state

From: <jezekj AT centrum.cz>
To: FreeTDS Development Group <freetds AT lists.ibiblio.org>
Subject: Re: [freetds] Invalid Cursor state
Date: Fri, 29 Jan 2016 19:31:28 +0100
Hello,

I have dumped my session with logging(default level) enabled. File is
attached.

JJ

______________________________________________________________
Od: Frediano Ziglio <freddy77 AT gmail.com>
Komu: FreeTDS Development Group <freetds AT lists.ibiblio.org>
Datum: 29.01.2016 14:52
Předmět: Re: [freetds] Invalid Cursor state
2016-01-28 19:51 GMT+00:00 <jezekj AT centrum.cz>:
Hello,

first of all I would like to thank you for creating and maintaining FreeTDS.
In our application we do have procedure to trace progress of huge procedures
execution to easily find out where the error is. This procedure contains
raiserror function. When in another procedure I call select after trace
procedure is called out I got invalid cursor state. i.e.:

create procedure sp_trace as
raiserror('test', 0, 1)
go

create procedure sp_test_me as
select getdate()
execute sp_trace
select getdate()
go

Then we call out from perl:
my $dbh=APPDBI->masterConnect();
my $statement='execute sp_test_me';
my $sth=$dbh->prepare($statement);
my $sth->execute;
while (my $d=$sth->fetch;){ #get 1st result set (first getdate)
print Dumper $d;
}
while (my $d=$sth->fetch;){ #get 2nd result set (second getdate)
print Dumper $d;
}

Can you confirm this is a bug which can/will be fixed?
We have tried other driver in our setup (easysoft) and it is not case.
Please let me know wah other details you need from me to support you in
investigation.

Best Regards,
JJ
Hi,
it's indeed quite strange. Have a look at
http://www.freetds.org/userguide/askingforhelp.htm
<http://www.freetds.org/userguide/askingforhelp.htm> and
http://www.freetds.org/userguide/logging.htm
<http://www.freetds.org/userguide/logging.htm>. With the dump we can
understand a lot like which library is Perl using and how. As your
problem is not logging in you could strip the login part if you feel
more comfortable for security reasons.

Frediano
_______________________________________________
FreeTDS mailing list
FreeTDS AT lists.ibiblio.org
http://lists.ibiblio.org/mailman/listinfo/freetds
<http://lists.ibiblio.org/mailman/listinfo/freetds>
log.c:196:Starting log file for FreeTDS 0.91.dev.20110409RC1
	on 2016-01-29 09:10:52 with debug flags 0x4fff.
iconv.c:330:tds_iconv_open(0x118f8110, ISO-8859-1)
iconv.c:187:local name for ISO-8859-1 is ISO-8859-1
iconv.c:187:local name for UTF-8 is UTF-8
iconv.c:187:local name for UCS-2LE is UCS-2LE
iconv.c:187:local name for UCS-2BE is UCS-2BE
iconv.c:349:setting up conversions for client charset "ISO-8859-1"
iconv.c:351:preparing iconv for "ISO-8859-1" <-> "UCS-2LE" conversion
iconv.c:391:preparing iconv for "ISO-8859-1" <-> "UCS-2LE" conversion
iconv.c:394:tds_iconv_open: done
net.c:205:Connecting to 10.57.97.43 port 10501 (TDS version 7.1)
net.c:270:tds_open_socket: connect(2) returned "Operation now in progress"
net.c:310:tds_open_socket() succeeded
util.c:156:Changed query state from DEAD to IDLE
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 02 4d 53 53 51-4c 53 65 72 76 65 72 00 |U...MSSQ LServer.|
0030 e6 7c 00 00            -                        |.|..|

net.c:555:Received header
0000 04 01 00 25 00 00 01 00-                        |...%....|

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 0a 00 17 |........ ........|
0020 70 00 00 02 01         -                        |p....|

login.c:1051:detected flag 2
login.c:776:quietly sending TDS 7+ login packet
token.c:328:tds_process_login_tokens()
net.c:555:Received header
0000 04 01 01 d1 00 49 01 00-                        |.....I..|

net.c:609:Received packet
0000 04 01 01 d1 00 49 01 00-e3 19 00 01 05 70 00 6f |.....I.. .....p.o|
0010 00 69 00 6e 00 74 00 06-6d 00 61 00 73 00 74 00 |.i.n.t.. m.a.s.t.|
0020 65 00 72 00 ab 92 00 45-16 00 00 02 00 24 00 43 |e.r....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 70 00 6f 00 69 00 6e |.t.o. .' .p.o.i.n|
0070 00 74 00 27 00 2e 00 1f-4e 00 59 00 4b 00 44 00 |.t.'.... N.Y.K.D.|
0080 43 00 4d 00 30 00 35 00-36 00 37 00 33 00 56 00 |C.M.0.5. 6.7.3.V.|
0090 30 00 35 00 41 00 5c 00-50 00 4f 00 49 00 4e 00 |0.5.A.\. P.O.I.N.|
00a0 54 00 5f 00 4d 00 41 00-49 00 4e 00 31 00 5f 00 |T._.M.A. I.N.1._.|
00b0 44 00 45 00 56 00 00 01-00 e3 08 00 07 05 09 04 |D.E.V... ........|
00c0 c0 00 00 00 e3 17 00 02-0a 75 00 73 00 5f 00 65 |........ .u.s._.e|
00d0 00 6e 00 67 00 6c 00 69-00 73 00 68 00 00 ab 98 |.n.g.l.i .s.h....|
00e0 00 47 16 00 00 01 00 27-00 43 00 68 00 61 00 6e |.G.....' .C.h.a.n|
00f0 00 67 00 65 00 64 00 20-00 6c 00 61 00 6e 00 67 |.g.e.d.  .l.a.n.g|
0100 00 75 00 61 00 67 00 65-00 20 00 73 00 65 00 74 |.u.a.g.e . .s.e.t|
0110 00 74 00 69 00 6e 00 67-00 20 00 74 00 6f 00 20 |.t.i.n.g . .t.o. |
0120 00 75 00 73 00 5f 00 65-00 6e 00 67 00 6c 00 69 |.u.s._.e .n.g.l.i|
0130 00 73 00 68 00 2e 00 1f-4e 00 59 00 4b 00 44 00 |.s.h.... N.Y.K.D.|
0140 43 00 4d 00 30 00 35 00-36 00 37 00 33 00 56 00 |C.M.0.5. 6.7.3.V.|
0150 30 00 35 00 41 00 5c 00-50 00 4f 00 49 00 4e 00 |0.5.A.\. P.O.I.N.|
0160 54 00 5f 00 4d 00 41 00-49 00 4e 00 31 00 5f 00 |T._.M.A. I.N.1._.|
0170 44 00 45 00 56 00 00 01-00 ad 36 00 01 71 00 00 |D.E.V... ..6..q..|
0180 01 16 4d 00 69 00 63 00-72 00 6f 00 73 00 6f 00 |..M.i.c. r.o.s.o.|
0190 66 00 74 00 20 00 53 00-51 00 4c 00 20 00 53 00 |f.t. .S. Q.L. .S.|
01a0 65 00 72 00 76 00 65 00-72 00 00 00 00 00 0a 00 |e.r.v.e. r.......|
01b0 17 70 e3 13 00 04 04 34-00 30 00 39 00 36 00 04 |.p.....4 .0.9.6..|
01c0 34 00 30 00 39 00 36 00-fd 00 00 00 00 00 00 00 |4.0.9.6. ........|
01d0 00                     -                        |.|

token.c:337:looking for login token, got  e3(ENVCHANGE)
token.c:122:tds_process_default_tokens() marker is e3(ENVCHANGE)
token.c:337:looking for login token, got  ab(INFO)
token.c:122:tds_process_default_tokens() marker is ab(INFO)
token.c:2584:tds_process_msg() reading message 5701 from server
token.c:2657:tds_process_msg() calling client msg handler
odbc.c:2270:msgno 5701 20003
token.c:2670:tds_process_msg() returning TDS_SUCCEED
token.c:337:looking for login token, got  e3(ENVCHANGE)
token.c:122:tds_process_default_tokens() marker is e3(ENVCHANGE)
token.c:2415:tds_process_env_chg(): 5 bytes of collation data received
token.c:2416:tds->collation was
0000 00 00 00 00 00         -                        |.....|

iconv.c:988:setting server single-byte charset to "CP1252"
token.c:2426:tds->collation now
0000 09 04 c0 00 00         -                        |.....|

token.c:337:looking for login token, got  e3(ENVCHANGE)
token.c:122:tds_process_default_tokens() marker is e3(ENVCHANGE)
token.c:337:looking for login token, got  ab(INFO)
token.c:122:tds_process_default_tokens() marker is ab(INFO)
token.c:2584:tds_process_msg() reading message 5703 from server
token.c:2657:tds_process_msg() calling client msg handler
odbc.c:2270:msgno 5703 20003
token.c:2670:tds_process_msg() returning TDS_SUCCEED
token.c:337:looking for login token, got  ad(LOGINACK)
token.c:372:server reports TDS version 71.0.0.1
token.c:374:Product name for 0x71000001 is 2000 SP1
token.c:337:looking for login token, got  e3(ENVCHANGE)
token.c:122:tds_process_default_tokens() marker is e3(ENVCHANGE)
token.c:2473:changing block size from 4096 to 4096
token.c:337:looking for login token, got  fd(DONE)
token.c:122:tds_process_default_tokens() marker is fd(DONE)
token.c:2335:tds_process_end: more_results = 0
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2351:tds_process_end() state set to TDS_IDLE
token.c:2366:                rows_affected = 0
token.c:437:tds_process_login_tokens() returning TDS_SUCCEED
mem.c:615:tds_free_all_results()
util.c:156:Changed query state from IDLE to QUERYING
write.c:140:tds_put_string converting 22 bytes of "set textsize 25000000 "
write.c:168:tds_put_string wrote 44 bytes
util.c:156:Changed query state from QUERYING to PENDING
net.c:741:Sending packet
0000 01 01 00 34 00 00 00 00-73 00 65 00 74 00 20 00 |...4.... s.e.t. .|
0010 74 00 65 00 78 00 74 00-73 00 69 00 7a 00 65 00 |t.e.x.t. s.i.z.e.|
0020 20 00 32 00 35 00 30 00-30 00 30 00 30 00 30 00 | .2.5.0. 0.0.0.0.|
0030 30 00 20 00            -                        |0. .|

token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6acae4, 0x7fff5d6acae0, 0x100)
util.c:156:Changed query state from PENDING to READING
net.c:555:Received header
0000 04 01 00 11 00 49 01 00-                        |.....I..|

net.c:609:Received packet
0000 04 01 00 11 00 49 01 00-fd 00 00 be 00 00 00 00 |.....I.. ........|
0010 00                     -                        |.|

token.c:554:processing result tokens.  marker is  fd(DONE)
token.c:2335:tds_process_end: more_results = 0
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2351:tds_process_end() state set to TDS_IDLE
util.c:156:Changed query state from READING to IDLE
token.c:2366:                rows_affected = 0
util.c:104:logic error: cannot change query state from IDLE to PENDING
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6acae4, 0x7fff5d6acae0, 0x100)
token.c:542:tds_process_tokens() state is COMPLETED
odbc.c:4916:SQLGetFunctions: fFunction is 999
odbc.c:6113:_SQLSetConnectAttr(0x118f6440, 102, 0x1, 0)
odbc.c:235:change_autocommit: executing SET IMPLICIT_TRANSACTIONS OFF
mem.c:615:tds_free_all_results()
util.c:156:Changed query state from IDLE to QUERYING
write.c:140:tds_put_string converting 29 bytes of "SET IMPLICIT_TRANSACTIONS OFF"
write.c:168:tds_put_string wrote 58 bytes
util.c:156:Changed query state from QUERYING to PENDING
net.c:741:Sending packet
0000 01 01 00 42 00 00 01 00-53 00 45 00 54 00 20 00 |...B.... S.E.T. .|
0010 49 00 4d 00 50 00 4c 00-49 00 43 00 49 00 54 00 |I.M.P.L. I.C.I.T.|
0020 5f 00 54 00 52 00 41 00-4e 00 53 00 41 00 43 00 |_.T.R.A. N.S.A.C.|
0030 54 00 49 00 4f 00 4e 00-53 00 20 00 4f 00 46 00 |T.I.O.N. S. .O.F.|
0040 46 00                  -                        |F.|

token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0434, 0x7fff5d6b0430, 0x100)
util.c:156:Changed query state from PENDING to READING
net.c:555:Received header
0000 04 01 00 11 00 49 01 00-                        |.....I..|

net.c:609:Received packet
0000 04 01 00 11 00 49 01 00-fd 00 00 ba 00 00 00 00 |.....I.. ........|
0010 00                     -                        |.|

token.c:554:processing result tokens.  marker is  fd(DONE)
token.c:2335:tds_process_end: more_results = 0
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2351:tds_process_end() state set to TDS_IDLE
util.c:156:Changed query state from READING to IDLE
token.c:2366:                rows_affected = 0
util.c:104:logic error: cannot change query state from IDLE to PENDING
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0434, 0x7fff5d6b0430, 0x100)
token.c:542:tds_process_tokens() state is COMPLETED
odbc.c:5831:SQLGetInfo(0x118f6440, 77, 0x118dfe78, 20, 0x7fff5d6b06fe)
odbc.c:5085:_SQLGetInfo(0x118f6440, 77, 0x118dfe78, 20, 0x7fff5d6b06fe)
odbc.c:5831:SQLGetInfo(0x118f6440, 6, 0x118dff0c, 80, 0x7fff5d6b06fe)
odbc.c:5085:_SQLGetInfo(0x118f6440, 6, 0x118dff0c, 80, 0x7fff5d6b06fe)
odbc.c:5831:SQLGetInfo(0x118f6440, 7, 0x118dff5c, 20, 0x7fff5d6b06fe)
odbc.c:5085:_SQLGetInfo(0x118f6440, 7, 0x118dff5c, 20, 0x7fff5d6b06fe)
error.c:517:SQLGetDiagRec(2, 0x118f6440, 1, 0x7fff5d6b04f0, 0x7fff5d6b050c, 0x7fff5d6b02f0, 512, 0x7fff5d6b0508)
odbc.c:5831:SQLGetInfo(0x118f6440, 17, 0x118dff70, 80, 0x7fff5d6b06fe)
odbc.c:5085:_SQLGetInfo(0x118f6440, 17, 0x118dff70, 80, 0x7fff5d6b06fe)
odbc.c:5831:SQLGetInfo(0x118f6440, 18, 0x118dffc0, 80, 0x7fff5d6b06fe)
odbc.c:5085:_SQLGetInfo(0x118f6440, 18, 0x118dffc0, 80, 0x7fff5d6b06fe)
odbc.c:5831:SQLGetInfo(0x118f6440, 30, 0x118dfe8c, 2, 0x7fff5d6b06fe)
odbc.c:5085:_SQLGetInfo(0x118f6440, 30, 0x118dfe8c, 2, 0x7fff5d6b06fe)
odbc.c:6113:_SQLSetConnectAttr(0x118f6440, 102, 0x1, -5)
odbc.c:235:change_autocommit: executing SET IMPLICIT_TRANSACTIONS OFF
mem.c:615:tds_free_all_results()
util.c:156:Changed query state from IDLE to QUERYING
write.c:140:tds_put_string converting 29 bytes of "SET IMPLICIT_TRANSACTIONS OFF"
write.c:168:tds_put_string wrote 58 bytes
util.c:156:Changed query state from QUERYING to PENDING
net.c:741:Sending packet
0000 01 01 00 42 00 00 01 00-53 00 45 00 54 00 20 00 |...B.... S.E.T. .|
0010 49 00 4d 00 50 00 4c 00-49 00 43 00 49 00 54 00 |I.M.P.L. I.C.I.T.|
0020 5f 00 54 00 52 00 41 00-4e 00 53 00 41 00 43 00 |_.T.R.A. N.S.A.C.|
0030 54 00 49 00 4f 00 4e 00-53 00 20 00 4f 00 46 00 |T.I.O.N. S. .O.F.|
0040 46 00                  -                        |F.|

token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0584, 0x7fff5d6b0580, 0x100)
util.c:156:Changed query state from PENDING to READING
net.c:555:Received header
0000 04 01 00 11 00 49 01 00-                        |.....I..|

net.c:609:Received packet
0000 04 01 00 11 00 49 01 00-fd 00 00 ba 00 00 00 00 |.....I.. ........|
0010 00                     -                        |.|

token.c:554:processing result tokens.  marker is  fd(DONE)
token.c:2335:tds_process_end: more_results = 0
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2351:tds_process_end() state set to TDS_IDLE
util.c:156:Changed query state from READING to IDLE
token.c:2366:                rows_affected = 0
util.c:104:logic error: cannot change query state from IDLE to PENDING
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0584, 0x7fff5d6b0580, 0x100)
token.c:542:tds_process_tokens() state is COMPLETED
odbc.c:5831:SQLGetInfo(0x118f6440, 17, 0x1192b4d0, 255, 0x7fff5d6b0b26)
odbc.c:5085:_SQLGetInfo(0x118f6440, 17, 0x1192b4d0, 255, 0x7fff5d6b0b26)
odbc.c:5831:SQLGetInfo(0x118f6440, 17, 0x1192b4d0, 255, 0x7fff5d6b0b26)
odbc.c:5085:_SQLGetInfo(0x118f6440, 17, 0x1192b4d0, 255, 0x7fff5d6b0b26)
odbc.c:1716:SQLAllocStmt(0x118f6440, 0x1192c590)
odbc.c:1615:_SQLAllocStmt(0x118f6440, 0x1192c590)
odbc.c:4388:SQLGetStmtAttr(0x1192c800, 10010, 0x7fff5d6b0ab8, 8, (nil))
odbc.c:4388:SQLGetStmtAttr(0x1192c800, 10011, 0x7fff5d6b0ab8, 8, (nil))
odbc.c:4388:SQLGetStmtAttr(0x1192c800, 10012, 0x7fff5d6b0ab8, 8, (nil))
odbc.c:4388:SQLGetStmtAttr(0x1192c800, 10013, 0x7fff5d6b0ab8, 8, (nil))
odbc.c:4445:SQLPrepare(0x1192c800, exec point..JJ_testproc, -3)
odbc.c:3496:SQLExecute(0x1192c800)
prepare_query.c:203:parsing 0 parameters
odbc.c:3211:_SQLExecute(0x1192c800)
odbc.c:3216:_SQLExecute() starting with state 0
mem.c:615:tds_free_all_results()
util.c:156:Changed query state from IDLE to QUERYING
util.c:156:Changed query state from QUERYING to PENDING
net.c:741:Sending packet
0000 03 01 00 61 00 00 01 00-ff ff 0d 00 00 00 00 01 |...a.... ........|
0010 26 04 00 00 00 63 00 00-00 00 09 04 c0 00 00 ff |&....c.. ........|
0020 ff ff ff 00 00 63 2e 00-00 00 09 04 c0 00 00 2e |.....c.. ........|
0030 00 00 00 65 00 78 00 65-00 63 00 20 00 70 00 6f |...e.x.e .c. .p.o|
0040 00 69 00 6e 00 74 00 2e-00 2e 00 4a 00 4a 00 5f |.i.n.t.. ...J.J._|
0050 00 74 00 65 00 73 00 74-00 70 00 72 00 6f 00 63 |.t.e.s.t .p.r.o.c|
0060 00                     -                        |.|

token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0a44, 0x7fff5d6b0a40, 0x6914)
util.c:156:Changed query state from PENDING to READING
net.c:555:Received header
0000 04 01 00 f6 00 49 01 00-                        |.....I..|

net.c:609:Received packet
0000 04 01 00 f6 00 49 01 00-81 01 00 00 00 20 00 3d |.....I.. ..... .=|
0010 00 d1 9c a5 00 00 1d 4c-97 00 ff 11 00 c1 00 01 |.......L ........|
0020 00 00 00 ab 6a 00 50 c3-00 00 01 00 04 00 74 00 |....j.P. ......t.|
0030 65 00 73 00 74 00 1f 4e-00 59 00 4b 00 44 00 43 |e.s.t..N .Y.K.D.C|
0040 00 4d 00 30 00 35 00 36-00 37 00 33 00 56 00 30 |.M.0.5.6 .7.3.V.0|
0050 00 35 00 41 00 5c 00 50-00 4f 00 49 00 4e 00 54 |.5.A.\.P .O.I.N.T|
0060 00 5f 00 4d 00 41 00 49-00 4e 00 31 00 5f 00 44 |._.M.A.I .N.1._.D|
0070 00 45 00 56 00 0c 4a 00-4a 00 5f 00 64 00 75 00 |.E.V..J. J._.d.u.|
0080 6d 00 6d 00 79 00 70 00-72 00 6f 00 63 00 05 00 |m.m.y.p. r.o.c...|
0090 ff 01 00 f6 00 00 00 00-00 ff 11 00 c1 00 01 00 |........ ........|
00a0 00 00 ff 01 00 e0 00 00-00 00 00 81 01 00 00 00 |........ ........|
00b0 20 00 3d 00 d1 9c a5 00-00 1d 4c 97 00 ff 11 00 | .=..... ..L.....|
00c0 c1 00 01 00 00 00 ff 11-00 c1 00 01 00 00 00 ff |........ ........|
00d0 01 00 e0 00 00 00 00 00-79 00 00 00 00 ac 0d 00 |........ y.......|
00e0 00 01 00 00 00 00 26 04-04 01 00 00 00 fe 00 00 |......&. ........|
00f0 e0 00 00 00 00 00      -                        |......|

token.c:554:processing result tokens.  marker is  81(TDS7_RESULT)
token.c:1511:processing TDS7 result metadata.
mem.c:615:tds_free_all_results()
token.c:1536:set current_results (1 column) to tds->res_info
token.c:1543:setting up 1 columns
token.c:1482:tds7_get_data_info: 
	colname =  (0 bytes)
	type = 61 (datetime)
	server's type = 61 (datetime)
	column_varint_size = 0
	column_size = 8 (8 on server)
token.c:1552: name                 size/wsize      type/wtype      utype  
token.c:1553: -------------------- --------------- --------------- -------
token.c:1563:                            8/8            61/61            0
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4049, TDS_DONE_COUNT=0, TDS_DONE_ERROR=0
odbc.c:3605:odbc_process_tokens: returning result_type 4049
odbc.c:3374:_SQLExecute: odbc_process_tokens returned result_type 4049
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0a44, 0x7fff5d6b0a40, 0x6914)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  d1(ROW)
token.c:665:tds_process_tokens::SET_RETURN stopping on current token
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4040, TDS_DONE_COUNT=0, TDS_DONE_ERROR=0
odbc.c:3605:odbc_process_tokens: returning result_type 4040
odbc.c:3374:_SQLExecute: odbc_process_tokens returned result_type 4040
odbc.c:3519:SQLExecute returns SQL_SUCCESS
odbc.c:4506:_SQLRowCount(0x1192c800, 0x1192bcb8)
odbc.c:4525:SQLRowCount returns 0, row count -1
odbc.c:4420:SQLNumResultCols(0x1192c800, 0x7fff5d6b0c50)
odbc.c:4420:SQLNumResultCols(0x1192c800, 0x7fff5d6b0bf6)
odbc.c:1919:SQLDescribeCol(0x1192c800, 1, 0x119355f0, 128, 0x11935590, 0x119355a2, 0x11935598, 0x119355a0, 0x119355a4)
odbc.c:1984:_SQLColAttribute(0x1192c800, 1, 6, (nil), 0, (nil), 0x119355b0)
odbc.c:2026:SQLColAttribute: fDescType is 6
odbc.c:1984:_SQLColAttribute(0x1192c800, 1, 1003, (nil), 0, (nil), 0x119355a8)
odbc.c:2026:SQLColAttribute: fDescType is 1003
odbc.c:1731:SQLBindCol(0x1192c800, 1, 1, 0x1192e570, 24, 0x119355c8)
odbc.c:3935:SQLFetch(0x1192c800)
odbc.c:3671:_SQLFetch(0x1192c800, 1, 0)
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b09a4, 0x7fff5d6b09a0, 0x6508)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  d1(ROW)
token.c:2300:tds_process_row(): reading column 0 
token.c:2045:tds_get_data: type 61, varint size 0
token.c:2106:tds_get_data(): wire column size is 8 
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4040, TDS_DONE_COUNT=0, TDS_DONE_ERROR=0
odbc.c:3605:odbc_process_tokens: returning result_type 4040
convert_tds2sql.c:164:odbc_tds2sql: src is 61 dest = 1
convert_tds2sql.c:290:odbc_tds2sql: outputting character data destlen = 24 
odbc.c:3935:SQLFetch(0x1192c800)
odbc.c:3671:_SQLFetch(0x1192c800, 1, 0)
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b09a4, 0x7fff5d6b09a0, 0x6508)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 1
token.c:2366:                rows_affected = 1
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4054, TDS_DONE_COUNT=10, TDS_DONE_ERROR=0
odbc.c:3599:odbc_process_tokens: processed TDS_DONEINPROC_RESULT
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b09a4, 0x7fff5d6b09a0, 0x26508)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  ab(INFO)
token.c:793:tds_process_tokens::SET_RETURN stopping on current token
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4044, TDS_DONE_COUNT=10, TDS_DONE_ERROR=0
odbc.c:3605:odbc_process_tokens: returning result_type 4044
odbc.c:3803:SQLFetch: NO_DATA_FOUND
odbc.c:794:SQLMoreResults(0x1192c800)
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0a64, 0x7fff5d6b0a60, 0x15914)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  ab(INFO)
token.c:122:tds_process_default_tokens() marker is ab(INFO)
token.c:2584:tds_process_msg() reading message 50000 from server
token.c:2657:tds_process_msg() calling client msg handler
odbc.c:2270:msgno 50000 20003
token.c:2670:tds_process_msg() returning TDS_SUCCEED
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4044, TDS_DONE_COUNT=10, TDS_DONE_ERROR=0
odbc.c:3605:odbc_process_tokens: returning result_type 4044
odbc.c:821:SQLMoreResults: result_type=4044, row_count=-1, lastrc=1
mem.c:615:tds_free_all_results()
token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b0a64, 0x7fff5d6b0a60, 0x15914)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2366:                rows_affected = 0
util.c:156:Changed query state from READING to PENDING
odbc.c:3534:odbc_process_tokens: tds_process_tokens returned 1
odbc.c:3535:    result_type=4054, TDS_DONE_COUNT=0, TDS_DONE_ERROR=0
odbc.c:3599:odbc_process_tokens: processed TDS_DONEINPROC_RESULT
odbc.c:821:SQLMoreResults: result_type=4054, row_count=-1, lastrc=1
error.c:517:SQLGetDiagRec(3, 0x1192c800, 1, 0x7fff5d6b0a70, 0x7fff5d6b0a8c, 0x7fff5d6b0870, 512, 0x7fff5d6b0a88)
error.c:566:SQLGetDiagRec: "[FreeTDS][SQL Server]test"
error.c:517:SQLGetDiagRec(3, 0x1192c800, 2, 0x7fff5d6b0a70, 0x7fff5d6b0a8c, 0x7fff5d6b0870, 512, 0x7fff5d6b0a88)
odbc.c:4154:SQLFreeStmt(0x1192c800, 2)
odbc.c:4075:_SQLFreeStmt(0x1192c800, 2, 0)
odbc.c:4420:SQLNumResultCols(0x1192c800, 0x7fff5d6b0bd6)
odbc.c:3935:SQLFetch(0x1192c800)
odbc.c:3671:_SQLFetch(0x1192c800, 1, 0)
error.c:412:odbc_errs_add: "Invalid cursor state"
error.c:517:SQLGetDiagRec(3, 0x1192c800, 1, 0x7fff5d6b0a70, 0x7fff5d6b0a8c, 0x7fff5d6b0870, 512, 0x7fff5d6b0a88)
error.c:566:SQLGetDiagRec: "[FreeTDS][SQL Server]Invalid cursor state"
error.c:517:SQLGetDiagRec(3, 0x1192c800, 2, 0x7fff5d6b0a70, 0x7fff5d6b0a8c, 0x7fff5d6b0870, 512, 0x7fff5d6b0a88)
odbc.c:4154:SQLFreeStmt(0x1192c800, 0)
odbc.c:4075:_SQLFreeStmt(0x1192c800, 0, 0)
query.c:2157:tds_send_cancel: not in_cancel and not idle
query.c:2166:tds_send_cancel: sending cancel packet
net.c:741:Sending packet
0000 06 01 00 08 00 00 01 00-                        |........|

token.c:539:tds_process_tokens(0x118f8110, 0x7fff5d6b08f4, (nil), 0x0)
util.c:156:Changed query state from PENDING to READING
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 1
token.c:2366:                rows_affected = 1
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2366:                rows_affected = 0
token.c:554:processing result tokens.  marker is  81(TDS7_RESULT)
token.c:1511:processing TDS7 result metadata.
mem.c:615:tds_free_all_results()
token.c:1536:set current_results (1 column) to tds->res_info
token.c:1543:setting up 1 columns
token.c:1482:tds7_get_data_info: 
	colname =  (0 bytes)
	type = 61 (datetime)
	server's type = 61 (datetime)
	column_varint_size = 0
	column_size = 8 (8 on server)
token.c:1552: name                 size/wsize      type/wtype      utype  
token.c:1553: -------------------- --------------- --------------- -------
token.c:1563:                            8/8            61/61            0
token.c:554:processing result tokens.  marker is  d1(ROW)
token.c:2300:tds_process_row(): reading column 0 
token.c:2045:tds_get_data: type 61, varint size 0
token.c:2106:tds_get_data(): wire column size is 8 
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 1
token.c:2366:                rows_affected = 1
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 1
token.c:2366:                rows_affected = 1
token.c:554:processing result tokens.  marker is  ff(DONEINPROC)
token.c:2335:tds_process_end: more_results = 1
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2366:                rows_affected = 0
token.c:554:processing result tokens.  marker is  79(RETURNSTATUS)
token.c:554:processing result tokens.  marker is  ac(PARAM)
token.c:611:processing parameters for sp 13
token.c:613:calling tds_process_param_result
token.c:1243:tds_process_param_result(0x118f8110, 0x7fff5d6b0898)
token.c:1587:tds_get_data_info(0x118f8110, 0x11948d70, 1) [for parameter]
token.c:1650:processing result. type = 38(integer-null), varint_size 1
token.c:1655:processing result. column_size 4
token.c:2045:tds_get_data: type 38, varint size 1
token.c:2106:tds_get_data(): wire column size is 4 
log.c:493:type integer-null has value 1
token.c:617:1 hidden return parameters
token.c:554:processing result tokens.  marker is  fe(DONEPROC)
token.c:2335:tds_process_end: more_results = 0
		was_cancelled = 0
		error = 0
		done_count_valid = 0
token.c:2366:                rows_affected = 0
net.c:555:Received header
0000 04 01 00 11 00 49 01 00-                        |.....I..|

net.c:609:Received packet
0000 04 01 00 11 00 49 01 00-fd 20 00 fd 00 00 00 00 |.....I.. . ......|
0010 00                     -                        |.|

token.c:554:processing result tokens.  marker is  fd(DONE)
token.c:2335:tds_process_end: more_results = 0
		was_cancelled = 1
		error = 0
		done_count_valid = 0
token.c:2351:tds_process_end() state set to TDS_IDLE
util.c:156:Changed query state from READING to IDLE
token.c:2366:                rows_affected = 0
odbc.c:3980:SQLFreeHandle(3, 0x1192c800)
odbc.c:4075:_SQLFreeStmt(0x1192c800, 1, 0)
odbc.c:4718:_SQLGetConnectAttr(0x118f6440, 102, 0x7fff5d6b093c, -5, (nil))
odbc.c:2240:SQLDisconnect(0x118f6440)
mem.c:615:tds_free_all_results()
util.c:156:Changed query state from IDLE to DEAD
odbc.c:3980:SQLFreeHandle(2, 0x118f6440)
odbc.c:4006:_SQLFreeConnect(0x118f6440)
odbc.c:3980:SQLFreeHandle(1, 0x118f40a0)
odbc.c:4050:_SQLFreeEnv(0x118f40a0)
[freetds] Invalid Cursor state, jezekj, 01/28/2016
- Re: [freetds] Invalid Cursor state, Frediano Ziglio, 01/29/2016
  - Re: [freetds] Invalid Cursor state, jezekj, 01/29/2016